builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-039 starttime: 1502919047.81 results: success (0) revision: 833f84d0d5c729054a3aa8b3f34735f56fe6436b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-08-16 14:30:47.806373) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-08-16 14:30:47.806813) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-16 14:30:47.807099) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-16 14:30:48.219984) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-08-16 14:30:48.220374) ========= 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=5bd038846534b2fdfa68eeae000001e2-1502919045.249407-1615021432 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-08-16 14:30:48-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 95.2M=0s 2017-08-16 14:30:48 (95.2 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.188534 ========= master_lag: 1.49 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-08-16 14:30:49.894459) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-16 14:30:49.894810) ========= 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=5bd038846534b2fdfa68eeae000001e2-1502919045.249407-1615021432 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020368 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-16 14:30:49.934866) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-08-16 14:30:49.935217) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 833f84d0d5c729054a3aa8b3f34735f56fe6436b --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 833f84d0d5c729054a3aa8b3f34735f56fe6436b --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=5bd038846534b2fdfa68eeae000001e2-1502919045.249407-1615021432 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-08-16 14:30:49,977 truncating revision to first 12 chars 2017-08-16 14:30:49,977 Setting DEBUG logging. 2017-08-16 14:30:49,977 attempt 1/10 2017-08-16 14:30:49,977 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/833f84d0d5c7?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-08-16 14:30:51,219 unpacking tar archive at: mozilla-central-833f84d0d5c7/testing/mozharness/ program finished with exit code 0 elapsedTime=1.579706 ========= master_lag: 0.12 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-08-16 14:30:51.638925) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-16 14:30:51.639272) ========= script_repo_revision: 833f84d0d5c729054a3aa8b3f34735f56fe6436b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-16 14:30:51.639656) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-16 14:30:51.639917) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-16 14:30:51.660828) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 3 secs) (at 2017-08-16 14:30:51.663263) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 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-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] 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=5bd038846534b2fdfa68eeae000001e2-1502919045.249407-1615021432 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 14:30:51 INFO - MultiFileLogger online at 20170816 14:30:51 in /builds/slave/test 14:30:51 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 14:30:51 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 14:30:51 INFO - {'append_to_log': False, 14:30:51 INFO - 'base_work_dir': '/builds/slave/test', 14:30:51 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 14:30:51 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 14:30:51 INFO - 'branch': 'Firefox-Non-PGO', 14:30:51 INFO - 'buildbot_json_path': 'buildprops.json', 14:30:51 INFO - 'code_coverage': False, 14:30:51 INFO - 'config_files': ('talos/linux_config.py',), 14:30:51 INFO - 'default_actions': ('clobber', 14:30:51 INFO - 'read-buildbot-config', 14:30:51 INFO - 'download-and-extract', 14:30:51 INFO - 'populate-webroot', 14:30:51 INFO - 'create-virtualenv', 14:30:51 INFO - 'install', 14:30:51 INFO - 'setup-mitmproxy', 14:30:51 INFO - 'run-tests'), 14:30:51 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:30:51 INFO - 'disable_ccov_upload': False, 14:30:51 INFO - 'download_minidump_stackwalk': True, 14:30:51 INFO - 'download_symbols': 'ondemand', 14:30:51 INFO - 'enable_stylo': False, 14:30:51 INFO - 'enable_webrender': False, 14:30:51 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 14:30:51 INFO - 'tooltool.py': '/tools/tooltool.py', 14:30:51 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 14:30:51 INFO - '/tools/misc-python/virtualenv.py')}, 14:30:51 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:30:51 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:30:51 INFO - 'gecko_profile': False, 14:30:51 INFO - 'gecko_profile_interval': 0, 14:30:51 INFO - 'installer_path': 'installer.exe', 14:30:51 INFO - 'log_level': 'info', 14:30:51 INFO - 'log_name': 'talos', 14:30:51 INFO - 'log_to_console': True, 14:30:51 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 14:30:51 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 14:30:51 INFO - 'opt_config_files': (), 14:30:51 INFO - 'pip_index': False, 14:30:51 INFO - 'suite': 'dromaeojs-e10s', 14:30:51 INFO - 'system_bits': '32', 14:30:51 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:30:51 INFO - 'title': 'talos-linux64-ix-039', 14:30:51 INFO - 'tooltool_cache': '/builds/tooltool_cache', 14:30:51 INFO - 'use_talos_json': True, 14:30:51 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 14:30:51 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:30:51 INFO - 'work_dir': 'build'} 14:30:51 INFO - [mozharness: 2017-08-16 21:30:51.912731Z] Running clobber step. 14:30:51 INFO - Running pre-action listener: _resource_record_pre_action 14:30:51 INFO - Running main action method: clobber 14:30:51 INFO - rmtree: /builds/slave/test/build 14:30:51 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 14:30:53 INFO - Running post-action listener: _resource_record_post_action 14:30:53 INFO - [mozharness: 2017-08-16 21:30:53.074025Z] Finished clobber step (success) 14:30:53 INFO - [mozharness: 2017-08-16 21:30:53.074127Z] Running read-buildbot-config step. 14:30:53 INFO - Running pre-action listener: _resource_record_pre_action 14:30:53 INFO - Running main action method: read_buildbot_config 14:30:53 INFO - Using buildbot properties: 14:30:53 INFO - { 14:30:53 INFO - "project": "", 14:30:53 INFO - "product": "firefox", 14:30:53 INFO - "who": "ryanvm@gmail.com", 14:30:53 INFO - "installer_path": "public/build/target.tar.bz2", 14:30:53 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 14:30:53 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 14:30:53 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 14:30:53 INFO - "stage_platform": "linux64", 14:30:53 INFO - "basedir": "/builds/slave/test", 14:30:53 INFO - "buildnumber": 773, 14:30:53 INFO - "platform": "ubuntu64_hw", 14:30:53 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 14:30:53 INFO - "slavebuilddir": "test", 14:30:53 INFO - "taskId": "CZMBZnOoTde23xRuQfC6uQ", 14:30:53 INFO - "branch": "mozilla-central", 14:30:53 INFO - "script_repo_revision": "production", 14:30:53 INFO - "revision": "833f84d0d5c729054a3aa8b3f34735f56fe6436b", 14:30:53 INFO - "slavename": "talos-linux64-ix-039", 14:30:53 INFO - "repo_path": "mozilla-central" 14:30:53 INFO - } 14:30:53 INFO - Finding installer, test and symbols from parent task. 14:30:53 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CZMBZnOoTde23xRuQfC6uQ'}, attempt #1 14:30:53 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CZMBZnOoTde23xRuQfC6uQ'}, attempt #1 14:30:53 INFO - Task dependencies: ZbYi7e4SShuLRJRdD3myxg 14:30:53 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ZbYi7e4SShuLRJRdD3myxg'}, attempt #1 14:30:54 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/ZbYi7e4SShuLRJRdD3myxg/artifacts/public/build/target.tar.bz2 14:30:54 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/ZbYi7e4SShuLRJRdD3myxg/artifacts/public/build/target.test_packages.json 14:30:54 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/ZbYi7e4SShuLRJRdD3myxg/artifacts/public/build/target.crashreporter-symbols.zip 14:30:54 INFO - Running post-action listener: _resource_record_post_action 14:30:54 INFO - [mozharness: 2017-08-16 21:30:54.442363Z] Finished read-buildbot-config step (success) 14:30:54 INFO - [mozharness: 2017-08-16 21:30:54.442583Z] Running download-and-extract step. 14:30:54 INFO - Running pre-action listener: _resource_record_pre_action 14:30:54 INFO - Running main action method: download_and_extract 14:30:54 INFO - mkdir: /builds/slave/test/build/tests 14:30:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:30:54 INFO - trying https://queue.taskcluster.net/v1/task/ZbYi7e4SShuLRJRdD3myxg/artifacts/public/build/target.test_packages.json 14:30:54 INFO - Downloading https://queue.taskcluster.net/v1/task/ZbYi7e4SShuLRJRdD3myxg/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 14:30:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ZbYi7e4SShuLRJRdD3myxg/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 14:30:55 INFO - Downloaded 989 bytes. 14:30:55 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 14:30:55 INFO - Using the following test package requirements: 14:30:55 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 14:30:55 INFO - u'common': [u'target.common.tests.zip'], 14:30:55 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 14:30:55 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 14:30:55 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 14:30:55 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 14:30:55 INFO - u'mozbase': [u'target.common.tests.zip'], 14:30:55 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 14:30:55 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 14:30:55 INFO - u'web-platform': [u'target.common.tests.zip', 14:30:55 INFO - u'target.web-platform.tests.tar.gz'], 14:30:55 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 14:30:55 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 14:30:55 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ZbYi7e4SShuLRJRdD3myxg/artifacts/public/build/target.common.tests.zip 14:30:55 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ZbYi7e4SShuLRJRdD3myxg/artifacts/public/build/target.common.tests.zip'}, attempt #1 14:30:55 INFO - Fetch https://queue.taskcluster.net/v1/task/ZbYi7e4SShuLRJRdD3myxg/artifacts/public/build/target.common.tests.zip into memory 14:30:57 INFO - Content-Length response header: 54003921 14:30:57 INFO - Bytes received: 54003921 14:31:07 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 14:31:07 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ZbYi7e4SShuLRJRdD3myxg/artifacts/public/build/target.common.tests.zip 14:31:07 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ZbYi7e4SShuLRJRdD3myxg/artifacts/public/build/target.common.tests.zip'}, attempt #1 14:31:07 INFO - Fetch https://queue.taskcluster.net/v1/task/ZbYi7e4SShuLRJRdD3myxg/artifacts/public/build/target.common.tests.zip into memory 14:31:09 INFO - Content-Length response header: 54003921 14:31:09 INFO - Bytes received: 54003921 14:31:24 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ZbYi7e4SShuLRJRdD3myxg/artifacts/public/build/target.talos.tests.zip 14:31:24 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ZbYi7e4SShuLRJRdD3myxg/artifacts/public/build/target.talos.tests.zip'}, attempt #1 14:31:24 INFO - Fetch https://queue.taskcluster.net/v1/task/ZbYi7e4SShuLRJRdD3myxg/artifacts/public/build/target.talos.tests.zip into memory 14:31:25 INFO - Content-Length response header: 14496050 14:31:25 INFO - Bytes received: 14496050 14:31: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')]} 14:31:25 INFO - trying https://queue.taskcluster.net/v1/task/ZbYi7e4SShuLRJRdD3myxg/artifacts/public/build/target.tar.bz2 14:31:25 INFO - Downloading https://queue.taskcluster.net/v1/task/ZbYi7e4SShuLRJRdD3myxg/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 14:31:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ZbYi7e4SShuLRJRdD3myxg/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 14:31:27 INFO - Downloaded 61710599 bytes. 14:31:27 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ZbYi7e4SShuLRJRdD3myxg/artifacts/public/build/target.tar.bz2 14:31:27 INFO - mkdir: /builds/slave/test/properties 14:31:27 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 14:31:27 INFO - Writing to file /builds/slave/test/properties/build_url 14:31:27 INFO - Contents: 14:31:27 INFO - build_url:https://queue.taskcluster.net/v1/task/ZbYi7e4SShuLRJRdD3myxg/artifacts/public/build/target.tar.bz2 14:31:27 INFO - Running post-action listener: _resource_record_post_action 14:31:27 INFO - Running post-action listener: set_extra_try_arguments 14:31:27 INFO - [mozharness: 2017-08-16 21:31:27.225558Z] Finished download-and-extract step (success) 14:31:27 INFO - [mozharness: 2017-08-16 21:31:27.225656Z] Running populate-webroot step. 14:31:27 INFO - Running pre-action listener: _resource_record_pre_action 14:31:27 INFO - Running main action method: populate_webroot 14:31:27 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 14:31:27 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 14:31:27 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 14:31:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:31:27 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 14:31:27 INFO - 'tests': ['damp', 'tps']}, 14:31:27 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 14:31:27 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 14:31:27 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 14:31:27 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 14:31:27 INFO - 'other-e10s': {'tests': ['a11yr', 14:31:27 INFO - 'ts_paint', 14:31:27 INFO - 'tpaint', 14:31:27 INFO - 'sessionrestore', 14:31:27 INFO - 'sessionrestore_many_windows', 14:31:27 INFO - 'sessionrestore_no_auto_restore', 14:31:27 INFO - 'tabpaint']}, 14:31:27 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 14:31:27 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 14:31:27 INFO - 'svgr-e10s': {'tests': ['tsvgx', 14:31:27 INFO - 'tsvgr_opacity', 14:31:27 INFO - 'tart', 14:31:27 INFO - 'tscrollx', 14:31:27 INFO - 'tsvg_static']}, 14:31:27 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 14:31:27 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 14:31:27 INFO - 'talos_options': ['--mitmproxy', 14:31:27 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 14:31:27 INFO - '--firstNonBlankPaint'], 14:31:27 INFO - 'tests': ['tp6_google', 14:31:27 INFO - 'tp6_youtube', 14:31:27 INFO - 'tp6_amazon', 14:31:27 INFO - 'tp6_facebook']}, 14:31:27 INFO - 'tp6-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 14:31:27 INFO - 'talos_options': ['--stylo', 14:31:27 INFO - '--mitmproxy', 14:31:27 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 14:31:27 INFO - '--firstNonBlankPaint'], 14:31:27 INFO - 'tests': ['tp6_google', 14:31:27 INFO - 'tp6_youtube', 14:31:27 INFO - 'tp6_amazon', 14:31:27 INFO - 'tp6_facebook']}, 14:31:27 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 14:31:27 INFO - 'talos_options': ['--stylo', 14:31:27 INFO - '--stylo-threads=1', 14:31:27 INFO - '--mitmproxy', 14:31:27 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 14:31:27 INFO - '--firstNonBlankPaint'], 14:31:27 INFO - 'tests': ['tp6_google', 14:31:27 INFO - 'tp6_youtube', 14:31:27 INFO - 'tp6_amazon', 14:31:27 INFO - 'tp6_facebook']}, 14:31:27 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 14:31:27 INFO - 'talos_options': ['--xperf_path', 14:31:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:31:27 INFO - 'tests': ['tp5n']}}} 14:31:27 INFO - Running post-action listener: _resource_record_post_action 14:31:27 INFO - [mozharness: 2017-08-16 21:31:27.234150Z] Finished populate-webroot step (success) 14:31:27 INFO - [mozharness: 2017-08-16 21:31:27.234240Z] Running create-virtualenv step. 14:31:27 INFO - Running pre-action listener: _resource_record_pre_action 14:31:27 INFO - Running main action method: create_virtualenv 14:31:27 INFO - Creating virtualenv /builds/slave/test/build/venv 14:31:27 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 14:31:27 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 14:31:27 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 14:31:27 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 14:31:27 INFO - Using real prefix '/usr' 14:31:27 INFO - New python executable in /builds/slave/test/build/venv/bin/python 14:31:28 INFO - Installing distribute.............................................................................................................................................................................................done. 14:31:29 INFO - Installing pip.................done. 14:31:29 INFO - Return code: 0 14:31:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 14:31:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 14:31:30 INFO - Reading from file tmpfile_stdout 14:31:30 INFO - Output received: 14:31:30 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) 14:31:30 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 14:31:30 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')]} 14:31:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:31:30 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')]} 14:31:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:31:30 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 0xaf71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xbfda80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xda0a10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd9dbb0>, '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 0xd9eab0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd9ef40>, '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': '5bd038846534b2fdfa68eeae000001e2-1502919045.249407-1615021432', '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 14:31:30 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 14:31:30 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 14:31:30 INFO - Using env: {'DISPLAY': ':0', 14:31:30 INFO - 'HOME': '/home/cltbld', 14:31:30 INFO - 'LANG': 'en_US.UTF-8', 14:31:30 INFO - 'LANGUAGE': 'en_US:en', 14:31:30 INFO - 'LOGNAME': 'cltbld', 14:31:30 INFO - 'MAIL': '/var/mail/cltbld', 14:31:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:31:30 INFO - 'MOZ_NO_REMOTE': '1', 14:31:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:31:30 INFO - 'NO_EM_RESTART': '1', 14:31:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:31:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:31:30 INFO - 'PWD': '/builds/slave/test', 14:31:30 INFO - 'SHELL': '/bin/bash', 14:31:30 INFO - 'SHLVL': '1', 14:31:30 INFO - 'TERM': 'linux', 14:31:30 INFO - 'TMOUT': '86400', 14:31:30 INFO - 'USER': 'cltbld', 14:31:30 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1502919045.249407-1615021432', 14:31:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:31:30 INFO - '_': '/tools/buildbot/bin/python'} 14:31:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:31:30 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 14:31:30 INFO - Cleaning up... 14:31:30 INFO - Return code: 0 14:31:30 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 14:31:30 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')]} 14:31:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:31:30 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')]} 14:31:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:31:30 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 0xaf71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xbfda80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xda0a10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd9dbb0>, '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 0xd9eab0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd9ef40>, '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': '5bd038846534b2fdfa68eeae000001e2-1502919045.249407-1615021432', '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 14:31:30 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 14:31:30 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 14:31:30 INFO - Using env: {'DISPLAY': ':0', 14:31:30 INFO - 'HOME': '/home/cltbld', 14:31:30 INFO - 'LANG': 'en_US.UTF-8', 14:31:30 INFO - 'LANGUAGE': 'en_US:en', 14:31:30 INFO - 'LOGNAME': 'cltbld', 14:31:30 INFO - 'MAIL': '/var/mail/cltbld', 14:31:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:31:30 INFO - 'MOZ_NO_REMOTE': '1', 14:31:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:31:30 INFO - 'NO_EM_RESTART': '1', 14:31:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:31:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:31:30 INFO - 'PWD': '/builds/slave/test', 14:31:30 INFO - 'SHELL': '/bin/bash', 14:31:30 INFO - 'SHLVL': '1', 14:31:30 INFO - 'TERM': 'linux', 14:31:30 INFO - 'TMOUT': '86400', 14:31:30 INFO - 'USER': 'cltbld', 14:31:30 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1502919045.249407-1615021432', 14:31:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:31:30 INFO - '_': '/tools/buildbot/bin/python'} 14:31:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:31:30 INFO - Downloading/unpacking psutil>=3.1.1 14:31:30 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 14:31:30 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 14:31:30 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 14:31:30 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 14:31:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 14:31:31 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:31:31 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:31:32 INFO - Installing collected packages: psutil 14:31:32 INFO - Running setup.py install for psutil 14:31:32 INFO - building 'psutil._psutil_linux' extension 14:31:32 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 14:31:33 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 14:31:33 INFO - building 'psutil._psutil_posix' extension 14:31:33 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 14:31:33 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 14:31:33 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:31:33 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:31:33 INFO - Successfully installed psutil 14:31:33 INFO - Cleaning up... 14:31:33 INFO - Return code: 0 14:31:33 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 14:31:33 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')]} 14:31:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:31:33 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')]} 14:31:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:31:33 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 0xaf71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xbfda80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xda0a10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd9dbb0>, '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 0xd9eab0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd9ef40>, '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': '5bd038846534b2fdfa68eeae000001e2-1502919045.249407-1615021432', '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 14:31:33 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 14:31:33 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 14:31:33 INFO - Using env: {'DISPLAY': ':0', 14:31:33 INFO - 'HOME': '/home/cltbld', 14:31:33 INFO - 'LANG': 'en_US.UTF-8', 14:31:33 INFO - 'LANGUAGE': 'en_US:en', 14:31:33 INFO - 'LOGNAME': 'cltbld', 14:31:33 INFO - 'MAIL': '/var/mail/cltbld', 14:31:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:31:33 INFO - 'MOZ_NO_REMOTE': '1', 14:31:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:31:33 INFO - 'NO_EM_RESTART': '1', 14:31:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:31:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:31:33 INFO - 'PWD': '/builds/slave/test', 14:31:33 INFO - 'SHELL': '/bin/bash', 14:31:33 INFO - 'SHLVL': '1', 14:31:33 INFO - 'TERM': 'linux', 14:31:33 INFO - 'TMOUT': '86400', 14:31:33 INFO - 'USER': 'cltbld', 14:31:33 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1502919045.249407-1615021432', 14:31:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:31:33 INFO - '_': '/tools/buildbot/bin/python'} 14:31:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:31:33 INFO - Downloading/unpacking mozsystemmonitor==0.3 14:31:33 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 14:31:33 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 14:31:33 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 14:31:33 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 14:31:34 INFO - Downloading mozsystemmonitor-0.3.tar.gz 14:31:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:31:34 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 14:31:34 INFO - Installing collected packages: mozsystemmonitor 14:31:34 INFO - Running setup.py install for mozsystemmonitor 14:31:34 INFO - Successfully installed mozsystemmonitor 14:31:34 INFO - Cleaning up... 14:31:34 INFO - Return code: 0 14:31:34 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 14:31:34 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')]} 14:31:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:31:34 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')]} 14:31:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:31:34 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 0xaf71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xbfda80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xda0a10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd9dbb0>, '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 0xd9eab0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd9ef40>, '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': '5bd038846534b2fdfa68eeae000001e2-1502919045.249407-1615021432', '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 14:31:34 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 14:31:34 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 14:31:34 INFO - Using env: {'DISPLAY': ':0', 14:31:34 INFO - 'HOME': '/home/cltbld', 14:31:34 INFO - 'LANG': 'en_US.UTF-8', 14:31:34 INFO - 'LANGUAGE': 'en_US:en', 14:31:34 INFO - 'LOGNAME': 'cltbld', 14:31:34 INFO - 'MAIL': '/var/mail/cltbld', 14:31:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:31:34 INFO - 'MOZ_NO_REMOTE': '1', 14:31:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:31:34 INFO - 'NO_EM_RESTART': '1', 14:31:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:31:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:31:34 INFO - 'PWD': '/builds/slave/test', 14:31:34 INFO - 'SHELL': '/bin/bash', 14:31:34 INFO - 'SHLVL': '1', 14:31:34 INFO - 'TERM': 'linux', 14:31:34 INFO - 'TMOUT': '86400', 14:31:34 INFO - 'USER': 'cltbld', 14:31:34 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1502919045.249407-1615021432', 14:31:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:31:34 INFO - '_': '/tools/buildbot/bin/python'} 14:31:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:31:35 INFO - Downloading/unpacking jsonschema==2.5.1 14:31:35 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 14:31:35 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 14:31:35 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 14:31:35 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 14:31:36 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:31:36 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 14:31:36 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 14:31:36 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 14:31:36 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 14:31:36 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 14:31:36 INFO - Downloading functools32-3.2.3-2.tar.gz 14:31:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 14:31:36 INFO - warning: no files found matching '*.txt' 14:31:36 INFO - no previously-included directories found matching 'build' 14:31:36 INFO - no previously-included directories found matching 'dist' 14:31:36 INFO - no previously-included directories found matching '.git*' 14:31:36 INFO - Installing collected packages: jsonschema, functools32 14:31:36 INFO - Running setup.py install for functools32 14:31:36 INFO - warning: no files found matching '*.txt' 14:31:36 INFO - no previously-included directories found matching 'build' 14:31:36 INFO - no previously-included directories found matching 'dist' 14:31:36 INFO - no previously-included directories found matching '.git*' 14:31:36 INFO - Successfully installed jsonschema functools32 14:31:36 INFO - Cleaning up... 14:31:36 INFO - Return code: 0 14:31:36 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 14:31:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:31:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:31:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:31:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:31:36 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 0xaf71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xbfda80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xda0a10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd9dbb0>, '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 0xd9eab0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd9ef40>, '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': '5bd038846534b2fdfa68eeae000001e2-1502919045.249407-1615021432', '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 14:31:36 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 14:31:36 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 14:31:36 INFO - Using env: {'DISPLAY': ':0', 14:31:36 INFO - 'HOME': '/home/cltbld', 14:31:36 INFO - 'LANG': 'en_US.UTF-8', 14:31:36 INFO - 'LANGUAGE': 'en_US:en', 14:31:36 INFO - 'LOGNAME': 'cltbld', 14:31:36 INFO - 'MAIL': '/var/mail/cltbld', 14:31:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:31:36 INFO - 'MOZ_NO_REMOTE': '1', 14:31:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:31:36 INFO - 'NO_EM_RESTART': '1', 14:31:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:31:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:31:36 INFO - 'PWD': '/builds/slave/test', 14:31:36 INFO - 'SHELL': '/bin/bash', 14:31:36 INFO - 'SHLVL': '1', 14:31:36 INFO - 'TERM': 'linux', 14:31:36 INFO - 'TMOUT': '86400', 14:31:36 INFO - 'USER': 'cltbld', 14:31:36 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1502919045.249407-1615021432', 14:31:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:31:36 INFO - '_': '/tools/buildbot/bin/python'} 14:31:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:31:36 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 14:31:36 INFO - Cleaning up... 14:31:36 INFO - Return code: 0 14:31:36 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 14:31:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:31:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:31:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:31:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:31:36 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 0xaf71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xbfda80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xda0a10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd9dbb0>, '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 0xd9eab0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd9ef40>, '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': '5bd038846534b2fdfa68eeae000001e2-1502919045.249407-1615021432', '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 14:31:36 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 14:31:36 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 14:31:36 INFO - Using env: {'DISPLAY': ':0', 14:31:36 INFO - 'HOME': '/home/cltbld', 14:31:36 INFO - 'LANG': 'en_US.UTF-8', 14:31:36 INFO - 'LANGUAGE': 'en_US:en', 14:31:36 INFO - 'LOGNAME': 'cltbld', 14:31:36 INFO - 'MAIL': '/var/mail/cltbld', 14:31:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:31:36 INFO - 'MOZ_NO_REMOTE': '1', 14:31:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:31:36 INFO - 'NO_EM_RESTART': '1', 14:31:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:31:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:31:36 INFO - 'PWD': '/builds/slave/test', 14:31:36 INFO - 'SHELL': '/bin/bash', 14:31:36 INFO - 'SHLVL': '1', 14:31:36 INFO - 'TERM': 'linux', 14:31:36 INFO - 'TMOUT': '86400', 14:31:36 INFO - 'USER': 'cltbld', 14:31:36 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1502919045.249407-1615021432', 14:31:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:31:36 INFO - '_': '/tools/buildbot/bin/python'} 14:31:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:31:37 INFO - Downloading/unpacking blobuploader==1.2.4 14:31:37 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 14:31:37 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 14:31:37 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 14:31:37 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 14:31:38 INFO - Downloading blobuploader-1.2.4.tar.gz 14:31:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 14:31:38 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:31:38 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 14:31:38 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 14:31:38 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 14:31:38 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 14:31:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 14:31:38 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:31:38 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 14:31:38 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 14:31:38 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 14:31:38 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 14:31:38 INFO - Downloading docopt-0.6.1.tar.gz 14:31:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 14:31:38 INFO - Installing collected packages: blobuploader, requests, docopt 14:31:38 INFO - Running setup.py install for blobuploader 14:31:38 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 14:31:38 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 14:31:38 INFO - Running setup.py install for requests 14:31:39 INFO - Running setup.py install for docopt 14:31:39 INFO - Successfully installed blobuploader requests docopt 14:31:39 INFO - Cleaning up... 14:31:39 INFO - Return code: 0 14:31:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:31:39 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')]} 14:31:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:31:39 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')]} 14:31:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:31:39 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 0xaf71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xbfda80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xda0a10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd9dbb0>, '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 0xd9eab0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd9ef40>, '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': '5bd038846534b2fdfa68eeae000001e2-1502919045.249407-1615021432', '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 14:31:39 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 14:31:39 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 14:31:39 INFO - Using env: {'DISPLAY': ':0', 14:31:39 INFO - 'HOME': '/home/cltbld', 14:31:39 INFO - 'LANG': 'en_US.UTF-8', 14:31:39 INFO - 'LANGUAGE': 'en_US:en', 14:31:39 INFO - 'LOGNAME': 'cltbld', 14:31:39 INFO - 'MAIL': '/var/mail/cltbld', 14:31:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:31:39 INFO - 'MOZ_NO_REMOTE': '1', 14:31:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:31:39 INFO - 'NO_EM_RESTART': '1', 14:31:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:31:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:31:39 INFO - 'PWD': '/builds/slave/test', 14:31:39 INFO - 'SHELL': '/bin/bash', 14:31:39 INFO - 'SHLVL': '1', 14:31:39 INFO - 'TERM': 'linux', 14:31:39 INFO - 'TMOUT': '86400', 14:31:39 INFO - 'USER': 'cltbld', 14:31:39 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1502919045.249407-1615021432', 14:31:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:31:39 INFO - '_': '/tools/buildbot/bin/python'} 14:31:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:31:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:31:39 INFO - Running setup.py (path:/tmp/pip-iwMXhz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:31:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:31:39 INFO - Running setup.py (path:/tmp/pip-kfhhEc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:31:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:31:39 INFO - Running setup.py (path:/tmp/pip-w8xtI1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:31:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:31:39 INFO - Running setup.py (path:/tmp/pip-ZwOfPo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:31:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:31:39 INFO - Running setup.py (path:/tmp/pip-NX8Upd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:31:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:31:39 INFO - Running setup.py (path:/tmp/pip-c1lXBZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:31:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:31:40 INFO - Running setup.py (path:/tmp/pip-rv0Z2G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:31:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:31:40 INFO - Running setup.py (path:/tmp/pip-rZFseB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:31:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:31:40 INFO - Running setup.py (path:/tmp/pip-0qZbhP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:31:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:31:40 INFO - Running setup.py (path:/tmp/pip-z73BCn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:31:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:31:40 INFO - Running setup.py (path:/tmp/pip-GuZ1Ma-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:31:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:31:40 INFO - Running setup.py (path:/tmp/pip-ktjrWN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:31:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:31:40 INFO - Running setup.py (path:/tmp/pip-N1FYiY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:31:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:31:40 INFO - Running setup.py (path:/tmp/pip-YGl5aK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:31:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:31:40 INFO - Running setup.py (path:/tmp/pip-BIJ6ql-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:31:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:31:40 INFO - Running setup.py (path:/tmp/pip-iVg2sp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:31:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:31:40 INFO - Running setup.py (path:/tmp/pip-19JThi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:31:40 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:31:40 INFO - Running setup.py install for manifestparser 14:31:40 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 14:31:40 INFO - Running setup.py install for mozcrash 14:31:41 INFO - Running setup.py install for mozdebug 14:31:41 INFO - Running setup.py install for mozdevice 14:31:41 INFO - Installing dm script to /builds/slave/test/build/venv/bin 14:31:41 INFO - Running setup.py install for mozfile 14:31:41 INFO - Running setup.py install for mozhttpd 14:31:41 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 14:31:41 INFO - Running setup.py install for mozinfo 14:31:41 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 14:31:41 INFO - Running setup.py install for mozInstall 14:31:41 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 14:31:41 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 14:31:41 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 14:31:41 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 14:31:41 INFO - Running setup.py install for mozleak 14:31:41 INFO - Running setup.py install for mozlog 14:31:41 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 14:31:41 INFO - Running setup.py install for moznetwork 14:31:41 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 14:31:41 INFO - Running setup.py install for mozprocess 14:31:42 INFO - Running setup.py install for mozprofile 14:31:42 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 14:31:42 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 14:31:42 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 14:31:42 INFO - Running setup.py install for mozrunner 14:31:42 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 14:31:42 INFO - Running setup.py install for mozscreenshot 14:31:42 INFO - Running setup.py install for moztest 14:31:42 INFO - Running setup.py install for mozversion 14:31:42 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 14:31:42 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:31:42 INFO - Cleaning up... 14:31:42 INFO - Return code: 0 14:31:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:31:42 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')]} 14:31:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:31:42 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')]} 14:31:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:31:42 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 0xaf71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xbfda80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xda0a10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd9dbb0>, '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 0xd9eab0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd9ef40>, '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': '5bd038846534b2fdfa68eeae000001e2-1502919045.249407-1615021432', '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 14:31:42 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 14:31:42 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 14:31:42 INFO - Using env: {'DISPLAY': ':0', 14:31:42 INFO - 'HOME': '/home/cltbld', 14:31:42 INFO - 'LANG': 'en_US.UTF-8', 14:31:42 INFO - 'LANGUAGE': 'en_US:en', 14:31:42 INFO - 'LOGNAME': 'cltbld', 14:31:42 INFO - 'MAIL': '/var/mail/cltbld', 14:31:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:31:42 INFO - 'MOZ_NO_REMOTE': '1', 14:31:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:31:42 INFO - 'NO_EM_RESTART': '1', 14:31:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:31:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:31:42 INFO - 'PWD': '/builds/slave/test', 14:31:42 INFO - 'SHELL': '/bin/bash', 14:31:42 INFO - 'SHLVL': '1', 14:31:42 INFO - 'TERM': 'linux', 14:31:42 INFO - 'TMOUT': '86400', 14:31:42 INFO - 'USER': 'cltbld', 14:31:42 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1502919045.249407-1615021432', 14:31:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:31:42 INFO - '_': '/tools/buildbot/bin/python'} 14:31:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:31:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:31:42 INFO - Running setup.py (path:/tmp/pip-Cu0rb1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:31:42 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)) 14:31:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:31:42 INFO - Running setup.py (path:/tmp/pip-pPJDNP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:31:42 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)) 14:31:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:31:42 INFO - Running setup.py (path:/tmp/pip-La2cjr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:31:43 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)) 14:31:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:31:43 INFO - Running setup.py (path:/tmp/pip-56K5ur-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:31:43 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)) 14:31:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:31:43 INFO - Running setup.py (path:/tmp/pip-l3zsf9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:31:43 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)) 14:31:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:31:43 INFO - Running setup.py (path:/tmp/pip-ynxFzo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:31:43 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)) 14:31:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:31:43 INFO - Running setup.py (path:/tmp/pip-TccWUu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:31:43 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)) 14:31:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:31:43 INFO - Running setup.py (path:/tmp/pip-jtrRRk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:31:43 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)) 14:31:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:31:43 INFO - Running setup.py (path:/tmp/pip-w6AwBC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:31:43 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)) 14:31:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:31:43 INFO - Running setup.py (path:/tmp/pip-chTucU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:31:43 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)) 14:31:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:31:43 INFO - Running setup.py (path:/tmp/pip-pXEl_z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:31:43 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)) 14:31:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:31:43 INFO - Running setup.py (path:/tmp/pip-4nHx7G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:31:43 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)) 14:31:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:31:43 INFO - Running setup.py (path:/tmp/pip-NPhmH9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:31:43 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)) 14:31:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:31:43 INFO - Running setup.py (path:/tmp/pip-1touZX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:31:43 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)) 14:31:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:31:43 INFO - Running setup.py (path:/tmp/pip-AdXagI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:31:43 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)) 14:31:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:31:43 INFO - Running setup.py (path:/tmp/pip-_4xqXk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:31:44 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)) 14:31:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:31:44 INFO - Running setup.py (path:/tmp/pip-F84naW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:31:44 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)) 14:31:44 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)) 14:31:44 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)) 14:31:44 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)) 14:31:44 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)) 14:31:44 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)) 14:31:44 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)) 14:31:44 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:31:44 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 14:31:44 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 14:31:44 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 14:31:44 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 14:31:45 INFO - Downloading blessings-1.6.tar.gz 14:31:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 14:31:45 INFO - Installing collected packages: blessings 14:31:45 INFO - Running setup.py install for blessings 14:31:45 INFO - Successfully installed blessings 14:31:45 INFO - Cleaning up... 14:31:45 INFO - Return code: 0 14:31:45 INFO - Done creating virtualenv /builds/slave/test/build/venv. 14:31:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:31:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:31:45 INFO - Reading from file tmpfile_stdout 14:31:45 INFO - Current package versions: 14:31:45 INFO - argparse == 1.2.1 14:31:45 INFO - blessings == 1.6 14:31:45 INFO - blobuploader == 1.2.4 14:31:45 INFO - docopt == 0.6.1 14:31:45 INFO - functools32 == 3.2.3-2 14:31:45 INFO - jsonschema == 2.5.1 14:31:45 INFO - manifestparser == 1.1 14:31:45 INFO - mozInstall == 1.13 14:31:45 INFO - mozcrash == 1.0 14:31:45 INFO - mozdebug == 0.1 14:31:45 INFO - mozdevice == 0.51 14:31:45 INFO - mozfile == 1.2 14:31:45 INFO - mozhttpd == 0.7 14:31:45 INFO - mozinfo == 0.10 14:31:45 INFO - mozleak == 0.1 14:31:45 INFO - mozlog == 3.5 14:31:45 INFO - moznetwork == 0.27 14:31:45 INFO - mozprocess == 0.25 14:31:45 INFO - mozprofile == 0.28 14:31:45 INFO - mozrunner == 6.13 14:31:45 INFO - mozscreenshot == 0.1 14:31:45 INFO - mozsystemmonitor == 0.3 14:31:45 INFO - moztest == 0.8 14:31:45 INFO - mozversion == 1.4 14:31:45 INFO - psutil == 3.1.1 14:31:45 INFO - requests == 1.2.3 14:31:45 INFO - wsgiref == 0.1.2 14:31:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:31:45 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')]} 14:31:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:31:45 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')]} 14:31:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:31:45 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 0xaf71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xbfda80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xda0a10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd9dbb0>, '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 0xd9eab0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd9ef40>, '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': '5bd038846534b2fdfa68eeae000001e2-1502919045.249407-1615021432', '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 14:31:45 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 14:31:45 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 14:31:45 INFO - Using env: {'DISPLAY': ':0', 14:31:45 INFO - 'HOME': '/home/cltbld', 14:31:45 INFO - 'LANG': 'en_US.UTF-8', 14:31:45 INFO - 'LANGUAGE': 'en_US:en', 14:31:45 INFO - 'LOGNAME': 'cltbld', 14:31:45 INFO - 'MAIL': '/var/mail/cltbld', 14:31:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:31:45 INFO - 'MOZ_NO_REMOTE': '1', 14:31:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:31:45 INFO - 'NO_EM_RESTART': '1', 14:31:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:31:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:31:45 INFO - 'PWD': '/builds/slave/test', 14:31:45 INFO - 'SHELL': '/bin/bash', 14:31:45 INFO - 'SHLVL': '1', 14:31:45 INFO - 'TERM': 'linux', 14:31:45 INFO - 'TMOUT': '86400', 14:31:45 INFO - 'USER': 'cltbld', 14:31:45 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1502919045.249407-1615021432', 14:31:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:31:45 INFO - '_': '/tools/buildbot/bin/python'} 14:31:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:31:45 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)) 14:31:45 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)) 14:31:45 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)) 14:31:45 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)) 14:31:45 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)) 14:31:45 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)) 14:31:45 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)) 14:31:45 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)) 14:31:45 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)) 14:31:45 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 14:31:45 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 14:31:45 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 14:31:45 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 14:31:45 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 14:31:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 14:31:47 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)) 14:31:47 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)) 14:31:47 INFO - Installing collected packages: simplejson 14:31:47 INFO - Running setup.py install for simplejson 14:31:47 INFO - building 'simplejson._speedups' extension 14:31:47 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 14:31:47 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 14:31:48 INFO - Successfully installed simplejson 14:31:48 INFO - Cleaning up... 14:31:48 INFO - Return code: 0 14:31:48 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 14:31:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:31:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:31:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:31:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:31:48 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 0xaf71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xbfda80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xda0a10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd9dbb0>, '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 0xd9eab0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd9ef40>, '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': '5bd038846534b2fdfa68eeae000001e2-1502919045.249407-1615021432', '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 14:31:48 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 14:31:48 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 14:31:48 INFO - Using env: {'DISPLAY': ':0', 14:31:48 INFO - 'HOME': '/home/cltbld', 14:31:48 INFO - 'LANG': 'en_US.UTF-8', 14:31:48 INFO - 'LANGUAGE': 'en_US:en', 14:31:48 INFO - 'LOGNAME': 'cltbld', 14:31:48 INFO - 'MAIL': '/var/mail/cltbld', 14:31:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:31:48 INFO - 'MOZ_NO_REMOTE': '1', 14:31:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:31:48 INFO - 'NO_EM_RESTART': '1', 14:31:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:31:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:31:48 INFO - 'PWD': '/builds/slave/test', 14:31:48 INFO - 'SHELL': '/bin/bash', 14:31:48 INFO - 'SHLVL': '1', 14:31:48 INFO - 'TERM': 'linux', 14:31:48 INFO - 'TMOUT': '86400', 14:31:48 INFO - 'USER': 'cltbld', 14:31:48 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1502919045.249407-1615021432', 14:31:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:31:48 INFO - '_': '/tools/buildbot/bin/python'} 14:31:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:31:48 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 14:31:48 INFO - Cleaning up... 14:31:48 INFO - Return code: 0 14:31:48 INFO - Running post-action listener: _resource_record_post_action 14:31:48 INFO - Running post-action listener: _start_resource_monitoring 14:31:48 INFO - Starting resource monitoring. 14:31:48 INFO - [mozharness: 2017-08-16 21:31:48.399799Z] Finished create-virtualenv step (success) 14:31:48 INFO - [mozharness: 2017-08-16 21:31:48.400786Z] Running install step. 14:31:48 INFO - Running pre-action listener: _resource_record_pre_action 14:31:48 INFO - Running main action method: install 14:31:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:31:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:31:48 INFO - Reading from file tmpfile_stdout 14:31:48 INFO - Detecting whether we're running mozinstall >=1.0... 14:31:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 14:31:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 14:31:48 INFO - Reading from file tmpfile_stdout 14:31:48 INFO - Output received: 14:31:48 INFO - Usage: mozinstall [options] installer 14:31:48 INFO - Options: 14:31:48 INFO - -h, --help show this help message and exit 14:31:48 INFO - -d DEST, --destination=DEST 14:31:48 INFO - Directory to install application into. [default: 14:31:48 INFO - "/builds/slave/test"] 14:31:48 INFO - --app=APP Application being installed. [default: firefox] 14:31:48 INFO - mkdir: /builds/slave/test/build/application 14:31:48 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'] 14:31:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 14:32:06 INFO - Reading from file tmpfile_stdout 14:32:06 INFO - Output received: 14:32:06 INFO - /builds/slave/test/build/application/firefox/firefox 14:32:06 INFO - Running post-action listener: _resource_record_post_action 14:32:06 INFO - [mozharness: 2017-08-16 21:32:06.639008Z] Finished install step (success) 14:32:06 INFO - [mozharness: 2017-08-16 21:32:06.639262Z] Running setup-mitmproxy step. 14:32:06 INFO - Running pre-action listener: _resource_record_pre_action 14:32:06 INFO - Running main action method: setup_mitmproxy 14:32:06 INFO - Skipping: mitmproxy is not required 14:32:06 INFO - Running post-action listener: _resource_record_post_action 14:32:06 INFO - [mozharness: 2017-08-16 21:32:06.640407Z] Finished setup-mitmproxy step (success) 14:32:06 INFO - [mozharness: 2017-08-16 21:32:06.640629Z] Running run-tests step. 14:32:06 INFO - Running pre-action listener: _resource_record_pre_action 14:32:06 INFO - Running pre-action listener: _set_gcov_prefix 14:32:06 INFO - Running main action method: run_tests 14:32:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 14:32:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 14:32:06 INFO - Python 2.7.3 14:32:06 INFO - Return code: 0 14:32:06 INFO - grabbing minidump binary from tooltool 14:32:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:32:06 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 0xd9dbb0>, '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 0xd9eab0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd9ef40>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 14:32:06 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 14:32:06 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 14:32:06 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 14:32:07 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:32:07 INFO - Return code: 0 14:32:07 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 14:32:07 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 14:32:07 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 14:32:07 INFO - ENV: RUST_BACKTRACE is now full 14:32:07 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 14:32:07 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 14:32:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ZbYi7e4SShuLRJRdD3myxg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-039', '--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 14:32:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/ZbYi7e4SShuLRJRdD3myxg/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-039 --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 14:32:07 INFO - Using env: {'DISPLAY': ':0', 14:32:07 INFO - 'HOME': '/home/cltbld', 14:32:07 INFO - 'LANG': 'en_US.UTF-8', 14:32:07 INFO - 'LANGUAGE': 'en_US:en', 14:32:07 INFO - 'LOGNAME': 'cltbld', 14:32:07 INFO - 'MAIL': '/var/mail/cltbld', 14:32:07 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 14:32:07 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 14:32:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:32:07 INFO - 'MOZ_NO_REMOTE': '1', 14:32:07 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 14:32:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:32:07 INFO - 'NO_EM_RESTART': '1', 14:32:07 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:32:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:32:07 INFO - 'PWD': '/builds/slave/test', 14:32:07 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 14:32:07 INFO - 'RUST_BACKTRACE': 'full', 14:32:07 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 14:32:07 INFO - 'SHELL': '/bin/bash', 14:32:07 INFO - 'SHLVL': '1', 14:32:07 INFO - 'TERM': 'linux', 14:32:07 INFO - 'TMOUT': '86400', 14:32:07 INFO - 'USER': 'cltbld', 14:32:07 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1502919045.249407-1615021432', 14:32:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:32:07 INFO - '_': '/tools/buildbot/bin/python'} 14:32:07 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ZbYi7e4SShuLRJRdD3myxg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-039', '--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 14:32:07 INFO - mozversion application_buildid: 20170816203421 14:32:07 INFO - mozversion application_changeset: 833f84d0d5c729054a3aa8b3f34735f56fe6436b 14:32:07 INFO - mozversion application_display_name: Nightly 14:32:07 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:32:07 INFO - mozversion application_name: Firefox 14:32:07 INFO - mozversion application_remotingname: firefox 14:32:07 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 14:32:07 INFO - mozversion application_vendor: Mozilla 14:32:07 INFO - mozversion application_version: 57.0a1 14:32:07 INFO - mozversion platform_buildid: 20170816203421 14:32:07 INFO - mozversion platform_changeset: 833f84d0d5c729054a3aa8b3f34735f56fe6436b 14:32:07 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 14:32:07 INFO - mozversion platform_version: 57.0a1 14:32:07 INFO - using testdate: 1502919127 14:32:07 INFO - actual date: 1502919127 14:32:07 INFO - starting webserver on 'localhost:41969' 14:32:07 INFO - SUITE-START | Running 2 tests 14:32:07 INFO - TEST-START | dromaeo_css 14:32:07 INFO - Initialising browser for dromaeo_css test... 14:32:07 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:41969/getInfo.html -profile /tmp/tmpn7jkkg/profile 14:32:07 INFO - TEST-INFO | started process 3317 (/builds/slave/test/build/application/firefox/firefox http://localhost:41969/getInfo.html) 14:32:15 INFO - TEST-INFO | 3317: exit 0 14:32:15 INFO - Browser initialized. 14:32:15 INFO - Running cycle 1/1 for dromaeo_css test... 14:32:15 INFO - TEST-INFO | started process 3527 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpn7jkkg/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 14:32:16 INFO - PID 3527 | 14:32:16 INFO - PID 3527 | (/builds/slave/test/build/application/firefox/firefox:3575): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 14:32:16 INFO - PID 3527 | 14:32:27 INFO - PID 3527 | RSS: Main: 168677376 14:32:27 INFO - PID 3527 | 14:35:25 INFO - PID 3527 | Cycle 1(1): loaded http://localhost:41969/tests/dromaeo/cssquery-dojo.html (next: http://localhost:41969/tests/dromaeo/cssquery-ext.html) 14:35:27 INFO - PID 3527 | RSS: Main: 172855296 14:35:27 INFO - PID 3527 | 14:38:29 INFO - PID 3527 | Cycle 1(1): loaded http://localhost:41969/tests/dromaeo/cssquery-ext.html (next: http://localhost:41969/tests/dromaeo/cssquery-jquery.html) 14:38:30 INFO - PID 3527 | RSS: Main: 169054208 14:38:30 INFO - PID 3527 | 14:41:31 INFO - PID 3527 | Cycle 1(1): loaded http://localhost:41969/tests/dromaeo/cssquery-jquery.html (next: http://localhost:41969/tests/dromaeo/cssquery-mootools.html) 14:41:32 INFO - PID 3527 | RSS: Main: 170749952 14:41:32 INFO - PID 3527 | 14:44:34 INFO - PID 3527 | Cycle 1(1): loaded http://localhost:41969/tests/dromaeo/cssquery-mootools.html (next: http://localhost:41969/tests/dromaeo/cssquery-prototype.html) 14:44:34 INFO - PID 3527 | RSS: Main: 170758144 14:44:34 INFO - PID 3527 | 14:47:38 INFO - PID 3527 | Cycle 1(1): loaded http://localhost:41969/tests/dromaeo/cssquery-prototype.html (next: http://localhost:41969/tests/dromaeo/cssquery-yui.html) 14:47:39 INFO - PID 3527 | RSS: Main: 172335104 14:47:39 INFO - PID 3527 | 14:50:40 INFO - PID 3527 | Cycle 1(1): loaded http://localhost:41969/tests/dromaeo/cssquery-yui.html (next: http://localhost:41969/tests/dromaeo/cssquery-dojo.html) 14:50:41 INFO - PID 3527 | RSS: Main: 174866432 14:50:41 INFO - PID 3527 | 14:50:41 INFO - PID 3527 | __start_tp_report 14:50:41 INFO - PID 3527 | _x_x_mozilla_page_load 14:50:41 INFO - PID 3527 | _x_x_mozilla_page_load_details 14:50:41 INFO - PID 3527 | |i|pagename|runs| 14:50:41 INFO - PID 3527 | |0;dojo.html;3150.070729134905;3179.793313434627;3185.378851124031;3192.808431494112;3207.9198020049494;6059.878802423955;6082.543809214305;6144.723487443064;6194.070889366597;6239.9688001559925;6055.6972151392365;6091.7258723357445;6105.267367915845;6134.785282515113;6180.567360284771;5550.111982082867;5613.298337707786;5618.12918997554;5623.662580245178;5641.069223578125;5660.29246344207;5714.25714657094;5728.312602487707;5740.913886291688;5780.942190578083;5661.688607126606;5675.290588676416;5684.943150568482;5725.14122881569;5728.427157284259;5453.100238460649;5508.586855985817;5519.254900588449;5523.751431185607;5588.273524441837;5571.888562228733;5612.887742245173;5648;5656.632318899258;5657.886842263173;5968.343482216953;6101.786437474711;6112.083187521863;6144.38556144382;6194.380561943814;6391.488680905516;6408.967955160241;6434.356564343529;6519.478441724651;6520.152380190592;6493.4155925966415;6496;6526.053722210253;6557;6561.0814485972005;6035.637861728266;6041.24484439445;6155.87688246237;6158;6223.533235007392;6751.966240168767;6803.65981700913;6820.727170913207;6840.384365407137;6897.034415181878;6494.772682956074;6500.187476565429;6567.047778072153;6592.835179120464;6600.669966501656;6522.217333919865;6542.705578248991;6591.505637077238;6593.142891424084;6744.6627668616375;6587.308332625101;6616.900746488806;6618.271990081087;6619.040239165294;6630.469562434993;6574.276829548746;6662.900056499157;6672.132622759107;6672.599644021374;6708.563943343667;5341.0653135701095;5364.544013758796;5411.621186516906;5412.675239485565;5413.702246376487;4501.18978583858;4597.7241365518175;4622.560856718606;4721.008588196517;4742.739149346819;5764.135379692996;5813.040848260074;5813.593048486566;5820.82537523875;5841.474267315877;6543.083968244449;6652.567583107082;6691.5650482718465;6719.697613607399;6740.83147921296;5590.5248053915875;5749.88500229993;5824.737886795105;5832.037713777179;5843.444872737168;5784.537237021028;5884.293884733774;5910.8817823643285;5921.526277897758;5932.169496270526;6522.184726909137;6552.344765523505;6560.179977502812;6560.507961902877;6585.045168450548;6619.404253617197;6630.171228596426;6634.734610615521;6703.564268322543;6729.057931889637;6211.844703882439;6298;6427.614343139427;6449.322821103904;6542.869142617216;6400;6456.903146452901;6457.160569126171;6474.5791523550815;6484.351564843667;6589.044588534732;6620.966895165493;6627.204735431586;6640.601563906181;6647.036179753812;6473.190851143607;6499.902501462577;6501.057346684799;6518.696260747774;6520.869582608416;6360.268569114426;6367.108604795425;6433.581817181868;6476.870462590816;6527.379898909596;6206.875862482635;6322.778702745381;6343.397377249154;6345.873082538231;6384.042393640991;6511.674416279262;6526.738930442729;6575.835604109744;6577.572457790228;6587.736490540517;6344.048392741126;6348.396902294275;6349.269833968968;6353.555251132561;6354.872902542016 14:50:41 INFO - PID 3527 | |1;ext.html;738.47937204271;789.0531362365161;806.3589446390121;811.4928169893813;1156.58941075918;30268.243293917636;30641.08076757698;40936.18127637449;41629.375559366556;41924.37113443301;41332.38001429981;42036.15927681445;42143.78928105363;42174.789126054406;42218.7889060555;7485.550866947973;7680.692772289101;7684.769456916273;7685.692572297102;7687.6156192190165;7565.659545320446;7687;7693.269139431745;7693.30760231579;7696.576688282143;7649.38804895607;7729.458937874351;7731.652075656609;7735.922640773577;7737.148913619498;7657.923420765805;7683.884741728879;7687.769366919001;7689.807754806118;7695.230476952316;7466.514676546007;7519;7530.43521735872;7531.096268447766;7533.585652789095;38187.23625527502;38369.23261534781;38402.231955361014;38451.23097538062;38506;3514.964850351515;3550.4141816600486;3555.1289933966086;3560.092176494977;3565.3404120237838;28968.130956071353;29141.271468213254;29209.561856572167;29218.70781292181;29239.85380073086;5096.159133742928;5103.87240318995;5133.281340612317;5158.561522270612;5190.06578815813;192052;192731;193331;194019;194246;47848;48120.27819582709;48224.03551929012;49572.75213623909;49846;51847.74076129671;51867.2219916694;51929.48070519322;52053;52123.47876521186;14783.260836958325;15020.624484387978;15022.24888755558;15025.849741502663;16611.169441527876;11858.822117668242;11878.227915185485;11897;11920.52317907274;11940.283582985048;14024.579262622137;14030.789538156936;14053.508127215702;14080.647983800282;14152.080114792505;5592.888142237214;5627.971860140673;5644.238027866186;5730.77076916927;5807.448292412214;974.1038244814788;996.835522138839;1004.3572113847143;1010.4990149380371;1020.132887046005;21906.12375504962;22100;22114.557708845732;22239;22251.998660060337;1067.2955849139687;1082.3884505254664;1087.217203613397;1122.4892673833385;1123.8538989931258;12346.938265308616;12444.937775311066;12466.439010244561;12543.435545400296;12544.623661290176;261240;262738;262763;262884;262936;51922.48077519176;52063;52162;52361.7381913088;52483;1433.5412667946366;1446.9348879300458;1455.2069122328357;1461.978070328946;1468.4713503138862;22696;22743.203987860346;22785.544289114456;23021.88489057611;23195.76804231936;38342;38567.228655426174;38662.420063698504;49716.75141624269;52716.20925686195;219344;221444;221530;221697;221844;36041.459378108826;36262.8186859064;36477;36497;36513.81743091268;43427;44591.10817783691;44875.10249794921;44942.77528612336;45063.54936450725;32355.926187012195;32758.836205818818;33254.667453326125;33400.33199336048;33660.66339336575;32411.351772963935;32608.34783304368;32610.347793044482;32644.34711305808;33112.1721956953;35024.64975350214;35070.47394289036;35229.82385088058;35460.64539354573;35575.46636800398;30983.380332393677;31412;31491;31597;31854.68145318517;22263.643677759;23466.296011119695;23764.287071387887;23874.761252387252;24206.878965605058 14:50:41 INFO - PID 3527 | |2;jquery.html;561.2114978455272;568.874847533543;575.4159528079;578.3175852494055;591.7810410148245;22236.88881555594;22522.774772252294;22996.88501557494;23118.306450806504;23139.768602313972;25515.61726574098;26002.869985650093;26028.739712602917;26067.478650427027;26126.34684132893;6333.651649159295;6484.740610375603;6488.383603557654;6492.05865149553;6494.902576461357;6294.968525157367;6347.904781428282;6395.104685344032;6974.128233970754;7001.299870012984;6991.545549539263;6992.230854605989;6995.755148569801;6999.720011199572;7003.369696727294;6932.410745086674;6984.685689144001;6992.58044517328;6996.090508233948;6998.68505917235;6854;6868.7252509899545;6869.107016087876;6880.449564034865;6883.724651013953;30062.69937300621;30494.08517744449;31240;31762.841185793925;31961.200969975704;1909.3699079303797;1951.0049874563933;1953.8632295739303;1956.5010922214885;1959.8824070555813;19964.401067967985;20668.58662826735;21111.261105861373;21125.154993800228;21144.2599509018;2475.492524032569;2492.439201179738;2569.075132952136;2575.549278876208;2576;143325;147802;148320.25839870732;148374.51625483716;148627.25686371606;20449.48876278075;21735.565288694455;21978.780212197995;22209.55580888373;22468.32595022152;44326.33510497345;44398.778006110406;44432.55567444349;44483.332750008776;44565.331520027226;11942.820857687142;12177.939110304393;12308.26150430977;12330.321832299309;12398.62804115878;10244.180465562886;10280.845787313196;10319;10408.947955260175;10436.165106791439;9114.589843456928;10859.425383319374;11505.309681419141;11793.646190614296;12081.335526545949;3332.900012999614;3390.5761779777526;3585.3904836177912;3832.559255685576;3930.4104384342004;946.9952650236705;964.2575217082807;965.4496936746128;969.4280374579032;988.6786794291884;19361.12874920632;19385.321513746952;19399.806001940084;19623.31318403849;20252.898735506522;1039.7868436970402;1043.3426941027255;1051.4059556350637;1054.5254635413942;1058.8358804385332;10985.450727463594;10995;11057.225994180491;11108.555657773599;11199.384033878052;486386;497171;504571;505079;531632;114175.42912285385;114647;114709;114728;115600.42199788947;1176.6528873982195;1198.7602479504135;1223.577865636354;1226.5461779141597;1232.796588562895;17465.738013930055;17498.650026999643;17627.03051332189;17950.461486155436;18063.458096257134;53152.20271696005;53624.19563706625;54352.18471722848;54571;55252;64341;64991.67504162449;69044;69155.30844691489;70039.29960700328;19721.211151553776;20076.69884951756;20080.799192007893;20360.79639203589;20374.388768336972;22548.436289092904;22577.774222258224;22651.660225096308;22764.20325288607;22955.54088918179;18192;18232.36186733458;18369;18439;18481.72277415813;15155.469558565397;15387.846121538641;15546.611334716723;15833.208339582745;16070;18176.00031998253;18201.544961376072;18559.53601159982;18641.067946602343;18910.338138165098;19697.507562311057;19702.704459433407;19789.60420791605;19791.505212369804;19803.10886010162;18081.819181808012;18188.272469101088;18233.908830455763;18287.359942401952;18318.81681183171 14:50:41 INFO - PID 3527 | |3;mootools.html;1350.7838745800673;1402.8947828912835;1425.144913052169;1431.6850292935565;1438.5252866554038;14217.502387416442;14540.636484087903;14829.851701482981;14840.628984275398;14853.55439336819;15168.924155379234;15396.84603153968;15414.69170616587;15430.69138617227;15431.537053888402;2688.3951111000065;2727.986360068197;2731.726827317262;2732.098407525512;2732.8770205340807;2641.128427618891;2676.451327477872;2709.282040259333;2713.4844379567917;2714.647095877543;2645.7618814306707;2693.407450360927;2704.9459010819774;2757.765589924859;2763.474939761449;2674.986625066872;2675.6789185297694;2677.7456141666607;2687.2610032241096;2697.676278846541;2592.6499922510598;2594.3903182752038;2607.2699644099685;2611.138324352959;2613.9607905881235;14016;14389.78415323771;14395.136291822524;14398.784018239734;14403.279836008158;7464.626768661599;7728.961355193244;7730.458867879251;7734.7679569613;7739.148693643695;4994.700317980896;5156.742162891878;5171.276021357013;5258.737063146866;5276.630635855492;1823.7203495630463;1827.1047186878466;1834.4038187588953;1859.0890463672838;1881.9435416937513;23502.412439689146;23839.046438142454;24119.87940060306;24146.275611731675;24821;6315.210598675166;6325.65208913505;6340.651264180515;6487.902681459782;6503.674816259168;15618.375264989274;15636.4527241546;15640.687186256439;15653.608659783597;15671.294791734399;7501.624918754041;7601.315881570686;7625.161232264447;7626.733064342721;7632.389408847279;6911.447084233249;6916.965415172892;6971.302869713089;7024.964875175694;7106.857862842714;4745.525447455296;4914.189158788831;4935.975320123376;4937.086638971802;4940.407151141814;1429.478240442231;1458.4530800949644;1466.6846627974824;1485.695432436348;1494.8355680875095;875.4484674655054;892.8571428571397;905.524599585223;909.1090731083575;914.4193437167446;7097.645117744195;8732.345074119467;9063.773405664911;9077.682281120262;9150.267978561698;974.5030034682363;982.1602529837029;1014.1379827146866;1019.4393083803878;1021.7445638590352;4778.904421911542;4975.328330675385;4999.925001124986;5072.797088116507;5115.130427827278;19498.70751938722;19956.401307960783;19983.700244496344;20158;20175.192992280143;19463.513412164524;20043.799562004486;20046.89976550108;20082;20098.19607215695;9779.217662586976;10175.185985121025;11112.499937502831;11208.27146235492;11249.268797528299;7436.851262974818;8473.576321183793;8515.233628973423;8719.694810681596;8933.553322333988;17421;17549.1225438725;17746.41012486755;17911.820881791016;18180.999999999472;17439.389621363192;18020.639587208443;18034;18123.72814407811;18436.631267374847;13128.60614181576;13510.256935868621;13513.189208647513;13517.188968661912;13547.187168769906;13309.80035299452;13510.527131550349;13626.250556219504;13634.590962271148;13876.930615346859;11084.833727493933;11127.221094523306;11142.777144456903;11248.83126753083;11268.943655281671;10581.62964296246;10593.09958653523;10639.8936010642;10681.57273709043;10904.672859814218;8960.775980600538;9086.954565227132;9094.2269907056;9105.954470227607;9107.22588579978;6778.254392016973;6805.6937437816405;6824.044633751179;6838.555493892881;6873.0377747116445;7009.474289428415;7031.824204394931;7055;7083.6812343443635;7104.11198600175 14:50:41 INFO - PID 3527 | |4;prototype.html;485.59210263378753;494.7166422089489;509.23868816119904;512.7538781384935;518.924755910393;6240.3759624037575;6241.563090583661;6293.118963345124;6302.621842689437;6304.8108556743255;5829.795957141501;6009.699515024253;6019.518438524919;6034.487068599175;6167.568270221086;5100.362454693154;5137.51193636604;5146.356705411824;5154.814392689684;5166.044281807859;5148.98784211235;5155.278261043457;5160.483951604847;5175.353080864892;5176.249443830642;4793.5685788279225;4950.702957822524;4976.830444845458;4984.1526940420035;4999.850004499871;4943.629227807893;4944.134776414131;4973.726445045521;4974.651774375795;5010.248462730619;4439.489458712257;4466.620337271336;4477.126960242754;4484.573965473275;4497.685162038659;6084.539710469499;6141.907871381933;6151.677389361309;6154.692265386758;6168.352323006109;5697.544196464273;5715.799947001877;5740.282464691913;5749.511291540224;5751.798687045975;6488.0592314114665;6546.134351709788;6574.605523668547;6613.867722645568;6631.436327912085;4833.275008748716;4886.731229782361;4893.168161412568;4897.51024897511;4915.213565829486;6909.3380508330665;6979.197392299849;6981.581105133709;6983.615901125436;7006.334398232159;5029.321041659402;5031.6729412588065;5047.4447810740785;5077.771500282496;5081.415637201695;5068.4287870759445;5106.15748401517;5109.693418394908;5121.820736274287;5126.461721519261;5689.260396148474;5712.971435142797;5715.514181294553;5733.598648094593;5753.88492230153;6540.533517235777;6592.43964263043;6597.8350541235895;6661.367170118831;6673.165854268216;1667.5164202381416;1705.4116329866176;1736.8697347698974;1779.715245560704;1883.2561138350275;1426.522115091456;1442.581651321105;1449.8260208775014;1452.2282814965724;1452.4843680493484;5670.404607516234;5678.261825962681;5738.454846789548;5748.137779333133;5760.942390576124;6617;6667.399934005963;6721.7311307548125;6755.324467553205;6814.352636499525;5846.795362162304;5857.887001469707;5870.941290587125;5896.469317761422;5896.906598157678;3593.4969104325414;3621.6197299283194;3623.474596183539;3634.9636503635156;3637.308911306843;6600.273969863311;6633.834154146185;6646.23874673212;6686.297938716462;6698.129243198353;6963.825904352431;7002.159740831133;7003.3346832050875;7004.4396448284015;7025.824354391181;6579.934200658124;6618.271990081087;6625.966870165618;6642.800715978528;6652.534322597372;6758.904739530762;6770.458363330823;6799.422049125879;6803.727850885909;6804.931950680628;6688.6990085445295;6697.196336439659;6751.527393082437;6778.457723382019;6781.38967492928;6524.380183882524;6559.4752419807255;6582.334154667367;6597.406233439014;6655.833604159935;4971.776270067783;5056.266841308064;5063.113955057924;5078.492150784892;5089.13484707608;6779.491538134561;6779.796606101825;6798.52410331272;6862.691178896862;6875.965620171867;6552.737890484327;6580.868382632225;6609.272979972103;6631.767888123893;6636.767713130017;6422.6467544285515;6458.257300410325;6543.247526534319;6546.869062618816;6552.935825215042;6284.685765711604;6331.398517140864;6341.524385671185;6389.329120442472;6437.774677886251;6486.5459417842185;6557.737690492518;6627.801165965029;6656.600603963778;6668.899966500409;6309.526785491198;6330.8100756977365;6347.619142851444;6355.809325720235;6385 14:50:41 INFO - PID 3527 | |5;yui.html;877.2630989968425;888.2982443869341;904.2630256341085;909.6588779207797;923.224491427201;17978.730319045226;18757.531061723465;18821.153048112832;18843.963582003;22375.216867409643;20895.268665596708;22257.2209972651;22291;22325.44186395341;22388.88805555974;1021.678171376016;1060.0565496707898;1061.060961049473;1065.8241390170613;1068.6259809066842;1008.2424568804314;1057.8095942730306;1060.4379678770226;1068.1081297116916;1069.1927594666056;1105.4196546812948;1111.4053981120103;1112.0603090388609;1115.1524841120765;1117.7261570915136;1110.944452777362;1115.7433790228292;1115.8493603363604;1116.0792346314324;1116.972075698106;813.0812182234097;813.2193094629163;813.9064007639138;814.1126172471975;814.6252723747084;20447.182112715473;20493.4876628084;20776.272830451013;20777.06503207359;20900.477488062766;21715;21812.236571719914;21902.561948761097;22059.779402206095;22167;16842.747358789627;17043.14784260782;17147.82852171475;17206.311747530082;17227.138643067796;3596.0829988353066;3622.9275414491826;3626.220362622049;3626.8005259710703;3627.2382799612114;123318.38340808329;128657.35671321584;128896.35551822181;129107;129754.3512282442;17086.145692715316;17582.296708131787;17672.646547068987;17734.11329433523;18269.63460730778;31569.36861262762;31604.051878443683;31615.841920790248;31766.682333176374;32182.517262241086;7677.923220767832;8102.230288122619;8406.579671016547;8666.956665216634;9123.406978546505;4329.15581461612;4441.267190913528;4441.400410944481;4455.554444555519;4459.219636563588;5257.526822585985;5322.8935421292135;5324;5340.198970154508;5364.624476286701;1036.7822757220918;1047.0838016735356;1058.4231593781408;1059.0680201422854;1063.1654151491043;667.4493542827187;686.8729285082276;688.6763221285987;702.8594281143792;708.3447810775116;11637.418129093512;11732.296062236293;12099.27404355724;12347.488038277368;12782.9360853197;665.2682049745241;672.4754691340762;676.3304328714537;677.7729460630744;691.7924622613297;5739.426057394227;6089.8477538061015;6092.69536523181;6130.846728831815;6161.229846269216;113721.43139284251;115464.42267788608;117413.4129329365;117453.41273293579;119698;97541;98791.50604246932;99620.50189749004;101380.49309753551;101870.49064754629;90031;90775;91553;91558;93541.99999999863;12860.549880754128;13390.866091339352;13408.396622151831;13477.19136851792;13515.324233788468;39555.60444395519;41195.1760964785;41487;41568.376474353514;41582.79208603938;71532.28467715257;73077.6346118266;76251.61874190815;77049.61475192588;77660;17572.12139393051;19985.700214497087;20178;20185.59628807445;20433.386998390073;20008;20114.39656810298;20356.389308320773;20398.082086305854;22873.771262287166;19230.519237018627;19632.60734785325;20069.698954515403;20072.397828065183;20450.181992720125;19519.51201219924;20955.580888381843;21113.366599002056;21304.786952130282;21767.020484077937;19508.02459876972;19546.90226548858;19642.90178549098;20813.895930520855;20961.58076838485;13747.381367838272;14238.715225695636;14340.139591624536;14448.855511444752;14507.78238326447;15201.087934723952;15240.618984525476;15244;15414;15556.844431555539 14:50:41 INFO - PID 3527 | __end_tp_report 14:50:41 INFO - PID 3527 | __start_cc_report 14:50:41 INFO - PID 3527 | _x_x_mozilla_cycle_collect,396 14:50:41 INFO - PID 3527 | __end_cc_report 14:50:41 INFO - PID 3527 | __startTimestamp1502920241217__endTimestamp 14:50:41 INFO - PID 3527 | 14:50:41 INFO - PID 3527 | ------- Summary: start ------- 14:50:41 INFO - PID 3527 | Number of tests: 6 14:50:41 INFO - PID 3527 | 14:50:41 INFO - PID 3527 | [#0] dojo.html Cycles:165 Average:6093.34 Median:6348.40 stddev:706.58 (11.1%) stddev-sans-first:669.96 14:50:41 INFO - PID 3527 | Values: 3150.1 3179.8 3185.4 3192.8 3207.9 6059.9 6082.5 6144.7 6194.1 6240.0 6055.7 6091.7 6105.3 6134.8 6180.6 5550.1 5613.3 5618.1 5623.7 5641.1 5660.3 5714.3 5728.3 5740.9 5780.9 5661.7 5675.3 5684.9 5725.1 5728.4 5453.1 5508.6 5519.3 5523.8 5588.3 5571.9 5612.9 5648.0 5656.6 5657.9 5968.3 6101.8 6112.1 6144.4 6194.4 6391.5 6409.0 6434.4 6519.5 6520.2 6493.4 6496.0 6526.1 6557.0 6561.1 6035.6 6041.2 6155.9 6158.0 6223.5 6752.0 6803.7 6820.7 6840.4 6897.0 6494.8 6500.2 6567.0 6592.8 6600.7 6522.2 6542.7 6591.5 6593.1 6744.7 6587.3 6616.9 6618.3 6619.0 6630.5 6574.3 6662.9 6672.1 6672.6 6708.6 5341.1 5364.5 5411.6 5412.7 5413.7 4501.2 4597.7 4622.6 4721.0 4742.7 5764.1 5813.0 5813.6 5820.8 5841.5 6543.1 6652.6 6691.6 6719.7 6740.8 5590.5 5749.9 5824.7 5832.0 5843.4 5784.5 5884.3 5910.9 5921.5 5932.2 6522.2 6552.3 6560.2 6560.5 6585.0 6619.4 6630.2 6634.7 6703.6 6729.1 6211.8 6298.0 6427.6 6449.3 6542.9 6400.0 6456.9 6457.2 6474.6 6484.4 6589.0 6621.0 6627.2 6640.6 6647.0 6473.2 6499.9 6501.1 6518.7 6520.9 6360.3 6367.1 6433.6 6476.9 6527.4 6206.9 6322.8 6343.4 6345.9 6384.0 6511.7 6526.7 6575.8 6577.6 6587.7 6344.0 6348.4 6349.3 6353.6 6354.9 14:50:41 INFO - PID 3527 | 14:50:41 INFO - PID 3527 | [#1] ext.html Cycles:180 Average:39242.99 Median:19505.53 stddev:59172.26 (303.4%) stddev-sans-first:59267.63 14:50:41 INFO - PID 3527 | Values: 738.5 789.1 806.4 811.5 1156.6 30268.2 30641.1 40936.2 41629.4 41924.4 41332.4 42036.2 42143.8 42174.8 42218.8 7485.6 7680.7 7684.8 7685.7 7687.6 7565.7 7687.0 7693.3 7693.3 7696.6 7649.4 7729.5 7731.7 7735.9 7737.1 7657.9 7683.9 7687.8 7689.8 7695.2 7466.5 7519.0 7530.4 7531.1 7533.6 38187.2 38369.2 38402.2 38451.2 38506.0 3515.0 3550.4 3555.1 3560.1 3565.3 28968.1 29141.3 29209.6 29218.7 29239.9 5096.2 5103.9 5133.3 5158.6 5190.1 192052.0 192731.0 193331.0 194019.0 194246.0 47848.0 48120.3 48224.0 49572.8 49846.0 51847.7 51867.2 51929.5 52053.0 52123.5 14783.3 15020.6 15022.2 15025.8 16611.2 11858.8 11878.2 11897.0 11920.5 11940.3 14024.6 14030.8 14053.5 14080.6 14152.1 5592.9 5628.0 5644.2 5730.8 5807.4 974.1 996.8 1004.4 1010.5 1020.1 21906.1 22100.0 22114.6 22239.0 22252.0 1067.3 1082.4 1087.2 1122.5 1123.9 12346.9 12444.9 12466.4 12543.4 12544.6 261240.0 262738.0 262763.0 262884.0 262936.0 51922.5 52063.0 52162.0 52361.7 52483.0 1433.5 1446.9 1455.2 1462.0 1468.5 22696.0 22743.2 22785.5 23021.9 23195.8 38342.0 38567.2 38662.4 49716.8 52716.2 219344.0 221444.0 221530.0 221697.0 221844.0 36041.5 36262.8 36477.0 36497.0 36513.8 43427.0 44591.1 44875.1 44942.8 45063.5 32355.9 32758.8 33254.7 33400.3 33660.7 32411.4 32608.3 32610.3 32644.3 33112.2 35024.6 35070.5 35229.8 35460.6 35575.5 30983.4 31412.0 31491.0 31597.0 31854.7 22263.6 23466.3 23764.3 23874.8 24206.9 14:50:41 INFO - PID 3527 | 14:50:41 INFO - PID 3527 | [#2] jquery.html Cycles:180 Average:36574.69 Median:21857.17 stddev:84974.17 (388.8%) stddev-sans-first:85169.52 14:50:41 INFO - PID 3527 | Values: 561.2 568.9 575.4 578.3 591.8 22236.9 22522.8 22996.9 23118.3 23139.8 25515.6 26002.9 26028.7 26067.5 26126.3 6333.7 6484.7 6488.4 6492.1 6494.9 6295.0 6347.9 6395.1 6974.1 7001.3 6991.5 6992.2 6995.8 6999.7 7003.4 6932.4 6984.7 6992.6 6996.1 6998.7 6854.0 6868.7 6869.1 6880.4 6883.7 30062.7 30494.1 31240.0 31762.8 31961.2 1909.4 1951.0 1953.9 1956.5 1959.9 19964.4 20668.6 21111.3 21125.2 21144.3 2475.5 2492.4 2569.1 2575.5 2576.0 143325.0 147802.0 148320.3 148374.5 148627.3 20449.5 21735.6 21978.8 22209.6 22468.3 44326.3 44398.8 44432.6 44483.3 44565.3 11942.8 12177.9 12308.3 12330.3 12398.6 10244.2 10280.8 10319.0 10408.9 10436.2 9114.6 10859.4 11505.3 11793.6 12081.3 3332.9 3390.6 3585.4 3832.6 3930.4 947.0 964.3 965.4 969.4 988.7 19361.1 19385.3 19399.8 19623.3 20252.9 1039.8 1043.3 1051.4 1054.5 1058.8 10985.5 10995.0 11057.2 11108.6 11199.4 486386.0 497171.0 504571.0 505079.0 531632.0 114175.4 114647.0 114709.0 114728.0 115600.4 1176.7 1198.8 1223.6 1226.5 1232.8 17465.7 17498.7 17627.0 17950.5 18063.5 53152.2 53624.2 54352.2 54571.0 55252.0 64341.0 64991.7 69044.0 69155.3 70039.3 19721.2 20076.7 20080.8 20360.8 20374.4 22548.4 22577.8 22651.7 22764.2 22955.5 18192.0 18232.4 18369.0 18439.0 18481.7 15155.5 15387.8 15546.6 15833.2 16070.0 18176.0 18201.5 18559.5 18641.1 18910.3 19697.5 19702.7 19789.6 19791.5 19803.1 18081.8 18188.3 18233.9 18287.4 18318.8 14:50:41 INFO - PID 3527 | 14:50:41 INFO - PID 3527 | [#3] mootools.html Cycles:180 Average:8974.60 Median:2612.55 stddev:6229.88 (238.5%) stddev-sans-first:6221.02 14:50:41 INFO - PID 3527 | Values: 1350.8 1402.9 1425.1 1431.7 1438.5 14217.5 14540.6 14829.9 14840.6 14853.6 15168.9 15396.8 15414.7 15430.7 15431.5 2688.4 2728.0 2731.7 2732.1 2732.9 2641.1 2676.5 2709.3 2713.5 2714.6 2645.8 2693.4 2704.9 2757.8 2763.5 2675.0 2675.7 2677.7 2687.3 2697.7 2592.6 2594.4 2607.3 2611.1 2614.0 14016.0 14389.8 14395.1 14398.8 14403.3 7464.6 7729.0 7730.5 7734.8 7739.1 4994.7 5156.7 5171.3 5258.7 5276.6 1823.7 1827.1 1834.4 1859.1 1881.9 23502.4 23839.0 24119.9 24146.3 24821.0 6315.2 6325.7 6340.7 6487.9 6503.7 15618.4 15636.5 15640.7 15653.6 15671.3 7501.6 7601.3 7625.2 7626.7 7632.4 6911.4 6917.0 6971.3 7025.0 7106.9 4745.5 4914.2 4936.0 4937.1 4940.4 1429.5 1458.5 1466.7 1485.7 1494.8 875.4 892.9 905.5 909.1 914.4 7097.6 8732.3 9063.8 9077.7 9150.3 974.5 982.2 1014.1 1019.4 1021.7 4778.9 4975.3 4999.9 5072.8 5115.1 19498.7 19956.4 19983.7 20158.0 20175.2 19463.5 20043.8 20046.9 20082.0 20098.2 9779.2 10175.2 11112.5 11208.3 11249.3 7436.9 8473.6 8515.2 8719.7 8933.6 17421.0 17549.1 17746.4 17911.8 18181.0 17439.4 18020.6 18034.0 18123.7 18436.6 13128.6 13510.3 13513.2 13517.2 13547.2 13309.8 13510.5 13626.3 13634.6 13876.9 11084.8 11127.2 11142.8 11248.8 11268.9 10581.6 10593.1 10639.9 10681.6 10904.7 8960.8 9087.0 9094.2 9106.0 9107.2 6778.3 6805.7 6824.0 6838.6 6873.0 7009.5 7031.8 7055.0 7083.7 7104.1 14:50:41 INFO - PID 3527 | 14:50:41 INFO - PID 3527 | [#4] prototype.html Cycles:180 Average:5557.55 Median:6146.79 stddev:1542.28 (25.1%) stddev-sans-first:1498.88 14:50:41 INFO - PID 3527 | Values: 485.6 494.7 509.2 512.8 518.9 6240.4 6241.6 6293.1 6302.6 6304.8 5829.8 6009.7 6019.5 6034.5 6167.6 5100.4 5137.5 5146.4 5154.8 5166.0 5149.0 5155.3 5160.5 5175.4 5176.2 4793.6 4950.7 4976.8 4984.2 4999.9 4943.6 4944.1 4973.7 4974.7 5010.2 4439.5 4466.6 4477.1 4484.6 4497.7 6084.5 6141.9 6151.7 6154.7 6168.4 5697.5 5715.8 5740.3 5749.5 5751.8 6488.1 6546.1 6574.6 6613.9 6631.4 4833.3 4886.7 4893.2 4897.5 4915.2 6909.3 6979.2 6981.6 6983.6 7006.3 5029.3 5031.7 5047.4 5077.8 5081.4 5068.4 5106.2 5109.7 5121.8 5126.5 5689.3 5713.0 5715.5 5733.6 5753.9 6540.5 6592.4 6597.8 6661.4 6673.2 1667.5 1705.4 1736.9 1779.7 1883.3 1426.5 1442.6 1449.8 1452.2 1452.5 5670.4 5678.3 5738.5 5748.1 5760.9 6617.0 6667.4 6721.7 6755.3 6814.4 5846.8 5857.9 5870.9 5896.5 5896.9 3593.5 3621.6 3623.5 3635.0 3637.3 6600.3 6633.8 6646.2 6686.3 6698.1 6963.8 7002.2 7003.3 7004.4 7025.8 6579.9 6618.3 6626.0 6642.8 6652.5 6758.9 6770.5 6799.4 6803.7 6804.9 6688.7 6697.2 6751.5 6778.5 6781.4 6524.4 6559.5 6582.3 6597.4 6655.8 4971.8 5056.3 5063.1 5078.5 5089.1 6779.5 6779.8 6798.5 6862.7 6876.0 6552.7 6580.9 6609.3 6631.8 6636.8 6422.6 6458.3 6543.2 6546.9 6552.9 6284.7 6331.4 6341.5 6389.3 6437.8 6486.5 6557.7 6627.8 6656.6 6668.9 6309.5 6330.8 6347.6 6355.8 6385.0 14:50:41 INFO - PID 3527 | 14:50:41 INFO - PID 3527 | [#5] yui.html Cycles:180 Average:24871.71 Median:20634.88 stddev:33343.38 (161.6%) stddev-sans-first:33388.23 14:50:41 INFO - PID 3527 | Values: 877.3 888.3 904.3 909.7 923.2 17978.7 18757.5 18821.2 18844.0 22375.2 20895.3 22257.2 22291.0 22325.4 22388.9 1021.7 1060.1 1061.1 1065.8 1068.6 1008.2 1057.8 1060.4 1068.1 1069.2 1105.4 1111.4 1112.1 1115.2 1117.7 1110.9 1115.7 1115.8 1116.1 1117.0 813.1 813.2 813.9 814.1 814.6 20447.2 20493.5 20776.3 20777.1 20900.5 21715.0 21812.2 21902.6 22059.8 22167.0 16842.7 17043.1 17147.8 17206.3 17227.1 3596.1 3622.9 3626.2 3626.8 3627.2 123318.4 128657.4 128896.4 129107.0 129754.4 17086.1 17582.3 17672.6 17734.1 18269.6 31569.4 31604.1 31615.8 31766.7 32182.5 7677.9 8102.2 8406.6 8667.0 9123.4 4329.2 4441.3 4441.4 4455.6 4459.2 5257.5 5322.9 5324.0 5340.2 5364.6 1036.8 1047.1 1058.4 1059.1 1063.2 667.4 686.9 688.7 702.9 708.3 11637.4 11732.3 12099.3 12347.5 12782.9 665.3 672.5 676.3 677.8 691.8 5739.4 6089.8 6092.7 6130.8 6161.2 113721.4 115464.4 117413.4 117453.4 119698.0 97541.0 98791.5 99620.5 101380.5 101870.5 90031.0 90775.0 91553.0 91558.0 93542.0 12860.5 13390.9 13408.4 13477.2 13515.3 39555.6 41195.2 41487.0 41568.4 41582.8 71532.3 73077.6 76251.6 77049.6 77660.0 17572.1 19985.7 20178.0 20185.6 20433.4 20008.0 20114.4 20356.4 20398.1 22873.8 19230.5 19632.6 20069.7 20072.4 20450.2 19519.5 20955.6 21113.4 21304.8 21767.0 19508.0 19546.9 19642.9 20813.9 20961.6 13747.4 14238.7 14340.1 14448.9 14507.8 15201.1 15240.6 15244.0 15414.0 15556.8 14:50:41 INFO - PID 3527 | -------- Summary: end -------- 14:50:41 INFO - PID 3527 | 14:50:41 INFO - TEST-INFO | 3527: exit 0 14:50:42 INFO - TEST-OK | dromaeo_css | took 1114351ms 14:50:42 INFO - TEST-START | kraken 14:50:42 INFO - Initialising browser for kraken test... 14:50:42 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:41969/getInfo.html -profile /tmp/tmptPqbrQ/profile 14:50:42 INFO - TEST-INFO | started process 3885 (/builds/slave/test/build/application/firefox/firefox http://localhost:41969/getInfo.html) 14:50:48 INFO - TEST-INFO | 3885: exit 0 14:50:48 INFO - Browser initialized. 14:50:48 INFO - Running cycle 1/1 for kraken test... 14:50:48 INFO - TEST-INFO | started process 4096 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmptPqbrQ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 14:50:49 INFO - PID 4096 | 14:50:49 INFO - PID 4096 | (/builds/slave/test/build/application/firefox/firefox:4144): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 14:50:49 INFO - PID 4096 | 14:50:59 INFO - PID 4096 | RSS: Main: 165474304 14:50:59 INFO - PID 4096 | 14:51:02 INFO - PID 4096 | Cycle 1(1): loaded http://localhost:41969/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:41969/tests/kraken/driver.html?testName=audio-beat-detection) 14:51:03 INFO - PID 4096 | RSS: Main: 171720704 14:51:03 INFO - PID 4096 | 14:51:06 INFO - PID 4096 | Cycle 1(1): loaded http://localhost:41969/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:41969/tests/kraken/driver.html?testName=audio-dft) 14:51:06 INFO - PID 4096 | RSS: Main: 175738880 14:51:06 INFO - PID 4096 | 14:51:09 INFO - PID 4096 | Cycle 1(1): loaded http://localhost:41969/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:41969/tests/kraken/driver.html?testName=audio-fft) 14:51:10 INFO - PID 4096 | RSS: Main: 169086976 14:51:10 INFO - PID 4096 | 14:51:12 INFO - PID 4096 | Cycle 1(1): loaded http://localhost:41969/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:41969/tests/kraken/driver.html?testName=audio-oscillator) 14:51:13 INFO - PID 4096 | RSS: Main: 169414656 14:51:13 INFO - PID 4096 | 14:51:15 INFO - PID 4096 | Cycle 1(1): loaded http://localhost:41969/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:41969/tests/kraken/driver.html?testName=imaging-gaussian-blur) 14:51:15 INFO - PID 4096 | RSS: Main: 169951232 14:51:15 INFO - PID 4096 | 14:51:18 INFO - PID 4096 | Cycle 1(1): loaded http://localhost:41969/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:41969/tests/kraken/driver.html?testName=imaging-darkroom) 14:51:19 INFO - PID 4096 | RSS: Main: 171376640 14:51:19 INFO - PID 4096 | 14:51:22 INFO - PID 4096 | Cycle 1(1): loaded http://localhost:41969/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:41969/tests/kraken/driver.html?testName=imaging-desaturate) 14:51:23 INFO - PID 4096 | RSS: Main: 173768704 14:51:23 INFO - PID 4096 | 14:51:26 INFO - PID 4096 | Cycle 1(1): loaded http://localhost:41969/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:41969/tests/kraken/driver.html?testName=json-parse-financial) 14:51:26 INFO - PID 4096 | RSS: Main: 172666880 14:51:26 INFO - PID 4096 | 14:51:28 INFO - PID 4096 | Cycle 1(1): loaded http://localhost:41969/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:41969/tests/kraken/driver.html?testName=json-stringify-tinderbox) 14:51:29 INFO - PID 4096 | RSS: Main: 172363776 14:51:29 INFO - PID 4096 | 14:51:30 INFO - PID 4096 | Cycle 1(1): loaded http://localhost:41969/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:41969/tests/kraken/driver.html?testName=stanford-crypto-aes) 14:51:31 INFO - PID 4096 | RSS: Main: 172249088 14:51:31 INFO - PID 4096 | 14:51:33 INFO - PID 4096 | Cycle 1(1): loaded http://localhost:41969/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:41969/tests/kraken/driver.html?testName=stanford-crypto-ccm) 14:51:34 INFO - PID 4096 | RSS: Main: 172494848 14:51:34 INFO - PID 4096 | 14:51:36 INFO - PID 4096 | Cycle 1(1): loaded http://localhost:41969/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:41969/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 14:51:37 INFO - PID 4096 | RSS: Main: 172625920 14:51:37 INFO - PID 4096 | 14:51:40 INFO - PID 4096 | Cycle 1(1): loaded http://localhost:41969/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:41969/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 14:51:41 INFO - PID 4096 | RSS: Main: 172732416 14:51:41 INFO - PID 4096 | 14:51:43 INFO - PID 4096 | Cycle 1(1): loaded http://localhost:41969/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:41969/tests/kraken/driver.html?testName=ai-astar) 14:51:43 INFO - PID 4096 | RSS: Main: 173887488 14:51:43 INFO - PID 4096 | 14:51:43 INFO - PID 4096 | __start_tp_report 14:51:43 INFO - PID 4096 | _x_x_mozilla_page_load 14:51:43 INFO - PID 4096 | _x_x_mozilla_page_load_details 14:51:43 INFO - PID 4096 | |i|pagename|runs| 14:51:43 INFO - PID 4096 | |0;ai-astar;99;107;100;108;109;110;110;99;108;109 14:51:43 INFO - PID 4096 | |1;audio-beat-detection;140;117;143;186;118;141;147;118;181;120 14:51:43 INFO - PID 4096 | |2;audio-dft;163;162;165;161;163;163;163;166;164;163 14:51:43 INFO - PID 4096 | |3;audio-fft;76;77;77;184;84;77;75;74;167;77 14:51:43 INFO - PID 4096 | |4;audio-oscillator;80;80;136;78;79;79;126;79;79;128 14:51:43 INFO - PID 4096 | |5;imaging-gaussian-blur;115;114;114;114;114;114;116;114;113;114 14:51:43 INFO - PID 4096 | |6;imaging-darkroom;150;150;150;158;149;151;151;149;158;150 14:51:43 INFO - PID 4096 | |7;imaging-desaturate;99;98;93;94;93;94;94;93;94;96 14:51:43 INFO - PID 4096 | |8;json-parse-financial;54;54;55;55;56;54;58;54;54;54 14:51:43 INFO - PID 4096 | |9;json-stringify-tinderbox;55;54;54;55;54;54;54;54;54;54 14:51:43 INFO - PID 4096 | |10;stanford-crypto-aes;78;79;78;76;78;76;76;77;77;79 14:51:43 INFO - PID 4096 | |11;stanford-crypto-ccm;126;123;127;123;122;125;129;119;122;164 14:51:43 INFO - PID 4096 | |12;stanford-crypto-pbkdf2;177;181;181;179;180;178;180;185;182;181 14:51:43 INFO - PID 4096 | |13;stanford-crypto-sha256-iterative;68;83;71;74;69;74;69;67;69;68 14:51:43 INFO - PID 4096 | __end_tp_report 14:51:43 INFO - PID 4096 | __start_cc_report 14:51:43 INFO - PID 4096 | _x_x_mozilla_cycle_collect,991 14:51:43 INFO - PID 4096 | __end_cc_report 14:51:43 INFO - PID 4096 | __startTimestamp1502920303497__endTimestamp 14:51:43 INFO - PID 4096 | 14:51:43 INFO - PID 4096 | ------- Summary: start ------- 14:51:43 INFO - PID 4096 | Number of tests: 14 14:51:43 INFO - PID 4096 | 14:51:43 INFO - PID 4096 | [#0] ai-astar Cycles:10 Average:105.90 Median:109.50 stddev:4.63 (4.2%) stddev-sans-first:4.18 14:51:43 INFO - PID 4096 | Values: 99.0 107.0 100.0 108.0 109.0 110.0 110.0 99.0 108.0 109.0 14:51:43 INFO - PID 4096 | 14:51:43 INFO - PID 4096 | [#1] audio-beat-detection Cycles:10 Average:141.10 Median:142.00 stddev:25.26 (17.8%) stddev-sans-first:26.79 14:51:43 INFO - PID 4096 | Values: 140.0 117.0 143.0 186.0 118.0 141.0 147.0 118.0 181.0 120.0 14:51:43 INFO - PID 4096 | 14:51:43 INFO - PID 4096 | [#2] audio-dft Cycles:10 Average:163.30 Median:163.00 stddev:1.42 (0.9%) stddev-sans-first:1.50 14:51:43 INFO - PID 4096 | Values: 163.0 162.0 165.0 161.0 163.0 163.0 163.0 166.0 164.0 163.0 14:51:43 INFO - PID 4096 | 14:51:43 INFO - PID 4096 | [#3] audio-fft Cycles:10 Average:96.80 Median:77.00 stddev:41.76 (54.2%) stddev-sans-first:43.60 14:51:43 INFO - PID 4096 | Values: 76.0 77.0 77.0 184.0 84.0 77.0 75.0 74.0 167.0 77.0 14:51:43 INFO - PID 4096 | 14:51:43 INFO - PID 4096 | [#4] audio-oscillator Cycles:10 Average:94.40 Median:79.00 stddev:24.70 (31.3%) stddev-sans-first:25.64 14:51:43 INFO - PID 4096 | Values: 80.0 80.0 136.0 78.0 79.0 79.0 126.0 79.0 79.0 128.0 14:51:43 INFO - PID 4096 | 14:51:43 INFO - PID 4096 | [#5] imaging-gaussian-blur Cycles:10 Average:114.20 Median:114.00 stddev:0.79 (0.7%) stddev-sans-first:0.78 14:51:43 INFO - PID 4096 | Values: 115.0 114.0 114.0 114.0 114.0 114.0 116.0 114.0 113.0 114.0 14:51:43 INFO - PID 4096 | 14:51:43 INFO - PID 4096 | [#6] imaging-darkroom Cycles:10 Average:151.60 Median:150.50 stddev:3.44 (2.3%) stddev-sans-first:3.60 14:51:43 INFO - PID 4096 | Values: 150.0 150.0 150.0 158.0 149.0 151.0 151.0 149.0 158.0 150.0 14:51:43 INFO - PID 4096 | 14:51:43 INFO - PID 4096 | [#7] imaging-desaturate Cycles:10 Average:94.80 Median:94.00 stddev:2.15 (2.3%) stddev-sans-first:1.66 14:51:43 INFO - PID 4096 | Values: 99.0 98.0 93.0 94.0 93.0 94.0 94.0 93.0 94.0 96.0 14:51:43 INFO - PID 4096 | 14:51:43 INFO - PID 4096 | [#8] json-parse-financial Cycles:10 Average:54.80 Median:54.50 stddev:1.32 (2.4%) stddev-sans-first:1.36 14:51:43 INFO - PID 4096 | Values: 54.0 54.0 55.0 55.0 56.0 54.0 58.0 54.0 54.0 54.0 14:51:43 INFO - PID 4096 | 14:51:43 INFO - PID 4096 | [#9] json-stringify-tinderbox Cycles:10 Average:54.20 Median:54.00 stddev:0.42 (0.8%) stddev-sans-first:0.33 14:51:43 INFO - PID 4096 | Values: 55.0 54.0 54.0 55.0 54.0 54.0 54.0 54.0 54.0 54.0 14:51:43 INFO - PID 4096 | 14:51:43 INFO - PID 4096 | [#10] stanford-crypto-aes Cycles:10 Average:77.40 Median:78.00 stddev:1.17 (1.5%) stddev-sans-first:1.22 14:51:43 INFO - PID 4096 | Values: 78.0 79.0 78.0 76.0 78.0 76.0 76.0 77.0 77.0 79.0 14:51:43 INFO - PID 4096 | 14:51:43 INFO - PID 4096 | [#11] stanford-crypto-ccm Cycles:10 Average:128.00 Median:125.50 stddev:12.97 (10.3%) stddev-sans-first:13.74 14:51:43 INFO - PID 4096 | Values: 126.0 123.0 127.0 123.0 122.0 125.0 129.0 119.0 122.0 164.0 14:51:43 INFO - PID 4096 | 14:51:43 INFO - PID 4096 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:180.40 Median:181.00 stddev:2.22 (1.2%) stddev-sans-first:1.99 14:51:43 INFO - PID 4096 | Values: 177.0 181.0 181.0 179.0 180.0 178.0 180.0 185.0 182.0 181.0 14:51:43 INFO - PID 4096 | 14:51:43 INFO - PID 4096 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.20 Median:70.00 stddev:4.80 (6.9%) stddev-sans-first:4.95 14:51:43 INFO - PID 4096 | Values: 68.0 83.0 71.0 74.0 69.0 74.0 69.0 67.0 69.0 68.0 14:51:43 INFO - PID 4096 | -------- Summary: end -------- 14:51:43 INFO - PID 4096 | 14:51:43 INFO - PID 4096 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 14:51:44 INFO - TEST-INFO | 4096: exit 0 14:51:44 INFO - TEST-OK | kraken | took 62296ms 14:51:44 INFO - SUITE-END | took 1176s 14:51:44 INFO - Completed test suite (00:19:37) 14:51:44 INFO - javascript benchmark 14:51:44 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8676.70109813785, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3150.070729134905, 3179.793313434627, 3185.378851124031, 3192.808431494112, 3207.9198020049494, 6059.878802423955, 6082.543809214305, 6144.723487443064, 6194.070889366597, 6239.9688001559925, 6055.6972151392365, 6091.7258723357445, 6105.267367915845, 6134.785282515113, 6180.567360284771, 5550.111982082867, 5613.298337707786, 5618.12918997554, 5623.662580245178, 5641.069223578125, 5660.29246344207, 5714.25714657094, 5728.312602487707, 5740.913886291688, 5780.942190578083, 5661.688607126606, 5675.290588676416, 5684.943150568482, 5725.14122881569, 5728.427157284259, 5453.100238460649, 5508.586855985817, 5519.254900588449, 5523.751431185607, 5588.273524441837, 5571.888562228733, 5612.887742245173, 5648.0, 5656.632318899258, 5657.886842263173, 5968.343482216953, 6101.786437474711, 6112.083187521863, 6144.38556144382, 6194.380561943814, 6391.488680905516, 6408.967955160241, 6434.356564343529, 6519.478441724651, 6520.152380190592, 6493.4155925966415, 6496.0, 6526.053722210253, 6557.0, 6561.0814485972005, 6035.637861728266, 6041.24484439445, 6155.87688246237, 6158.0, 6223.533235007392, 6751.966240168767, 6803.65981700913, 6820.727170913207, 6840.384365407137, 6897.034415181878, 6494.772682956074, 6500.187476565429, 6567.047778072153, 6592.835179120464, 6600.669966501656, 6522.217333919865, 6542.705578248991, 6591.505637077238, 6593.142891424084, 6744.6627668616375, 6587.308332625101, 6616.900746488806, 6618.271990081087, 6619.040239165294, 6630.469562434993, 6574.276829548746, 6662.900056499157, 6672.132622759107, 6672.599644021374, 6708.563943343667, 5341.0653135701095, 5364.544013758796, 5411.621186516906, 5412.675239485565, 5413.702246376487, 4501.18978583858, 4597.7241365518175, 4622.560856718606, 4721.008588196517, 4742.739149346819, 5764.135379692996, 5813.040848260074, 5813.593048486566, 5820.82537523875, 5841.474267315877, 6543.083968244449, 6652.567583107082, 6691.5650482718465, 6719.697613607399, 6740.83147921296, 5590.5248053915875, 5749.88500229993, 5824.737886795105, 5832.037713777179, 5843.444872737168, 5784.537237021028, 5884.293884733774, 5910.8817823643285, 5921.526277897758, 5932.169496270526, 6522.184726909137, 6552.344765523505, 6560.179977502812, 6560.507961902877, 6585.045168450548, 6619.404253617197, 6630.171228596426, 6634.734610615521, 6703.564268322543, 6729.057931889637, 6211.844703882439, 6298.0, 6427.614343139427, 6449.322821103904, 6542.869142617216, 6400.0, 6456.903146452901, 6457.160569126171, 6474.5791523550815, 6484.351564843667, 6589.044588534732, 6620.966895165493, 6627.204735431586, 6640.601563906181, 6647.036179753812, 6473.190851143607, 6499.902501462577, 6501.057346684799, 6518.696260747774, 6520.869582608416, 6360.268569114426, 6367.108604795425, 6433.581817181868, 6476.870462590816, 6527.379898909596, 6206.875862482635, 6322.778702745381, 6343.397377249154, 6345.873082538231, 6384.042393640991, 6511.674416279262, 6526.738930442729, 6575.835604109744, 6577.572457790228, 6587.736490540517, 6344.048392741126, 6348.396902294275, 6349.269833968968, 6353.555251132561, 6354.872902542016], "value": 6041.645072680825, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [738.47937204271, 789.0531362365161, 806.3589446390121, 811.4928169893813, 1156.58941075918, 30268.243293917636, 30641.08076757698, 40936.18127637449, 41629.375559366556, 41924.37113443301, 41332.38001429981, 42036.15927681445, 42143.78928105363, 42174.789126054406, 42218.7889060555, 7485.550866947973, 7680.692772289101, 7684.769456916273, 7685.692572297102, 7687.6156192190165, 7565.659545320446, 7687.0, 7693.269139431745, 7693.30760231579, 7696.576688282143, 7649.38804895607, 7729.458937874351, 7731.652075656609, 7735.922640773577, 7737.148913619498, 7657.923420765805, 7683.884741728879, 7687.769366919001, 7689.807754806118, 7695.230476952316, 7466.514676546007, 7519.0, 7530.43521735872, 7531.096268447766, 7533.585652789095, 38187.23625527502, 38369.23261534781, 38402.231955361014, 38451.23097538062, 38506.0, 3514.964850351515, 3550.4141816600486, 3555.1289933966086, 3560.092176494977, 3565.3404120237838, 28968.130956071353, 29141.271468213254, 29209.561856572167, 29218.70781292181, 29239.85380073086, 5096.159133742928, 5103.87240318995, 5133.281340612317, 5158.561522270612, 5190.06578815813, 192052.0, 192731.0, 193331.0, 194019.0, 194246.0, 47848.0, 48120.27819582709, 48224.03551929012, 49572.75213623909, 49846.0, 51847.74076129671, 51867.2219916694, 51929.48070519322, 52053.0, 52123.47876521186, 14783.260836958325, 15020.624484387978, 15022.24888755558, 15025.849741502663, 16611.169441527876, 11858.822117668242, 11878.227915185485, 11897.0, 11920.52317907274, 11940.283582985048, 14024.579262622137, 14030.789538156936, 14053.508127215702, 14080.647983800282, 14152.080114792505, 5592.888142237214, 5627.971860140673, 5644.238027866186, 5730.77076916927, 5807.448292412214, 974.1038244814788, 996.835522138839, 1004.3572113847143, 1010.4990149380371, 1020.132887046005, 21906.12375504962, 22100.0, 22114.557708845732, 22239.0, 22251.998660060337, 1067.2955849139687, 1082.3884505254664, 1087.217203613397, 1122.4892673833385, 1123.8538989931258, 12346.938265308616, 12444.937775311066, 12466.439010244561, 12543.435545400296, 12544.623661290176, 261240.0, 262738.0, 262763.0, 262884.0, 262936.0, 51922.48077519176, 52063.0, 52162.0, 52361.7381913088, 52483.0, 1433.5412667946366, 1446.9348879300458, 1455.2069122328357, 1461.978070328946, 1468.4713503138862, 22696.0, 22743.203987860346, 22785.544289114456, 23021.88489057611, 23195.76804231936, 38342.0, 38567.228655426174, 38662.420063698504, 49716.75141624269, 52716.20925686195, 219344.0, 221444.0, 221530.0, 221697.0, 221844.0, 36041.459378108826, 36262.8186859064, 36477.0, 36497.0, 36513.81743091268, 43427.0, 44591.10817783691, 44875.10249794921, 44942.77528612336, 45063.54936450725, 32355.926187012195, 32758.836205818818, 33254.667453326125, 33400.33199336048, 33660.66339336575, 32411.351772963935, 32608.34783304368, 32610.347793044482, 32644.34711305808, 33112.1721956953, 35024.64975350214, 35070.47394289036, 35229.82385088058, 35460.64539354573, 35575.46636800398, 30983.380332393677, 31412.0, 31491.0, 31597.0, 31854.68145318517, 22263.643677759, 23466.296011119695, 23764.287071387887, 23874.761252387252, 24206.878965605058], "value": 17214.16962934643, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [561.2114978455272, 568.874847533543, 575.4159528079, 578.3175852494055, 591.7810410148245, 22236.88881555594, 22522.774772252294, 22996.88501557494, 23118.306450806504, 23139.768602313972, 25515.61726574098, 26002.869985650093, 26028.739712602917, 26067.478650427027, 26126.34684132893, 6333.651649159295, 6484.740610375603, 6488.383603557654, 6492.05865149553, 6494.902576461357, 6294.968525157367, 6347.904781428282, 6395.104685344032, 6974.128233970754, 7001.299870012984, 6991.545549539263, 6992.230854605989, 6995.755148569801, 6999.720011199572, 7003.369696727294, 6932.410745086674, 6984.685689144001, 6992.58044517328, 6996.090508233948, 6998.68505917235, 6854.0, 6868.7252509899545, 6869.107016087876, 6880.449564034865, 6883.724651013953, 30062.69937300621, 30494.08517744449, 31240.0, 31762.841185793925, 31961.200969975704, 1909.3699079303797, 1951.0049874563933, 1953.8632295739303, 1956.5010922214885, 1959.8824070555813, 19964.401067967985, 20668.58662826735, 21111.261105861373, 21125.154993800228, 21144.2599509018, 2475.492524032569, 2492.439201179738, 2569.075132952136, 2575.549278876208, 2576.0, 143325.0, 147802.0, 148320.25839870732, 148374.51625483716, 148627.25686371606, 20449.48876278075, 21735.565288694455, 21978.780212197995, 22209.55580888373, 22468.32595022152, 44326.33510497345, 44398.778006110406, 44432.55567444349, 44483.332750008776, 44565.331520027226, 11942.820857687142, 12177.939110304393, 12308.26150430977, 12330.321832299309, 12398.62804115878, 10244.180465562886, 10280.845787313196, 10319.0, 10408.947955260175, 10436.165106791439, 9114.589843456928, 10859.425383319374, 11505.309681419141, 11793.646190614296, 12081.335526545949, 3332.900012999614, 3390.5761779777526, 3585.3904836177912, 3832.559255685576, 3930.4104384342004, 946.9952650236705, 964.2575217082807, 965.4496936746128, 969.4280374579032, 988.6786794291884, 19361.12874920632, 19385.321513746952, 19399.806001940084, 19623.31318403849, 20252.898735506522, 1039.7868436970402, 1043.3426941027255, 1051.4059556350637, 1054.5254635413942, 1058.8358804385332, 10985.450727463594, 10995.0, 11057.225994180491, 11108.555657773599, 11199.384033878052, 486386.0, 497171.0, 504571.0, 505079.0, 531632.0, 114175.42912285385, 114647.0, 114709.0, 114728.0, 115600.42199788947, 1176.6528873982195, 1198.7602479504135, 1223.577865636354, 1226.5461779141597, 1232.796588562895, 17465.738013930055, 17498.650026999643, 17627.03051332189, 17950.461486155436, 18063.458096257134, 53152.20271696005, 53624.19563706625, 54352.18471722848, 54571.0, 55252.0, 64341.0, 64991.67504162449, 69044.0, 69155.30844691489, 70039.29960700328, 19721.211151553776, 20076.69884951756, 20080.799192007893, 20360.79639203589, 20374.388768336972, 22548.436289092904, 22577.774222258224, 22651.660225096308, 22764.20325288607, 22955.54088918179, 18192.0, 18232.36186733458, 18369.0, 18439.0, 18481.72277415813, 15155.469558565397, 15387.846121538641, 15546.611334716723, 15833.208339582745, 16070.0, 18176.00031998253, 18201.544961376072, 18559.53601159982, 18641.067946602343, 18910.338138165098, 19697.507562311057, 19702.704459433407, 19789.60420791605, 19791.505212369804, 19803.10886010162, 18081.819181808012, 18188.272469101088, 18233.908830455763, 18287.359942401952, 18318.81681183171], "value": 12909.692481999418, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1350.7838745800673, 1402.8947828912835, 1425.144913052169, 1431.6850292935565, 1438.5252866554038, 14217.502387416442, 14540.636484087903, 14829.851701482981, 14840.628984275398, 14853.55439336819, 15168.924155379234, 15396.84603153968, 15414.69170616587, 15430.69138617227, 15431.537053888402, 2688.3951111000065, 2727.986360068197, 2731.726827317262, 2732.098407525512, 2732.8770205340807, 2641.128427618891, 2676.451327477872, 2709.282040259333, 2713.4844379567917, 2714.647095877543, 2645.7618814306707, 2693.407450360927, 2704.9459010819774, 2757.765589924859, 2763.474939761449, 2674.986625066872, 2675.6789185297694, 2677.7456141666607, 2687.2610032241096, 2697.676278846541, 2592.6499922510598, 2594.3903182752038, 2607.2699644099685, 2611.138324352959, 2613.9607905881235, 14016.0, 14389.78415323771, 14395.136291822524, 14398.784018239734, 14403.279836008158, 7464.626768661599, 7728.961355193244, 7730.458867879251, 7734.7679569613, 7739.148693643695, 4994.700317980896, 5156.742162891878, 5171.276021357013, 5258.737063146866, 5276.630635855492, 1823.7203495630463, 1827.1047186878466, 1834.4038187588953, 1859.0890463672838, 1881.9435416937513, 23502.412439689146, 23839.046438142454, 24119.87940060306, 24146.275611731675, 24821.0, 6315.210598675166, 6325.65208913505, 6340.651264180515, 6487.902681459782, 6503.674816259168, 15618.375264989274, 15636.4527241546, 15640.687186256439, 15653.608659783597, 15671.294791734399, 7501.624918754041, 7601.315881570686, 7625.161232264447, 7626.733064342721, 7632.389408847279, 6911.447084233249, 6916.965415172892, 6971.302869713089, 7024.964875175694, 7106.857862842714, 4745.525447455296, 4914.189158788831, 4935.975320123376, 4937.086638971802, 4940.407151141814, 1429.478240442231, 1458.4530800949644, 1466.6846627974824, 1485.695432436348, 1494.8355680875095, 875.4484674655054, 892.8571428571397, 905.524599585223, 909.1090731083575, 914.4193437167446, 7097.645117744195, 8732.345074119467, 9063.773405664911, 9077.682281120262, 9150.267978561698, 974.5030034682363, 982.1602529837029, 1014.1379827146866, 1019.4393083803878, 1021.7445638590352, 4778.904421911542, 4975.328330675385, 4999.925001124986, 5072.797088116507, 5115.130427827278, 19498.70751938722, 19956.401307960783, 19983.700244496344, 20158.0, 20175.192992280143, 19463.513412164524, 20043.799562004486, 20046.89976550108, 20082.0, 20098.19607215695, 9779.217662586976, 10175.185985121025, 11112.499937502831, 11208.27146235492, 11249.268797528299, 7436.851262974818, 8473.576321183793, 8515.233628973423, 8719.694810681596, 8933.553322333988, 17421.0, 17549.1225438725, 17746.41012486755, 17911.820881791016, 18180.999999999472, 17439.389621363192, 18020.639587208443, 18034.0, 18123.72814407811, 18436.631267374847, 13128.60614181576, 13510.256935868621, 13513.189208647513, 13517.188968661912, 13547.187168769906, 13309.80035299452, 13510.527131550349, 13626.250556219504, 13634.590962271148, 13876.930615346859, 11084.833727493933, 11127.221094523306, 11142.777144456903, 11248.83126753083, 11268.943655281671, 10581.62964296246, 10593.09958653523, 10639.8936010642, 10681.57273709043, 10904.672859814218, 8960.775980600538, 9086.954565227132, 9094.2269907056, 9105.954470227607, 9107.22588579978, 6778.254392016973, 6805.6937437816405, 6824.044633751179, 6838.555493892881, 6873.0377747116445, 7009.474289428415, 7031.824204394931, 7055.0, 7083.6812343443635, 7104.11198600175], "value": 6496.68721500886, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [485.59210263378753, 494.7166422089489, 509.23868816119904, 512.7538781384935, 518.924755910393, 6240.3759624037575, 6241.563090583661, 6293.118963345124, 6302.621842689437, 6304.8108556743255, 5829.795957141501, 6009.699515024253, 6019.518438524919, 6034.487068599175, 6167.568270221086, 5100.362454693154, 5137.51193636604, 5146.356705411824, 5154.814392689684, 5166.044281807859, 5148.98784211235, 5155.278261043457, 5160.483951604847, 5175.353080864892, 5176.249443830642, 4793.5685788279225, 4950.702957822524, 4976.830444845458, 4984.1526940420035, 4999.850004499871, 4943.629227807893, 4944.134776414131, 4973.726445045521, 4974.651774375795, 5010.248462730619, 4439.489458712257, 4466.620337271336, 4477.126960242754, 4484.573965473275, 4497.685162038659, 6084.539710469499, 6141.907871381933, 6151.677389361309, 6154.692265386758, 6168.352323006109, 5697.544196464273, 5715.799947001877, 5740.282464691913, 5749.511291540224, 5751.798687045975, 6488.0592314114665, 6546.134351709788, 6574.605523668547, 6613.867722645568, 6631.436327912085, 4833.275008748716, 4886.731229782361, 4893.168161412568, 4897.51024897511, 4915.213565829486, 6909.3380508330665, 6979.197392299849, 6981.581105133709, 6983.615901125436, 7006.334398232159, 5029.321041659402, 5031.6729412588065, 5047.4447810740785, 5077.771500282496, 5081.415637201695, 5068.4287870759445, 5106.15748401517, 5109.693418394908, 5121.820736274287, 5126.461721519261, 5689.260396148474, 5712.971435142797, 5715.514181294553, 5733.598648094593, 5753.88492230153, 6540.533517235777, 6592.43964263043, 6597.8350541235895, 6661.367170118831, 6673.165854268216, 1667.5164202381416, 1705.4116329866176, 1736.8697347698974, 1779.715245560704, 1883.2561138350275, 1426.522115091456, 1442.581651321105, 1449.8260208775014, 1452.2282814965724, 1452.4843680493484, 5670.404607516234, 5678.261825962681, 5738.454846789548, 5748.137779333133, 5760.942390576124, 6617.0, 6667.399934005963, 6721.7311307548125, 6755.324467553205, 6814.352636499525, 5846.795362162304, 5857.887001469707, 5870.941290587125, 5896.469317761422, 5896.906598157678, 3593.4969104325414, 3621.6197299283194, 3623.474596183539, 3634.9636503635156, 3637.308911306843, 6600.273969863311, 6633.834154146185, 6646.23874673212, 6686.297938716462, 6698.129243198353, 6963.825904352431, 7002.159740831133, 7003.3346832050875, 7004.4396448284015, 7025.824354391181, 6579.934200658124, 6618.271990081087, 6625.966870165618, 6642.800715978528, 6652.534322597372, 6758.904739530762, 6770.458363330823, 6799.422049125879, 6803.727850885909, 6804.931950680628, 6688.6990085445295, 6697.196336439659, 6751.527393082437, 6778.457723382019, 6781.38967492928, 6524.380183882524, 6559.4752419807255, 6582.334154667367, 6597.406233439014, 6655.833604159935, 4971.776270067783, 5056.266841308064, 5063.113955057924, 5078.492150784892, 5089.13484707608, 6779.491538134561, 6779.796606101825, 6798.52410331272, 6862.691178896862, 6875.965620171867, 6552.737890484327, 6580.868382632225, 6609.272979972103, 6631.767888123893, 6636.767713130017, 6422.6467544285515, 6458.257300410325, 6543.247526534319, 6546.869062618816, 6552.935825215042, 6284.685765711604, 6331.398517140864, 6341.524385671185, 6389.329120442472, 6437.774677886251, 6486.5459417842185, 6557.737690492518, 6627.801165965029, 6656.600603963778, 6668.899966500409, 6309.526785491198, 6330.8100756977365, 6347.619142851444, 6355.809325720235, 6385.0], "value": 5112.368733840109, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [877.2630989968425, 888.2982443869341, 904.2630256341085, 909.6588779207797, 923.224491427201, 17978.730319045226, 18757.531061723465, 18821.153048112832, 18843.963582003, 22375.216867409643, 20895.268665596708, 22257.2209972651, 22291.0, 22325.44186395341, 22388.88805555974, 1021.678171376016, 1060.0565496707898, 1061.060961049473, 1065.8241390170613, 1068.6259809066842, 1008.2424568804314, 1057.8095942730306, 1060.4379678770226, 1068.1081297116916, 1069.1927594666056, 1105.4196546812948, 1111.4053981120103, 1112.0603090388609, 1115.1524841120765, 1117.7261570915136, 1110.944452777362, 1115.7433790228292, 1115.8493603363604, 1116.0792346314324, 1116.972075698106, 813.0812182234097, 813.2193094629163, 813.9064007639138, 814.1126172471975, 814.6252723747084, 20447.182112715473, 20493.4876628084, 20776.272830451013, 20777.06503207359, 20900.477488062766, 21715.0, 21812.236571719914, 21902.561948761097, 22059.779402206095, 22167.0, 16842.747358789627, 17043.14784260782, 17147.82852171475, 17206.311747530082, 17227.138643067796, 3596.0829988353066, 3622.9275414491826, 3626.220362622049, 3626.8005259710703, 3627.2382799612114, 123318.38340808329, 128657.35671321584, 128896.35551822181, 129107.0, 129754.3512282442, 17086.145692715316, 17582.296708131787, 17672.646547068987, 17734.11329433523, 18269.63460730778, 31569.36861262762, 31604.051878443683, 31615.841920790248, 31766.682333176374, 32182.517262241086, 7677.923220767832, 8102.230288122619, 8406.579671016547, 8666.956665216634, 9123.406978546505, 4329.15581461612, 4441.267190913528, 4441.400410944481, 4455.554444555519, 4459.219636563588, 5257.526822585985, 5322.8935421292135, 5324.0, 5340.198970154508, 5364.624476286701, 1036.7822757220918, 1047.0838016735356, 1058.4231593781408, 1059.0680201422854, 1063.1654151491043, 667.4493542827187, 686.8729285082276, 688.6763221285987, 702.8594281143792, 708.3447810775116, 11637.418129093512, 11732.296062236293, 12099.27404355724, 12347.488038277368, 12782.9360853197, 665.2682049745241, 672.4754691340762, 676.3304328714537, 677.7729460630744, 691.7924622613297, 5739.426057394227, 6089.8477538061015, 6092.69536523181, 6130.846728831815, 6161.229846269216, 113721.43139284251, 115464.42267788608, 117413.4129329365, 117453.41273293579, 119698.0, 97541.0, 98791.50604246932, 99620.50189749004, 101380.49309753551, 101870.49064754629, 90031.0, 90775.0, 91553.0, 91558.0, 93541.99999999863, 12860.549880754128, 13390.866091339352, 13408.396622151831, 13477.19136851792, 13515.324233788468, 39555.60444395519, 41195.1760964785, 41487.0, 41568.376474353514, 41582.79208603938, 71532.28467715257, 73077.6346118266, 76251.61874190815, 77049.61475192588, 77660.0, 17572.12139393051, 19985.700214497087, 20178.0, 20185.59628807445, 20433.386998390073, 20008.0, 20114.39656810298, 20356.389308320773, 20398.082086305854, 22873.771262287166, 19230.519237018627, 19632.60734785325, 20069.698954515403, 20072.397828065183, 20450.181992720125, 19519.51201219924, 20955.580888381843, 21113.366599002056, 21304.786952130282, 21767.020484077937, 19508.02459876972, 19546.90226548858, 19642.90178549098, 20813.895930520855, 20961.58076838485, 13747.381367838272, 14238.715225695636, 14340.139591624536, 14448.855511444752, 14507.78238326447, 15201.087934723952, 15240.618984525476, 15244.0, 15414.0, 15556.844431555539], "value": 9568.170197657662, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1528.1000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 107.0, 100.0, 108.0, 109.0, 110.0, 110.0, 99.0, 108.0, 109.0], "value": 105.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 117.0, 143.0, 186.0, 118.0, 141.0, 147.0, 118.0, 181.0, 120.0], "value": 141.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 162.0, 165.0, 161.0, 163.0, 163.0, 163.0, 166.0, 164.0, 163.0], "value": 163.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 77.0, 77.0, 184.0, 84.0, 77.0, 75.0, 74.0, 167.0, 77.0], "value": 96.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 80.0, 136.0, 78.0, 79.0, 79.0, 126.0, 79.0, 79.0, 128.0], "value": 94.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 114.0, 114.0, 114.0, 114.0, 114.0, 116.0, 114.0, 113.0, 114.0], "value": 114.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 150.0, 150.0, 158.0, 149.0, 151.0, 151.0, 149.0, 158.0, 150.0], "value": 151.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 98.0, 93.0, 94.0, 93.0, 94.0, 94.0, 93.0, 94.0, 96.0], "value": 94.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 54.0, 55.0, 55.0, 56.0, 54.0, 58.0, 54.0, 54.0, 54.0], "value": 54.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 54.0, 54.0, 55.0, 54.0, 54.0, 54.0, 54.0, 54.0, 54.0], "value": 54.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 79.0, 78.0, 76.0, 78.0, 76.0, 76.0, 77.0, 77.0, 79.0], "value": 77.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [126.0, 123.0, 127.0, 123.0, 122.0, 125.0, 129.0, 119.0, 122.0, 164.0], "value": 128.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [177.0, 181.0, 181.0, 179.0, 180.0, 178.0, 180.0, 185.0, 182.0, 181.0], "value": 180.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 83.0, 71.0, 74.0, 69.0, 74.0, 69.0, 67.0, 69.0, 68.0], "value": 71.2, "unit": "score"}]}]} 14:51:44 INFO - Return code: 0 14:51:44 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 14:51:44 INFO - # TBPL SUCCESS # 14:51:44 INFO - Running post-action listener: _package_coverage_data 14:51:44 INFO - Running post-action listener: _resource_record_post_action 14:51:44 INFO - [mozharness: 2017-08-16 21:51:44.778794Z] Finished run-tests step (success) 14:51:44 INFO - Running post-run listener: _resource_record_post_run 14:51:44 INFO - Total resource usage - Wall time: 1196s; CPU: 13.0%; Read bytes: 5611520; Write bytes: 439308288; Read time: 3060; Write time: 516180 14:51:44 INFO - TinderboxPrint: CPU usage
13.1% 14:51:44 INFO - TinderboxPrint: I/O read bytes / time
5,611,520 / 3,060 14:51:44 INFO - TinderboxPrint: I/O write bytes / time
439,308,288 / 516,180 14:51:44 INFO - TinderboxPrint: CPU idle
8,284.1 (86.9%) 14:51:44 INFO - TinderboxPrint: CPU user
1,198.8 (12.6%) 14:51:44 INFO - TinderboxPrint: Swap in / out
0 / 0 14:51:44 INFO - install - Wall time: 18s; CPU: 14.0%; Read bytes: 4096; Write bytes: 192647168; Read time: 16; Write time: 254472 14:51:44 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 14:51:44 INFO - run-tests - Wall time: 1178s; CPU: 13.0%; Read bytes: 3575808; Write bytes: 198950912; Read time: 2544; Write time: 165368 14:51:45 INFO - Running post-run listener: _upload_blobber_files 14:51:45 INFO - Blob upload gear active. 14:51:45 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 14:51:45 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 14:51:45 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-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 14:51:45 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-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 14:51:45 INFO - (blobuploader) - INFO - Open directory for files ... 14:51:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 14:51:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:51:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:51:46 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 14:51:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:51:46 INFO - (blobuploader) - INFO - Done attempting. 14:51:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 14:51:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:51:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:51:53 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 14:51:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:51:53 INFO - (blobuploader) - INFO - Done attempting. 14:51:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 14:51:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:51:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:51:54 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 14:51:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:51:54 INFO - (blobuploader) - INFO - Done attempting. 14:51:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 14:51:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:51:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:51:55 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 14:51:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:51:55 INFO - (blobuploader) - INFO - Done attempting. 14:51:55 INFO - (blobuploader) - INFO - Iteration through files over. 14:51:55 INFO - Return code: 0 14:51:55 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 14:51:55 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 14:51:55 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/0f1cdf9ad0ee2c3dc3b45259de5b2417e3c38c5a937a88c89f0f118e09495d97384874e2654c07a93c46c4e25205c5eff13be6f0cfc4cc454a05685ed48586df", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c31ab26e8a963b8afee4958cdaed1f2462fc5f7779094c38517baf2f2ae6264e0ace46a3a7b81b013d22f04b7a56cfe38bf4055bb645af773fbe896286de9c49", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/4049d96f309f69fc14d6ffa91b818fd27bf9f5fa1d01fb2d24a47bb7480383ee5f789d299dc811aa8d5b2e1cfb2d5688186c891c01dce1796ec3f6a0f239dc76", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ae099f06c59df8679c1a3d35a0a892bc7910bf81eaf27e23e9b5d35600587d830b9b6cb1bc141a7ed59ae2ae54b8e5722889a9c9fd5543b7e55c91f68ab14ca2"} 14:51:55 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 14:51:55 INFO - Writing to file /builds/slave/test/properties/blobber_files 14:51:55 INFO - Contents: 14:51:55 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/0f1cdf9ad0ee2c3dc3b45259de5b2417e3c38c5a937a88c89f0f118e09495d97384874e2654c07a93c46c4e25205c5eff13be6f0cfc4cc454a05685ed48586df", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c31ab26e8a963b8afee4958cdaed1f2462fc5f7779094c38517baf2f2ae6264e0ace46a3a7b81b013d22f04b7a56cfe38bf4055bb645af773fbe896286de9c49", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/4049d96f309f69fc14d6ffa91b818fd27bf9f5fa1d01fb2d24a47bb7480383ee5f789d299dc811aa8d5b2e1cfb2d5688186c891c01dce1796ec3f6a0f239dc76", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ae099f06c59df8679c1a3d35a0a892bc7910bf81eaf27e23e9b5d35600587d830b9b6cb1bc141a7ed59ae2ae54b8e5722889a9c9fd5543b7e55c91f68ab14ca2"} 14:51:55 INFO - Running post-run listener: copy_logs_to_upload_dir 14:51:55 INFO - Copying logs to upload dir... 14:51:55 INFO - mkdir: /builds/slave/test/build/upload/logs 14:51:55 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1263.893113 ========= master_lag: -0.07 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 3 secs) (at 2017-08-16 14:51:55.490850) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-16 14:51:55.517492) ========= 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=5bd038846534b2fdfa68eeae000001e2-1502919045.249407-1615021432 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/0f1cdf9ad0ee2c3dc3b45259de5b2417e3c38c5a937a88c89f0f118e09495d97384874e2654c07a93c46c4e25205c5eff13be6f0cfc4cc454a05685ed48586df", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c31ab26e8a963b8afee4958cdaed1f2462fc5f7779094c38517baf2f2ae6264e0ace46a3a7b81b013d22f04b7a56cfe38bf4055bb645af773fbe896286de9c49", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/4049d96f309f69fc14d6ffa91b818fd27bf9f5fa1d01fb2d24a47bb7480383ee5f789d299dc811aa8d5b2e1cfb2d5688186c891c01dce1796ec3f6a0f239dc76", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ae099f06c59df8679c1a3d35a0a892bc7910bf81eaf27e23e9b5d35600587d830b9b6cb1bc141a7ed59ae2ae54b8e5722889a9c9fd5543b7e55c91f68ab14ca2"} build_url:https://queue.taskcluster.net/v1/task/ZbYi7e4SShuLRJRdD3myxg/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011181 build_url: 'https://queue.taskcluster.net/v1/task/ZbYi7e4SShuLRJRdD3myxg/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/0f1cdf9ad0ee2c3dc3b45259de5b2417e3c38c5a937a88c89f0f118e09495d97384874e2654c07a93c46c4e25205c5eff13be6f0cfc4cc454a05685ed48586df", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c31ab26e8a963b8afee4958cdaed1f2462fc5f7779094c38517baf2f2ae6264e0ace46a3a7b81b013d22f04b7a56cfe38bf4055bb645af773fbe896286de9c49", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/4049d96f309f69fc14d6ffa91b818fd27bf9f5fa1d01fb2d24a47bb7480383ee5f789d299dc811aa8d5b2e1cfb2d5688186c891c01dce1796ec3f6a0f239dc76", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ae099f06c59df8679c1a3d35a0a892bc7910bf81eaf27e23e9b5d35600587d830b9b6cb1bc141a7ed59ae2ae54b8e5722889a9c9fd5543b7e55c91f68ab14ca2"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-16 14:51:55.560348) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-16 14:51:55.560721) ========= 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=5bd038846534b2fdfa68eeae000001e2-1502919045.249407-1615021432 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004435 ========= master_lag: 0.07 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-16 14:51:55.639016) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-16 14:51:55.642364) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-16 14:51:55.642688) ========= ========= Total master_lag: 1.67 =========