builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-014 starttime: 1501718056.21 results: success (0) revision: 4c7317211990d6cf156c103a73a5b3ec41f2dd4d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-08-02 16:54:16.213640) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-08-02 16:54:16.214196) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-02 16:54:16.214534) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-02 16:54:16.232696) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-02 16:54:16.233316) ========= 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=31acecf42b6a79ec49471d4a000001bb-1501717684.416508-1647253761 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-08-02 16:54:16-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 46.1M=0s 2017-08-02 16:54:16 (46.1 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.092039 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-02 16:54:16.342216) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-02 16:54:16.342760) ========= 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=31acecf42b6a79ec49471d4a000001bb-1501717684.416508-1647253761 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.021143 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-02 16:54:16.394927) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-08-02 16:54:16.395753) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 4c7317211990d6cf156c103a73a5b3ec41f2dd4d --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 4c7317211990d6cf156c103a73a5b3ec41f2dd4d --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=31acecf42b6a79ec49471d4a000001bb-1501717684.416508-1647253761 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-08-02 16:54:16,921 truncating revision to first 12 chars 2017-08-02 16:54:16,921 Setting DEBUG logging. 2017-08-02 16:54:16,921 attempt 1/10 2017-08-02 16:54:16,921 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/4c7317211990?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-08-02 16:54:17,779 unpacking tar archive at: mozilla-central-4c7317211990/testing/mozharness/ program finished with exit code 0 elapsedTime=1.164373 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-08-02 16:54:17.576233) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-02 16:54:17.576580) ========= script_repo_revision: 4c7317211990d6cf156c103a73a5b3ec41f2dd4d ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-02 16:54:17.577003) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-02 16:54:17.577341) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-02 16:54:17.587307) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 50 secs) (at 2017-08-02 16:54:17.587627) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=31acecf42b6a79ec49471d4a000001bb-1501717684.416508-1647253761 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 16:54:18 INFO - MultiFileLogger online at 20170802 16:54:18 in /builds/slave/test 16:54:18 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 16:54:18 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:54:18 INFO - {'append_to_log': False, 16:54:18 INFO - 'base_work_dir': '/builds/slave/test', 16:54:18 INFO - 'blob_upload_branch': 'Firefox', 16:54:18 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:54:18 INFO - 'branch': 'Firefox', 16:54:18 INFO - 'buildbot_json_path': 'buildprops.json', 16:54:18 INFO - 'code_coverage': False, 16:54:18 INFO - 'config_files': ('talos/linux_config.py',), 16:54:18 INFO - 'default_actions': ('clobber', 16:54:18 INFO - 'read-buildbot-config', 16:54:18 INFO - 'download-and-extract', 16:54:18 INFO - 'populate-webroot', 16:54:18 INFO - 'create-virtualenv', 16:54:18 INFO - 'install', 16:54:18 INFO - 'setup-mitmproxy', 16:54:18 INFO - 'run-tests'), 16:54:18 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:54:18 INFO - 'disable_ccov_upload': False, 16:54:18 INFO - 'download_minidump_stackwalk': True, 16:54:18 INFO - 'download_symbols': 'ondemand', 16:54:18 INFO - 'enable_stylo': False, 16:54:18 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:54:18 INFO - 'tooltool.py': '/tools/tooltool.py', 16:54:18 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:54:18 INFO - '/tools/misc-python/virtualenv.py')}, 16:54:18 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:54:18 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:54:18 INFO - 'gecko_profile': False, 16:54:18 INFO - 'gecko_profile_interval': 0, 16:54:18 INFO - 'installer_path': 'installer.exe', 16:54:18 INFO - 'log_level': 'info', 16:54:18 INFO - 'log_name': 'talos', 16:54:18 INFO - 'log_to_console': True, 16:54:18 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 16:54:18 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 16:54:18 INFO - 'opt_config_files': (), 16:54:18 INFO - 'pip_index': False, 16:54:18 INFO - 'suite': 'dromaeojs-e10s', 16:54:18 INFO - 'system_bits': '32', 16:54:18 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:54:18 INFO - 'title': 'talos-linux64-ix-014', 16:54:18 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:54:18 INFO - 'use_talos_json': True, 16:54:18 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:54:18 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:54:18 INFO - 'work_dir': 'build'} 16:54:18 INFO - [mozharness: 2017-08-02 23:54:18.218734Z] Running clobber step. 16:54:18 INFO - Running pre-action listener: _resource_record_pre_action 16:54:18 INFO - Running main action method: clobber 16:54:18 INFO - rmtree: /builds/slave/test/build 16:54:18 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:54:19 INFO - Running post-action listener: _resource_record_post_action 16:54:19 INFO - [mozharness: 2017-08-02 23:54:19.261862Z] Finished clobber step (success) 16:54:19 INFO - [mozharness: 2017-08-02 23:54:19.261966Z] Running read-buildbot-config step. 16:54:19 INFO - Running pre-action listener: _resource_record_pre_action 16:54:19 INFO - Running main action method: read_buildbot_config 16:54:19 INFO - Using buildbot properties: 16:54:19 INFO - { 16:54:19 INFO - "project": "", 16:54:19 INFO - "product": "firefox", 16:54:19 INFO - "who": "kwierso@gmail.com", 16:54:19 INFO - "installer_path": "public/build/target.tar.bz2", 16:54:19 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 16:54:19 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 16:54:19 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs-e10s", 16:54:19 INFO - "stage_platform": "linux64-pgo", 16:54:19 INFO - "basedir": "/builds/slave/test", 16:54:19 INFO - "buildnumber": 106, 16:54:19 INFO - "platform": "ubuntu64_hw", 16:54:19 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 16:54:19 INFO - "slavebuilddir": "test", 16:54:19 INFO - "taskId": "JSV6457pSueCGtcOlRoENA", 16:54:19 INFO - "branch": "mozilla-central", 16:54:19 INFO - "script_repo_revision": "production", 16:54:19 INFO - "revision": "4c7317211990d6cf156c103a73a5b3ec41f2dd4d", 16:54:19 INFO - "slavename": "talos-linux64-ix-014", 16:54:19 INFO - "repo_path": "mozilla-central" 16:54:19 INFO - } 16:54:19 INFO - Finding installer, test and symbols from parent task. 16:54:19 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JSV6457pSueCGtcOlRoENA'}, attempt #1 16:54:19 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JSV6457pSueCGtcOlRoENA'}, attempt #1 16:54:20 INFO - Task dependencies: YjPNvDkxRpGcvZQBdlxoDQ 16:54:20 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YjPNvDkxRpGcvZQBdlxoDQ'}, attempt #1 16:54:20 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/YjPNvDkxRpGcvZQBdlxoDQ/artifacts/public/build/target.tar.bz2 16:54:20 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/YjPNvDkxRpGcvZQBdlxoDQ/artifacts/public/build/target.test_packages.json 16:54:20 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/YjPNvDkxRpGcvZQBdlxoDQ/artifacts/public/build/target.crashreporter-symbols.zip 16:54:20 INFO - Running post-action listener: _resource_record_post_action 16:54:20 INFO - [mozharness: 2017-08-02 23:54:20.627993Z] Finished read-buildbot-config step (success) 16:54:20 INFO - [mozharness: 2017-08-02 23:54:20.628214Z] Running download-and-extract step. 16:54:20 INFO - Running pre-action listener: _resource_record_pre_action 16:54:20 INFO - Running main action method: download_and_extract 16:54:20 INFO - mkdir: /builds/slave/test/build/tests 16:54:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:54:20 INFO - trying https://queue.taskcluster.net/v1/task/YjPNvDkxRpGcvZQBdlxoDQ/artifacts/public/build/target.test_packages.json 16:54:20 INFO - Downloading https://queue.taskcluster.net/v1/task/YjPNvDkxRpGcvZQBdlxoDQ/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 16:54:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YjPNvDkxRpGcvZQBdlxoDQ/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 16:54:21 INFO - Downloaded 989 bytes. 16:54:21 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 16:54:21 INFO - Using the following test package requirements: 16:54:21 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 16:54:21 INFO - u'common': [u'target.common.tests.zip'], 16:54:21 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 16:54:21 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 16:54:21 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 16:54:21 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 16:54:21 INFO - u'mozbase': [u'target.common.tests.zip'], 16:54:21 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 16:54:21 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 16:54:21 INFO - u'web-platform': [u'target.common.tests.zip', 16:54:21 INFO - u'target.web-platform.tests.tar.gz'], 16:54:21 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 16:54:21 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 16:54:21 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/YjPNvDkxRpGcvZQBdlxoDQ/artifacts/public/build/target.common.tests.zip 16:54:21 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YjPNvDkxRpGcvZQBdlxoDQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 16:54:21 INFO - Fetch https://queue.taskcluster.net/v1/task/YjPNvDkxRpGcvZQBdlxoDQ/artifacts/public/build/target.common.tests.zip into memory 16:54:24 INFO - Content-Length response header: 72965636 16:54:24 INFO - Bytes received: 72965636 16:54:35 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 16:54:35 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/YjPNvDkxRpGcvZQBdlxoDQ/artifacts/public/build/target.common.tests.zip 16:54:35 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YjPNvDkxRpGcvZQBdlxoDQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 16:54:35 INFO - Fetch https://queue.taskcluster.net/v1/task/YjPNvDkxRpGcvZQBdlxoDQ/artifacts/public/build/target.common.tests.zip into memory 16:54:36 INFO - Content-Length response header: 72965636 16:54:36 INFO - Bytes received: 72965636 16:54:49 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/YjPNvDkxRpGcvZQBdlxoDQ/artifacts/public/build/target.talos.tests.zip 16:54:49 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YjPNvDkxRpGcvZQBdlxoDQ/artifacts/public/build/target.talos.tests.zip'}, attempt #1 16:54:49 INFO - Fetch https://queue.taskcluster.net/v1/task/YjPNvDkxRpGcvZQBdlxoDQ/artifacts/public/build/target.talos.tests.zip into memory 16:54:50 INFO - Content-Length response header: 14496464 16:54:50 INFO - Bytes received: 14496464 16:54:50 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')]} 16:54:50 INFO - trying https://queue.taskcluster.net/v1/task/YjPNvDkxRpGcvZQBdlxoDQ/artifacts/public/build/target.tar.bz2 16:54:50 INFO - Downloading https://queue.taskcluster.net/v1/task/YjPNvDkxRpGcvZQBdlxoDQ/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 16:54:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YjPNvDkxRpGcvZQBdlxoDQ/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 16:54:52 INFO - Downloaded 63242329 bytes. 16:54:52 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/YjPNvDkxRpGcvZQBdlxoDQ/artifacts/public/build/target.tar.bz2 16:54:52 INFO - mkdir: /builds/slave/test/properties 16:54:52 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:54:52 INFO - Writing to file /builds/slave/test/properties/build_url 16:54:52 INFO - Contents: 16:54:52 INFO - build_url:https://queue.taskcluster.net/v1/task/YjPNvDkxRpGcvZQBdlxoDQ/artifacts/public/build/target.tar.bz2 16:54:52 INFO - Running post-action listener: _resource_record_post_action 16:54:52 INFO - Running post-action listener: set_extra_try_arguments 16:54:52 INFO - [mozharness: 2017-08-02 23:54:52.799940Z] Finished download-and-extract step (success) 16:54:52 INFO - [mozharness: 2017-08-02 23:54:52.800119Z] Running populate-webroot step. 16:54:52 INFO - Running pre-action listener: _resource_record_pre_action 16:54:52 INFO - Running main action method: populate_webroot 16:54:52 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:54:52 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:54:52 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 16:54:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:54:52 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 16:54:52 INFO - 'tests': ['damp', 'tps']}, 16:54:52 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:54:52 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 16:54:52 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 16:54:52 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 16:54:52 INFO - 'other-e10s': {'tests': ['a11yr', 16:54:52 INFO - 'ts_paint', 16:54:52 INFO - 'tpaint', 16:54:52 INFO - 'sessionrestore', 16:54:52 INFO - 'sessionrestore_many_windows', 16:54:52 INFO - 'sessionrestore_no_auto_restore', 16:54:52 INFO - 'tabpaint']}, 16:54:52 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 16:54:52 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 16:54:52 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 16:54:52 INFO - 'talos_options': ['--mitmproxy', 16:54:52 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 16:54:52 INFO - '--firstNonBlankPaint'], 16:54:52 INFO - 'tests': ['quantum_pageload_google', 16:54:52 INFO - 'quantum_pageload_youtube', 16:54:52 INFO - 'quantum_pageload_amazon', 16:54:52 INFO - 'quantum_pageload_facebook']}, 16:54:52 INFO - 'quantum-pageload-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 16:54:52 INFO - 'talos_options': ['--stylo', 16:54:52 INFO - '--mitmproxy', 16:54:52 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 16:54:52 INFO - '--firstNonBlankPaint'], 16:54:52 INFO - 'tests': ['quantum_pageload_google', 16:54:52 INFO - 'quantum_pageload_youtube', 16:54:52 INFO - 'quantum_pageload_amazon', 16:54:52 INFO - 'quantum_pageload_facebook']}, 16:54:52 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:54:52 INFO - 'tsvgr_opacity', 16:54:52 INFO - 'tart', 16:54:52 INFO - 'tscrollx', 16:54:52 INFO - 'cart', 16:54:52 INFO - 'tsvg_static']}, 16:54:52 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 16:54:52 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 16:54:52 INFO - 'talos_options': ['--stylo', 16:54:52 INFO - '--stylo-threads=1', 16:54:52 INFO - '--mitmproxy', 16:54:52 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 16:54:52 INFO - '--firstNonBlankPaint'], 16:54:52 INFO - 'tests': ['tp6_google', 16:54:52 INFO - 'tp6_youtube', 16:54:52 INFO - 'tp6_amazon', 16:54:52 INFO - 'tp6_facebook']}, 16:54:52 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 16:54:52 INFO - 'talos_options': ['--xperf_path', 16:54:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:54:52 INFO - 'tests': ['tp5n']}}} 16:54:52 INFO - Running post-action listener: _resource_record_post_action 16:54:52 INFO - [mozharness: 2017-08-02 23:54:52.816656Z] Finished populate-webroot step (success) 16:54:52 INFO - [mozharness: 2017-08-02 23:54:52.816745Z] Running create-virtualenv step. 16:54:52 INFO - Running pre-action listener: _resource_record_pre_action 16:54:52 INFO - Running main action method: create_virtualenv 16:54:52 INFO - Creating virtualenv /builds/slave/test/build/venv 16:54:52 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 16:54:52 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:54:52 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 16:54:52 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:54:52 INFO - Using real prefix '/usr' 16:54:52 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:54:53 INFO - Installing distribute.............................................................................................................................................................................................done. 16:54:55 INFO - Installing pip.................done. 16:54:55 INFO - Return code: 0 16:54:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 16:54:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 16:54:55 INFO - Reading from file tmpfile_stdout 16:54:55 INFO - Output received: 16:54:55 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) 16:54:55 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:54:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:54:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:54:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:54:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:54:55 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 0x1d401f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f0ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20afd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20aceb0>, '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 0x20addb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20ae240>, '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': '31acecf42b6a79ec49471d4a000001bb-1501717684.416508-1647253761', '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 16:54:55 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 16:54:55 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 16:54:55 INFO - Using env: {'DISPLAY': ':0', 16:54:55 INFO - 'HOME': '/home/cltbld', 16:54:55 INFO - 'LANG': 'en_US.UTF-8', 16:54:55 INFO - 'LANGUAGE': 'en_US:en', 16:54:55 INFO - 'LOGNAME': 'cltbld', 16:54:55 INFO - 'MAIL': '/var/mail/cltbld', 16:54:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:54:55 INFO - 'MOZ_NO_REMOTE': '1', 16:54:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:54:55 INFO - 'NO_EM_RESTART': '1', 16:54:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:54:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:54:55 INFO - 'PWD': '/builds/slave/test', 16:54:55 INFO - 'SHELL': '/bin/bash', 16:54:55 INFO - 'SHLVL': '1', 16:54:55 INFO - 'TERM': 'linux', 16:54:55 INFO - 'TMOUT': '86400', 16:54:55 INFO - 'USER': 'cltbld', 16:54:55 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1501717684.416508-1647253761', 16:54:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:54:55 INFO - '_': '/tools/buildbot/bin/python'} 16:54:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:54:55 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 16:54:55 INFO - Cleaning up... 16:54:55 INFO - Return code: 0 16:54:55 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:54:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:54:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:54:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:54:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:54:55 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 0x1d401f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f0ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20afd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20aceb0>, '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 0x20addb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20ae240>, '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': '31acecf42b6a79ec49471d4a000001bb-1501717684.416508-1647253761', '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 16:54:55 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 16:54:55 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 16:54:55 INFO - Using env: {'DISPLAY': ':0', 16:54:55 INFO - 'HOME': '/home/cltbld', 16:54:55 INFO - 'LANG': 'en_US.UTF-8', 16:54:55 INFO - 'LANGUAGE': 'en_US:en', 16:54:55 INFO - 'LOGNAME': 'cltbld', 16:54:55 INFO - 'MAIL': '/var/mail/cltbld', 16:54:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:54:55 INFO - 'MOZ_NO_REMOTE': '1', 16:54:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:54:55 INFO - 'NO_EM_RESTART': '1', 16:54:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:54:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:54:55 INFO - 'PWD': '/builds/slave/test', 16:54:55 INFO - 'SHELL': '/bin/bash', 16:54:55 INFO - 'SHLVL': '1', 16:54:55 INFO - 'TERM': 'linux', 16:54:55 INFO - 'TMOUT': '86400', 16:54:55 INFO - 'USER': 'cltbld', 16:54:55 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1501717684.416508-1647253761', 16:54:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:54:55 INFO - '_': '/tools/buildbot/bin/python'} 16:54:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:54:56 INFO - Downloading/unpacking psutil>=3.1.1 16:54:56 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 16:54:56 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 16:54:56 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 16:54:56 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 16:54:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:54:57 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:54:57 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:54:57 INFO - Installing collected packages: psutil 16:54:57 INFO - Running setup.py install for psutil 16:54:57 INFO - building 'psutil._psutil_linux' extension 16:54:57 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 16:54:57 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 16:54:57 INFO - building 'psutil._psutil_posix' extension 16:54:57 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 16:54:57 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 16:54:57 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:54:57 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:54:57 INFO - Successfully installed psutil 16:54:57 INFO - Cleaning up... 16:54:57 INFO - Return code: 0 16:54:57 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:54:57 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')]} 16:54:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:54:57 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')]} 16:54:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:54:57 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 0x1d401f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f0ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20afd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20aceb0>, '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 0x20addb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20ae240>, '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': '31acecf42b6a79ec49471d4a000001bb-1501717684.416508-1647253761', '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 16:54:57 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 16:54:57 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 16:54:57 INFO - Using env: {'DISPLAY': ':0', 16:54:57 INFO - 'HOME': '/home/cltbld', 16:54:57 INFO - 'LANG': 'en_US.UTF-8', 16:54:57 INFO - 'LANGUAGE': 'en_US:en', 16:54:57 INFO - 'LOGNAME': 'cltbld', 16:54:57 INFO - 'MAIL': '/var/mail/cltbld', 16:54:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:54:57 INFO - 'MOZ_NO_REMOTE': '1', 16:54:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:54:57 INFO - 'NO_EM_RESTART': '1', 16:54:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:54:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:54:57 INFO - 'PWD': '/builds/slave/test', 16:54:57 INFO - 'SHELL': '/bin/bash', 16:54:57 INFO - 'SHLVL': '1', 16:54:57 INFO - 'TERM': 'linux', 16:54:57 INFO - 'TMOUT': '86400', 16:54:57 INFO - 'USER': 'cltbld', 16:54:57 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1501717684.416508-1647253761', 16:54:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:54:57 INFO - '_': '/tools/buildbot/bin/python'} 16:54:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:54:58 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:54:58 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 16:54:58 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 16:54:58 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 16:54:58 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 16:54:59 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:54:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:54:59 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:54:59 INFO - Installing collected packages: mozsystemmonitor 16:54:59 INFO - Running setup.py install for mozsystemmonitor 16:54:59 INFO - Successfully installed mozsystemmonitor 16:54:59 INFO - Cleaning up... 16:54:59 INFO - Return code: 0 16:54:59 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:54:59 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')]} 16:54:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:54:59 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')]} 16:54:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:54:59 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 0x1d401f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f0ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20afd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20aceb0>, '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 0x20addb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20ae240>, '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': '31acecf42b6a79ec49471d4a000001bb-1501717684.416508-1647253761', '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 16:54:59 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 16:54:59 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 16:54:59 INFO - Using env: {'DISPLAY': ':0', 16:54:59 INFO - 'HOME': '/home/cltbld', 16:54:59 INFO - 'LANG': 'en_US.UTF-8', 16:54:59 INFO - 'LANGUAGE': 'en_US:en', 16:54:59 INFO - 'LOGNAME': 'cltbld', 16:54:59 INFO - 'MAIL': '/var/mail/cltbld', 16:54:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:54:59 INFO - 'MOZ_NO_REMOTE': '1', 16:54:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:54:59 INFO - 'NO_EM_RESTART': '1', 16:54:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:54:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:54:59 INFO - 'PWD': '/builds/slave/test', 16:54:59 INFO - 'SHELL': '/bin/bash', 16:54:59 INFO - 'SHLVL': '1', 16:54:59 INFO - 'TERM': 'linux', 16:54:59 INFO - 'TMOUT': '86400', 16:54:59 INFO - 'USER': 'cltbld', 16:54:59 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1501717684.416508-1647253761', 16:54:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:54:59 INFO - '_': '/tools/buildbot/bin/python'} 16:54:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:54:59 INFO - Downloading/unpacking jsonschema==2.5.1 16:54:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:54:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:54:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:54:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:55:00 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:55:00 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:55:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:55:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:55:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:55:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:55:00 INFO - Downloading functools32-3.2.3-2.tar.gz 16:55:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:55:00 INFO - warning: no files found matching '*.txt' 16:55:00 INFO - no previously-included directories found matching 'build' 16:55:00 INFO - no previously-included directories found matching 'dist' 16:55:00 INFO - no previously-included directories found matching '.git*' 16:55:00 INFO - Installing collected packages: jsonschema, functools32 16:55:00 INFO - Running setup.py install for functools32 16:55:00 INFO - warning: no files found matching '*.txt' 16:55:00 INFO - no previously-included directories found matching 'build' 16:55:00 INFO - no previously-included directories found matching 'dist' 16:55:00 INFO - no previously-included directories found matching '.git*' 16:55:00 INFO - Successfully installed jsonschema functools32 16:55:00 INFO - Cleaning up... 16:55:00 INFO - Return code: 0 16:55:00 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:55:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:55:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:55:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:55:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:55:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1d401f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f0ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20afd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20aceb0>, '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 0x20addb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20ae240>, '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': '31acecf42b6a79ec49471d4a000001bb-1501717684.416508-1647253761', '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 16:55:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 16:55:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 16:55:00 INFO - Using env: {'DISPLAY': ':0', 16:55:00 INFO - 'HOME': '/home/cltbld', 16:55:00 INFO - 'LANG': 'en_US.UTF-8', 16:55:00 INFO - 'LANGUAGE': 'en_US:en', 16:55:00 INFO - 'LOGNAME': 'cltbld', 16:55:00 INFO - 'MAIL': '/var/mail/cltbld', 16:55:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:55:00 INFO - 'MOZ_NO_REMOTE': '1', 16:55:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:55:00 INFO - 'NO_EM_RESTART': '1', 16:55:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:55:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:55:00 INFO - 'PWD': '/builds/slave/test', 16:55:00 INFO - 'SHELL': '/bin/bash', 16:55:00 INFO - 'SHLVL': '1', 16:55:00 INFO - 'TERM': 'linux', 16:55:00 INFO - 'TMOUT': '86400', 16:55:00 INFO - 'USER': 'cltbld', 16:55:00 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1501717684.416508-1647253761', 16:55:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:55:00 INFO - '_': '/tools/buildbot/bin/python'} 16:55:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:55:01 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:55:01 INFO - Cleaning up... 16:55:01 INFO - Return code: 0 16:55:01 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:55:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:55:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:55:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:55:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:55:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1d401f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f0ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20afd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20aceb0>, '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 0x20addb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20ae240>, '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': '31acecf42b6a79ec49471d4a000001bb-1501717684.416508-1647253761', '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 16:55:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 16:55:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 16:55:01 INFO - Using env: {'DISPLAY': ':0', 16:55:01 INFO - 'HOME': '/home/cltbld', 16:55:01 INFO - 'LANG': 'en_US.UTF-8', 16:55:01 INFO - 'LANGUAGE': 'en_US:en', 16:55:01 INFO - 'LOGNAME': 'cltbld', 16:55:01 INFO - 'MAIL': '/var/mail/cltbld', 16:55:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:55:01 INFO - 'MOZ_NO_REMOTE': '1', 16:55:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:55:01 INFO - 'NO_EM_RESTART': '1', 16:55:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:55:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:55:01 INFO - 'PWD': '/builds/slave/test', 16:55:01 INFO - 'SHELL': '/bin/bash', 16:55:01 INFO - 'SHLVL': '1', 16:55:01 INFO - 'TERM': 'linux', 16:55:01 INFO - 'TMOUT': '86400', 16:55:01 INFO - 'USER': 'cltbld', 16:55:01 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1501717684.416508-1647253761', 16:55:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:55:01 INFO - '_': '/tools/buildbot/bin/python'} 16:55:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:55:01 INFO - Downloading/unpacking blobuploader==1.2.4 16:55:01 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 16:55:01 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 16:55:01 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 16:55:01 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 16:55:02 INFO - Downloading blobuploader-1.2.4.tar.gz 16:55:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:55:02 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:55:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:55:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:55:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:55:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:55:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:55:03 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:55:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:55:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:55:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:55:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:55:03 INFO - Downloading docopt-0.6.1.tar.gz 16:55:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:55:03 INFO - Installing collected packages: blobuploader, requests, docopt 16:55:03 INFO - Running setup.py install for blobuploader 16:55:03 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:55:03 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:55:03 INFO - Running setup.py install for requests 16:55:03 INFO - Running setup.py install for docopt 16:55:03 INFO - Successfully installed blobuploader requests docopt 16:55:03 INFO - Cleaning up... 16:55:03 INFO - Return code: 0 16:55:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:55:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:55:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:55:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:55:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:55:03 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 0x1d401f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f0ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20afd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20aceb0>, '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 0x20addb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20ae240>, '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': '31acecf42b6a79ec49471d4a000001bb-1501717684.416508-1647253761', '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 16:55:03 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 16:55:03 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 16:55:03 INFO - Using env: {'DISPLAY': ':0', 16:55:03 INFO - 'HOME': '/home/cltbld', 16:55:03 INFO - 'LANG': 'en_US.UTF-8', 16:55:03 INFO - 'LANGUAGE': 'en_US:en', 16:55:03 INFO - 'LOGNAME': 'cltbld', 16:55:03 INFO - 'MAIL': '/var/mail/cltbld', 16:55:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:55:03 INFO - 'MOZ_NO_REMOTE': '1', 16:55:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:55:03 INFO - 'NO_EM_RESTART': '1', 16:55:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:55:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:55:03 INFO - 'PWD': '/builds/slave/test', 16:55:03 INFO - 'SHELL': '/bin/bash', 16:55:03 INFO - 'SHLVL': '1', 16:55:03 INFO - 'TERM': 'linux', 16:55:03 INFO - 'TMOUT': '86400', 16:55:03 INFO - 'USER': 'cltbld', 16:55:03 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1501717684.416508-1647253761', 16:55:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:55:03 INFO - '_': '/tools/buildbot/bin/python'} 16:55:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:55:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:55:04 INFO - Running setup.py (path:/tmp/pip-Ec0gKt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:55:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:55:04 INFO - Running setup.py (path:/tmp/pip-sAc0Yz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:55:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:55:04 INFO - Running setup.py (path:/tmp/pip-IJCpIt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:55:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:55:04 INFO - Running setup.py (path:/tmp/pip-_voVWr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:55:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:55:04 INFO - Running setup.py (path:/tmp/pip-B1yl8S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:55:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:55:04 INFO - Running setup.py (path:/tmp/pip-UrcTty-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:55:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:55:04 INFO - Running setup.py (path:/tmp/pip-YC4Tz3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:55:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:55:04 INFO - Running setup.py (path:/tmp/pip-QIRJJP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:55:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:55:04 INFO - Running setup.py (path:/tmp/pip-rGK0Pv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:55:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:55:04 INFO - Running setup.py (path:/tmp/pip-QS2HqS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:55:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:55:04 INFO - Running setup.py (path:/tmp/pip-uGRwkR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:55:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:55:04 INFO - Running setup.py (path:/tmp/pip-WUwF43-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:55:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:55:04 INFO - Running setup.py (path:/tmp/pip-6ZDpvi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:55:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:55:05 INFO - Running setup.py (path:/tmp/pip-1hTxrw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:55:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:55:05 INFO - Running setup.py (path:/tmp/pip-KdInSF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:55:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:55:05 INFO - Running setup.py (path:/tmp/pip-gsXTP6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:55:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:55:05 INFO - Running setup.py (path:/tmp/pip-TKHYaB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:55:05 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:55:05 INFO - Running setup.py install for manifestparser 16:55:05 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:55:05 INFO - Running setup.py install for mozcrash 16:55:05 INFO - Running setup.py install for mozdebug 16:55:05 INFO - Running setup.py install for mozdevice 16:55:05 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:55:05 INFO - Running setup.py install for mozfile 16:55:05 INFO - Running setup.py install for mozhttpd 16:55:05 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:55:05 INFO - Running setup.py install for mozinfo 16:55:05 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:55:06 INFO - Running setup.py install for mozInstall 16:55:06 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:55:06 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:55:06 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:55:06 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:55:06 INFO - Running setup.py install for mozleak 16:55:06 INFO - Running setup.py install for mozlog 16:55:06 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:55:06 INFO - Running setup.py install for moznetwork 16:55:06 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:55:06 INFO - Running setup.py install for mozprocess 16:55:06 INFO - Running setup.py install for mozprofile 16:55:06 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:55:06 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:55:06 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:55:06 INFO - Running setup.py install for mozrunner 16:55:06 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:55:06 INFO - Running setup.py install for mozscreenshot 16:55:06 INFO - Running setup.py install for moztest 16:55:06 INFO - Running setup.py install for mozversion 16:55:07 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:55:07 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:55:07 INFO - Cleaning up... 16:55:07 INFO - Return code: 0 16:55:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:55:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:55:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:55:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:55:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:55:07 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 0x1d401f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f0ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20afd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20aceb0>, '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 0x20addb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20ae240>, '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': '31acecf42b6a79ec49471d4a000001bb-1501717684.416508-1647253761', '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 16:55:07 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 16:55:07 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 16:55:07 INFO - Using env: {'DISPLAY': ':0', 16:55:07 INFO - 'HOME': '/home/cltbld', 16:55:07 INFO - 'LANG': 'en_US.UTF-8', 16:55:07 INFO - 'LANGUAGE': 'en_US:en', 16:55:07 INFO - 'LOGNAME': 'cltbld', 16:55:07 INFO - 'MAIL': '/var/mail/cltbld', 16:55:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:55:07 INFO - 'MOZ_NO_REMOTE': '1', 16:55:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:55:07 INFO - 'NO_EM_RESTART': '1', 16:55:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:55:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:55:07 INFO - 'PWD': '/builds/slave/test', 16:55:07 INFO - 'SHELL': '/bin/bash', 16:55:07 INFO - 'SHLVL': '1', 16:55:07 INFO - 'TERM': 'linux', 16:55:07 INFO - 'TMOUT': '86400', 16:55:07 INFO - 'USER': 'cltbld', 16:55:07 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1501717684.416508-1647253761', 16:55:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:55:07 INFO - '_': '/tools/buildbot/bin/python'} 16:55:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:55:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:55:07 INFO - Running setup.py (path:/tmp/pip-Q4O2RJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:55:07 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)) 16:55:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:55:07 INFO - Running setup.py (path:/tmp/pip-Daols0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:55:07 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)) 16:55:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:55:07 INFO - Running setup.py (path:/tmp/pip-3XUHtJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:55:07 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)) 16:55:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:55:07 INFO - Running setup.py (path:/tmp/pip-iaXYuh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:55:07 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)) 16:55:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:55:07 INFO - Running setup.py (path:/tmp/pip-2nLQVU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:55:07 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)) 16:55:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:55:07 INFO - Running setup.py (path:/tmp/pip-km0FuC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:55:07 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)) 16:55:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:55:07 INFO - Running setup.py (path:/tmp/pip-V4Timg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:55:07 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)) 16:55:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:55:07 INFO - Running setup.py (path:/tmp/pip-aIFMk0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:55:07 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)) 16:55:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:55:07 INFO - Running setup.py (path:/tmp/pip-HUcKrT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:55:07 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)) 16:55:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:55:07 INFO - Running setup.py (path:/tmp/pip-sfd_HF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:55:08 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)) 16:55:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:55:08 INFO - Running setup.py (path:/tmp/pip-DMJT83-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:55:08 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)) 16:55:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:55:08 INFO - Running setup.py (path:/tmp/pip-o0mkbw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:55:08 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)) 16:55:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:55:08 INFO - Running setup.py (path:/tmp/pip-dr33ht-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:55:08 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)) 16:55:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:55:08 INFO - Running setup.py (path:/tmp/pip-dRpy6E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:55:08 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)) 16:55:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:55:08 INFO - Running setup.py (path:/tmp/pip-P8WvDy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:55:08 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)) 16:55:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:55:08 INFO - Running setup.py (path:/tmp/pip-If8x9l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:55:08 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)) 16:55:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:55:08 INFO - Running setup.py (path:/tmp/pip-iRXQge-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:55:08 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)) 16:55:08 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)) 16:55:08 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)) 16:55:08 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)) 16:55:08 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)) 16:55:08 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)) 16:55:08 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)) 16:55:08 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:55:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:55:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:55:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:55:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:55:09 INFO - Downloading blessings-1.6.tar.gz 16:55:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:55:09 INFO - Installing collected packages: blessings 16:55:09 INFO - Running setup.py install for blessings 16:55:09 INFO - Successfully installed blessings 16:55:09 INFO - Cleaning up... 16:55:09 INFO - Return code: 0 16:55:09 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:55:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:55:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:55:10 INFO - Reading from file tmpfile_stdout 16:55:10 INFO - Current package versions: 16:55:10 INFO - argparse == 1.2.1 16:55:10 INFO - blessings == 1.6 16:55:10 INFO - blobuploader == 1.2.4 16:55:10 INFO - docopt == 0.6.1 16:55:10 INFO - functools32 == 3.2.3-2 16:55:10 INFO - jsonschema == 2.5.1 16:55:10 INFO - manifestparser == 1.1 16:55:10 INFO - mozInstall == 1.13 16:55:10 INFO - mozcrash == 1.0 16:55:10 INFO - mozdebug == 0.1 16:55:10 INFO - mozdevice == 0.51 16:55:10 INFO - mozfile == 1.2 16:55:10 INFO - mozhttpd == 0.7 16:55:10 INFO - mozinfo == 0.10 16:55:10 INFO - mozleak == 0.1 16:55:10 INFO - mozlog == 3.5 16:55:10 INFO - moznetwork == 0.27 16:55:10 INFO - mozprocess == 0.25 16:55:10 INFO - mozprofile == 0.28 16:55:10 INFO - mozrunner == 6.13 16:55:10 INFO - mozscreenshot == 0.1 16:55:10 INFO - mozsystemmonitor == 0.3 16:55:10 INFO - moztest == 0.8 16:55:10 INFO - mozversion == 1.4 16:55:10 INFO - psutil == 3.1.1 16:55:10 INFO - requests == 1.2.3 16:55:10 INFO - wsgiref == 0.1.2 16:55:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:55:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:55:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:55:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:55:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:55:10 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 0x1d401f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f0ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20afd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20aceb0>, '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 0x20addb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20ae240>, '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': '31acecf42b6a79ec49471d4a000001bb-1501717684.416508-1647253761', '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 16:55:10 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 16:55:10 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 16:55:10 INFO - Using env: {'DISPLAY': ':0', 16:55:10 INFO - 'HOME': '/home/cltbld', 16:55:10 INFO - 'LANG': 'en_US.UTF-8', 16:55:10 INFO - 'LANGUAGE': 'en_US:en', 16:55:10 INFO - 'LOGNAME': 'cltbld', 16:55:10 INFO - 'MAIL': '/var/mail/cltbld', 16:55:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:55:10 INFO - 'MOZ_NO_REMOTE': '1', 16:55:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:55:10 INFO - 'NO_EM_RESTART': '1', 16:55:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:55:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:55:10 INFO - 'PWD': '/builds/slave/test', 16:55:10 INFO - 'SHELL': '/bin/bash', 16:55:10 INFO - 'SHLVL': '1', 16:55:10 INFO - 'TERM': 'linux', 16:55:10 INFO - 'TMOUT': '86400', 16:55:10 INFO - 'USER': 'cltbld', 16:55:10 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1501717684.416508-1647253761', 16:55:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:55:10 INFO - '_': '/tools/buildbot/bin/python'} 16:55:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:55:10 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)) 16:55:10 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)) 16:55:10 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)) 16:55:10 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)) 16:55:10 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)) 16:55:10 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)) 16:55:10 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)) 16:55:10 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)) 16:55:10 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)) 16:55:10 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 16:55:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:55:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:55:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:55:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:55:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 16:55:11 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)) 16:55:11 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)) 16:55:11 INFO - Installing collected packages: simplejson 16:55:11 INFO - Running setup.py install for simplejson 16:55:11 INFO - building 'simplejson._speedups' extension 16:55:11 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 16:55:12 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 16:55:12 INFO - Successfully installed simplejson 16:55:12 INFO - Cleaning up... 16:55:12 INFO - Return code: 0 16:55:12 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:55:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:55:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:55:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:55:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:55:12 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d401f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f0ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20afd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20aceb0>, '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 0x20addb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20ae240>, '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': '31acecf42b6a79ec49471d4a000001bb-1501717684.416508-1647253761', '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 16:55:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 16:55:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 16:55:12 INFO - Using env: {'DISPLAY': ':0', 16:55:12 INFO - 'HOME': '/home/cltbld', 16:55:12 INFO - 'LANG': 'en_US.UTF-8', 16:55:12 INFO - 'LANGUAGE': 'en_US:en', 16:55:12 INFO - 'LOGNAME': 'cltbld', 16:55:12 INFO - 'MAIL': '/var/mail/cltbld', 16:55:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:55:12 INFO - 'MOZ_NO_REMOTE': '1', 16:55:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:55:12 INFO - 'NO_EM_RESTART': '1', 16:55:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:55:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:55:12 INFO - 'PWD': '/builds/slave/test', 16:55:12 INFO - 'SHELL': '/bin/bash', 16:55:12 INFO - 'SHLVL': '1', 16:55:12 INFO - 'TERM': 'linux', 16:55:12 INFO - 'TMOUT': '86400', 16:55:12 INFO - 'USER': 'cltbld', 16:55:12 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1501717684.416508-1647253761', 16:55:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:55:12 INFO - '_': '/tools/buildbot/bin/python'} 16:55:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:55:12 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:55:12 INFO - Cleaning up... 16:55:12 INFO - Return code: 0 16:55:12 INFO - Running post-action listener: _resource_record_post_action 16:55:12 INFO - Running post-action listener: _start_resource_monitoring 16:55:12 INFO - Starting resource monitoring. 16:55:12 INFO - [mozharness: 2017-08-02 23:55:12.785283Z] Finished create-virtualenv step (success) 16:55:12 INFO - [mozharness: 2017-08-02 23:55:12.785756Z] Running install step. 16:55:12 INFO - Running pre-action listener: _resource_record_pre_action 16:55:12 INFO - Running main action method: install 16:55:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:55:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:55:13 INFO - Reading from file tmpfile_stdout 16:55:13 INFO - Detecting whether we're running mozinstall >=1.0... 16:55:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:55:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:55:13 INFO - Reading from file tmpfile_stdout 16:55:13 INFO - Output received: 16:55:13 INFO - Usage: mozinstall [options] installer 16:55:13 INFO - Options: 16:55:13 INFO - -h, --help show this help message and exit 16:55:13 INFO - -d DEST, --destination=DEST 16:55:13 INFO - Directory to install application into. [default: 16:55:13 INFO - "/builds/slave/test"] 16:55:13 INFO - --app=APP Application being installed. [default: firefox] 16:55:13 INFO - mkdir: /builds/slave/test/build/application 16:55:13 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'] 16:55:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 16:55:31 INFO - Reading from file tmpfile_stdout 16:55:31 INFO - Output received: 16:55:31 INFO - /builds/slave/test/build/application/firefox/firefox 16:55:31 INFO - Running post-action listener: _resource_record_post_action 16:55:31 INFO - [mozharness: 2017-08-02 23:55:31.015987Z] Finished install step (success) 16:55:31 INFO - [mozharness: 2017-08-02 23:55:31.016328Z] Running setup-mitmproxy step. 16:55:31 INFO - Running pre-action listener: _resource_record_pre_action 16:55:31 INFO - Running main action method: setup_mitmproxy 16:55:31 INFO - Skipping: mitmproxy is not required 16:55:31 INFO - Running post-action listener: _resource_record_post_action 16:55:31 INFO - [mozharness: 2017-08-02 23:55:31.017998Z] Finished setup-mitmproxy step (success) 16:55:31 INFO - [mozharness: 2017-08-02 23:55:31.018308Z] Running run-tests step. 16:55:31 INFO - Running pre-action listener: _resource_record_pre_action 16:55:31 INFO - Running pre-action listener: _set_gcov_prefix 16:55:31 INFO - Running main action method: run_tests 16:55:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:55:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:55:31 INFO - Python 2.7.3 16:55:31 INFO - Return code: 0 16:55:31 INFO - grabbing minidump binary from tooltool 16:55:31 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')]} 16:55:31 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 0x20aceb0>, '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 0x20addb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20ae240>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:55:31 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 16:55:31 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 16:55:31 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 16:55:31 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:55:31 INFO - Return code: 0 16:55:31 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 16:55:31 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:55:31 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:55:31 INFO - ENV: RUST_BACKTRACE is now 1 16:55:31 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 16:55:31 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:55:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/YjPNvDkxRpGcvZQBdlxoDQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-014', '--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 16:55:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/YjPNvDkxRpGcvZQBdlxoDQ/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-014 --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 16:55:31 INFO - Using env: {'DISPLAY': ':0', 16:55:31 INFO - 'HOME': '/home/cltbld', 16:55:31 INFO - 'LANG': 'en_US.UTF-8', 16:55:31 INFO - 'LANGUAGE': 'en_US:en', 16:55:31 INFO - 'LOGNAME': 'cltbld', 16:55:31 INFO - 'MAIL': '/var/mail/cltbld', 16:55:31 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:55:31 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:55:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:55:31 INFO - 'MOZ_NO_REMOTE': '1', 16:55:31 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:55:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:55:31 INFO - 'NO_EM_RESTART': '1', 16:55:31 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:55:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:55:31 INFO - 'PWD': '/builds/slave/test', 16:55:31 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:55:31 INFO - 'RUST_BACKTRACE': '1', 16:55:31 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 16:55:31 INFO - 'SHELL': '/bin/bash', 16:55:31 INFO - 'SHLVL': '1', 16:55:31 INFO - 'TERM': 'linux', 16:55:31 INFO - 'TMOUT': '86400', 16:55:31 INFO - 'USER': 'cltbld', 16:55:31 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1501717684.416508-1647253761', 16:55:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:55:31 INFO - '_': '/tools/buildbot/bin/python'} 16:55:31 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/YjPNvDkxRpGcvZQBdlxoDQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-014', '--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 16:55:31 INFO - mozversion application_buildid: 20170802224818 16:55:31 INFO - mozversion application_changeset: 4c7317211990d6cf156c103a73a5b3ec41f2dd4d 16:55:31 INFO - mozversion application_display_name: Nightly 16:55:31 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:55:31 INFO - mozversion application_name: Firefox 16:55:31 INFO - mozversion application_remotingname: firefox 16:55:31 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 16:55:31 INFO - mozversion application_vendor: Mozilla 16:55:31 INFO - mozversion application_version: 57.0a1 16:55:31 INFO - mozversion platform_buildid: 20170802224818 16:55:31 INFO - mozversion platform_changeset: 4c7317211990d6cf156c103a73a5b3ec41f2dd4d 16:55:31 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 16:55:31 INFO - mozversion platform_version: 57.0a1 16:55:31 INFO - using testdate: 1501718131 16:55:31 INFO - actual date: 1501718131 16:55:31 INFO - starting webserver on 'localhost:58791' 16:55:31 INFO - SUITE-START | Running 2 tests 16:55:31 INFO - TEST-START | dromaeo_css 16:55:31 INFO - Initialising browser for dromaeo_css test... 16:55:31 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:58791/getInfo.html -profile /tmp/tmpiPS06F/profile 16:55:31 INFO - TEST-INFO | started process 22199 (/builds/slave/test/build/application/firefox/firefox http://localhost:58791/getInfo.html) 16:55:36 INFO - TEST-INFO | 22199: exit 0 16:55:37 INFO - Browser initialized. 16:55:37 INFO - Running cycle 1/1 for dromaeo_css test... 16:55:37 INFO - TEST-INFO | started process 22408 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpiPS06F/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 16:55:37 INFO - PID 22408 | 16:55:37 INFO - PID 22408 | (/builds/slave/test/build/application/firefox/firefox:22457): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 16:55:37 INFO - PID 22408 | 16:55:48 INFO - PID 22408 | RSS: Main: 165036032 16:55:48 INFO - PID 22408 | 16:58:46 INFO - PID 22408 | Cycle 1(1): loaded http://localhost:58791/tests/dromaeo/cssquery-dojo.html (next: http://localhost:58791/tests/dromaeo/cssquery-ext.html) 16:58:47 INFO - PID 22408 | RSS: Main: 167501824 16:58:47 INFO - PID 22408 | 17:01:49 INFO - PID 22408 | Cycle 1(1): loaded http://localhost:58791/tests/dromaeo/cssquery-ext.html (next: http://localhost:58791/tests/dromaeo/cssquery-jquery.html) 17:01:50 INFO - PID 22408 | RSS: Main: 165117952 17:01:50 INFO - PID 22408 | 17:04:51 INFO - PID 22408 | Cycle 1(1): loaded http://localhost:58791/tests/dromaeo/cssquery-jquery.html (next: http://localhost:58791/tests/dromaeo/cssquery-mootools.html) 17:04:52 INFO - PID 22408 | RSS: Main: 167268352 17:04:52 INFO - PID 22408 | 17:07:53 INFO - PID 22408 | Cycle 1(1): loaded http://localhost:58791/tests/dromaeo/cssquery-mootools.html (next: http://localhost:58791/tests/dromaeo/cssquery-prototype.html) 17:07:54 INFO - PID 22408 | RSS: Main: 165240832 17:07:54 INFO - PID 22408 | 17:10:57 INFO - PID 22408 | Cycle 1(1): loaded http://localhost:58791/tests/dromaeo/cssquery-prototype.html (next: http://localhost:58791/tests/dromaeo/cssquery-yui.html) 17:10:58 INFO - PID 22408 | RSS: Main: 169369600 17:10:58 INFO - PID 22408 | 17:14:00 INFO - PID 22408 | Cycle 1(1): loaded http://localhost:58791/tests/dromaeo/cssquery-yui.html (next: http://localhost:58791/tests/dromaeo/cssquery-dojo.html) 17:14:00 INFO - PID 22408 | RSS: Main: 167673856 17:14:00 INFO - PID 22408 | 17:14:00 INFO - PID 22408 | __start_tp_report 17:14:00 INFO - PID 22408 | _x_x_mozilla_page_load 17:14:00 INFO - PID 22408 | _x_x_mozilla_page_load_details 17:14:00 INFO - PID 22408 | |i|pagename|runs| 17:14:00 INFO - PID 22408 | |0;dojo.html;3335.5663763710727;3365.2428203654194;3377.155711072232;3383.3571621391934;3385.06910599585;6096.939030609698;6098.054801505763;6132.662703551303;6138.416850399205;6140;6020.698965051752;6099.5120390368775;6104.5116390688745;6110.144579758838;6115.847103822407;5327.360716714;5338.866528336794;5356.464353564632;5358.4641535846495;5366.141417373221;5387.057264978613;5389.434109418514;5422.91865622016;5436.483534064258;5469.124940009599;5393.406725260218;5399.78400863965;5427.62006659534;5442.346918369782;5450.618456708032;5350.170723537858;5406.675599464044;5417.404085550586;5422.024035673617;5476.397596264408;5175.637705360627;5252.054630166568;5288.524032837025;5303.575713942875;5377.677339359651;5911.438413350725;5982.581219314649;6004.399560044004;6004.8498787530225;6072;6412.3267056959285;6442.4201821835795;6451.1936007999;6501.187351581052;6539.44414724749;6588.670566471706;6608.074869518271;6627.966860165717;6634.203895532567;6635.236947751022;6090.604110732788;6104.2980057293535;6107.938920610826;6108.205933228652;6114.633122012649;6750.797476075725;6757.864842703217;6760.763373281911;6776.593404395752;6842.657867106725;6501.284858665543;6533.640649764214;6570.441512471493;6573.83565410859;6582.63795491243;6604.075429439882;6607.174103237096;6653.135092438049;6698.095757072733;6712.697928593225;6374.394432528902;6493.058506516529;6494.740210391531;6509.9023514647315;6620.205575330895;6497;6518.608883467007;6525;6525.608463492205;6552.6068435894;4801.663883528119;4808.0383923215495;4808.567228949411;4815.686980346266;4824.927626085611;4317.352397140454;4327.091310824699;4373.781310934441;4377.4965878923695;4393.406890069864;6094.908576371358;6153.692315384213;6164.321924588292;6186.969065154645;6299.118123462719;6536;6609.239937407164;6648.900266496006;6650;6660.9000864987065;6214.161088253028;6227.439530442282;6255.12428260044;6272.968635156795;6277.435030847154;5414.864628384243;5422.430644782241;5456;5477.780888764484;5516.475934786189;7043.225245223124;7054.6825392857445;7067.328603782633;7067.89398159028;7075.646217689198;6569.572977756622;6588.736450541925;6599.934000659932;6621.867562648817;6638.867222655424;6913.723451061901;6985.860282794214;6999.090118284794;7091.751788687371;7097.112860892388;6435.2599451063725;6500.479961602966;6503.122078519339;6536.542442029012;6582.045603387578;6517.348265173445;6672.799816005528;6684.465242780663;6697.397234248941;6704.128463299935;6429;6440.32376600469;6458.257300410513;6520.347965203442;6529.249136349381;6455.128557644658;6455.644939528371;6508.08886755864;6541.08424820535;6541.476681865344;6327.936720632919;6402.839929001812;6409.455196308179;6415.935840641534;6419.486441084796;6485.416312531895;6485.740570377132;6494;6519.641419721961;6522.934770652422;6180.876382472416;6195.16365290698;6229.968850155901;6255.749770009149;6268.091126786499 17:14:00 INFO - PID 22408 | |1;ext.html;883.8271495091499;908.550267617529;935;955.7849483866127;964.6141543382646;42469.57530424695;43188.56811431885;43368.566314336844;43440.56559434405;43542.129157416835;40503.59496405035;40643.18713625733;40820.59179408205;40962.180756384856;41056;6777.085093512386;6907.827304317407;6916.585004899697;6930.06444130041;6933.064036355103;6775.525713200078;6879.174499060095;6884.793456196322;6885.070515480396;6892.138482689664;6856.211535673387;6894.620795856226;6895.55178913369;6897.517173797836;7260.963695181516;7451.515651482637;7475.626218689098;7498.250174982512;7498.47510674253;7510.061242344707;7325.340719335285;7399.593022383767;7406.8888966665545;7408.59252741099;7411.555306681563;39903.401448978286;40318.39522407166;40458;40493;40765;3384.255463797985;3406.744494162923;3408.5057666638445;3408.642092580268;3414.351273258073;31103;31495.685043149733;31541;31764.04707858768;31845.52231716526;4925.310456536088;4983.775730092155;5014.448410674823;5030.496950304977;5052.368453943257;207995;209069;213524;216730;229323.99999999834;53904.46095539073;54096.72951635216;54175.45824541704;54185.458145418044;54204.45795542073;56268.718656406454;56276.15585766217;56416.43583564112;56437.024704135976;56550.71724641351;16054.518364449084;16185.271662775203;16209.10849903243;16218.513444596681;16354.836451635569;12927.224366538037;12970.092093553549;12975.610731678065;13023.47906083765;13113.016523760756;15486.5354039379;15496.380144794308;15531.301091450912;15541.98977066487;15570.9221453892;6014.8195554133445;6021.608595441282;6131.172291740647;6135.202423684982;6191.288001879751;1071.7749272652675;1081.1783044885785;1088.1240601315858;1097.434821067151;1101.5483651702764;24801.379965500644;24843.5031299373;25186;25294.620580691306;25550.744492555208;1191.5233906437495;1194.814803705427;1199.3463562358536;1209.7278112424633;1214.4656351205442;13259.469621215236;13284.335783210801;13507.324633768272;13564.525241616497;13761.243131627856;263384;265400;266309;266764;267461;56784;56828.71585642045;56980.14529782056;57142;57301.42698572961;1502.1512845242403;1513.8713209377106;1543.1744016951102;1543.3055125193707;1556.3152213026233;24691.87654061718;30111.698883011766;30127.548086779156;30499.847500762353;30988.535171971987;56194.71902640461;56795.148072778116;56823.86352272849;56832.715836422205;57082;235586;237931;238797;239294;240178;36252.27495450024;38016.429753553166;38195.236095278495;38251;38407.80796096002;45452;46159.3076103852;46671;46876;47219.527804722886;34568.82715586406;34900.47649285314;34915.65084349124;34929.65070349264;35260;34344.48483272803;34686.30627387387;34755.65244347524;34775.304493909476;35072.64927350796;39681.20637587174;39818.60181398149;39981;40070.59929400785;40146.79926600348;35009.64990350064;35076.473852891715;35787.28425431529;35791;35893.28213435665;26377.08122951053;27977.860110699316;28021.43957120887;28146.577801332587;28209.43581128407 17:14:00 INFO - PID 22408 | |2;jquery.html;558.1599692462843;573.406524247404;584.2229834320354;585.4496773033349;592.8725324055328;21278.57442851144;21954;22006.229781957634;22088;23168.42078948027;26217.213483595475;26609.733902661017;26809.731902680967;26967.05615303465;26968.5954710679;6208.8447788805415;6313.747450101991;6338.492920566344;6345.809625711213;6351.936480635204;6032.155498230251;6261.592996455239;6280.403361680633;6285.434310912017;6292.905606415908;6225.377462253761;6227.719752611143;6229.563930524865;6236.126942228092;6238.376162383748;6127.019676851683;6195.411435913581;6219.067139929024;6223.937760622381;6232.750689972396;5775;5789.2763404574425;5790.971045144789;5804;5826.766929322822;29454;30067.045642033198;30617.234569135726;30676.69323306783;30776.846115769276;2147.5490147069136;2187.628103222456;2188.616992026389;2189.419803752007;2189.8357623178167;19010.80989190104;19443.708344374845;19476.80523194764;19554.804451955442;19654.508637284038;2369.2399380578013;2403.579373609629;2439.5852705040184;2487.664165337674;2493;150673;154490.22754886266;154659;154709;154802;14719.411223550938;15072.321745521478;15898;15997.360105595879;16428.42500512476;23957;24063.639045414333;24075.51848963011;24096;24142.7585724144;10693.518791654395;10793.29843560166;10804.731006488568;10805.758272518213;10943.45282735876;8949.507777072193;9402.435853848654;9765;10162.796744065225;10208.2343824212;9476.336656433983;9594.601349662584;9605.135537801492;9683.225341972626;9888.901110988798;2803.3832556837465;2817.9295517612222;2818.1827270091444;2836.432713457317;2907.8109922854946;1011.630754774502;1019.23557332001;1024.52871679027;1033.256055639953;1036.8185567525652;20532.589348213252;20572.074256658485;20927.47681307949;21148.15407383718;21243;1079.6976846482996;1117.044926587772;1128.480898786551;1134.325076579434;1138.425095326871;11379.260348077349;11505.827412588818;11568;11605.535778568932;11688.357140357362;583727;588791;597130;611292;624083;124342.37828810979;126081;127236.36381818031;132493.33753331171;132740.33629831983;1200.9759804803855;1232.211384713784;1250.118666340228;1253.035162924543;1253.4234252243887;18544.721829172842;18638.72041919345;18649.90675046616;22623.095076197427;22760.992581632014;65295;65363.34636653573;66219.33780662132;67060;67404.6629766848;83145.58427207825;86876;87726.01616668695;88787;89263.55368223117;15340.693186136437;15645.295961681524;15701.842981570038;15712.60718482047;15722.606934826721;24610.876945615157;24816.627750584117;24933.376665583506;24944.75055249497;25002.624960625242;19127.904360478107;19332;19353.70969435488;19702.704459432833;19814;17645.470635880432;17955.73066404031;18099.547511311797;18162.636747265246;18609.44171674852;19714.309999149962;19918.203271868962;20032.298869539496;20033.59932801365;20810.583788324453;15193.164375959428;15334.9233253833;15340.309686064376;15453.304601293194;15483.071015739091;19368.90315548413;19574.21703131859;19651.90174049178;19743.308984185485;19934.401967940987 17:14:00 INFO - PID 22408 | |3;mootools.html;1430.9642258943525;1480.9115300254311;1485.0495682763026;1495.7756336549523;1499.4976682811264;15099.773503397459;15211.011284266515;15760.921195394021;15775.132367719785;15827.287772050257;16068.83931160688;16351.100689462075;16364.018158910474;16389.262483188257;16397.918010409932;2648.708642049373;2717.415755612543;2722.823016503931;2723.2919440945398;2726.2775364528416;2450.4853980664084;2594.2087663262696;2692.8249663771794;2715.701272859984;2749.1889892481768;2608.491344187884;2624.042224588031;2639.8020148488845;2639.8944042238286;2640.801939854509;2668.9599656005175;2683.235277945786;2686.1538615336203;2694.6227528146073;2695.5417579011614;2584.5218634552666;2643.0881345935622;2654.9469010619678;2658.4550167215675;2662.0948877381784;15319.31063102163;15721.292541835644;15802.051876887424;15813.841861581352;15818.209089545477;8013.198680131998;8296.626651800683;8300.958495207486;8307.169283071706;8317.251447369767;5096.362954630671;5256.579473642099;5297.258383826267;5305.469453054702;5376.650517716336;1963.2049020146862;1968.7261213298664;1979.6931475621302;2010.5275260313815;2021.858469907107;27349.316267093283;27460.725392746215;27498.17505474819;27634.170974870987;27696.584551231746;6609.537332386687;7241.167265764505;7242.49302548827;7522.172561018284;7537.886931696029;18372.08139593015;18512.537186570444;18514.074296285133;18517.07414629263;18567;8019.599020048974;8152.592370381457;8195.508269503849;8203.8359232815;8237.299829514439;7360.815979600446;7532.058492688414;7533.773986780406;7574.962125189449;7575.659095340613;5027.095122877844;5222.529972302435;5262.631615786858;5286.074936886106;5299;1300.6148451898887;1342.684469149749;1413.922234277104;1443.501991812823;1447.471672839406;953.8521529162991;965.3194497878978;968.6464440479174;976.6044751875502;999.2605471950704;8795.98846132703;9023.81952360949;9154.26765858743;9219.539023048956;9290.16388525036;1035.1563475767225;1050.333038520545;1056.7939251845817;1058.666520046183;1067.0183431243274;5434.510894019557;5449.536789372868;5549.223108764776;5644.294463192101;5867.090600956859;21671.458213544473;22832.65751013736;22974.310770676908;23025.654615180785;23078.192263270706;21164.682529762067;21997;22321.888390557942;22396.44008899758;22396.440088997904;12062.939685301517;12230.75538489243;12370.62888113358;12375.62873113826;12392.008639309013;9018.053104424203;9026.954865225895;9104.180623743894;9147.0852914708;9167;19274;19314.903425482782;19362;19793.109310080792;20011.59976800485;19856.90071549633;19866.404007879788;19904.60190796205;20034.499137521678;20280.087396066916;14076.296185190904;14448.855511445172;14452.421903124177;14455.566333009605;14466.132032078109;13918.51285205013;14291.64270893236;14362.569122926328;14400.415954244816;14488.492902748354;10910.127189824954;11134.33194008362;11365.090792736726;11631.88368116308;11672;11092.33445993243;11197.160212983896;11300.378479183724;11391.943040285078;11487.94256028748;10812.405317707602;10851.782964340828;10858.402787846428;10961.725956851142;10965;7775.650095745592;8089.393295502743;8122.350211982909;8253.463524870864;8255.463394879314;7899.68401263943;7903.525788452711;7910.8813367798375;7952.284294413531;8035.915151454479 17:14:00 INFO - PID 22408 | |4;prototype.html;493.29458873810484;506.08903972848884;513.304472439844;516.4190285928331;539.8326518779179;6009.87980240395;6057.878842423154;6107.480864126549;6118.969405152968;6142.754289828407;5922.289325280962;5954.285485741706;5956.612820166686;5966.403359664031;6075.3547871277215;4655.627549796008;4732.124556956956;4734.124187025394;4742.691725037862;4758.238681810911;4726.629277509535;4770.904581908359;4791.784369703371;4799.760011999403;4805.399325084347;4654.232051711463;4667.649926255544;4687.929681054787;4746.952530474686;4761.333413322138;4695;4721.362616046824;4749.667523273372;4766;4783.162946484351;4267.957320426787;4310.525842157392;4320.956790432118;4338.110687309094;4339.849939765964;5856.121581762727;5925.525957923356;5936.228290322274;5981.671008094553;5992.400759924017;5594.748236329375;5620.932022915633;5651.350094739117;5705.942940570582;5709.143628455765;6397.968010159966;6418.646974416405;6422.34833346652;6450.709718062699;6467.385598368148;4771.139255690284;4798.280257961299;4812.205986012304;4849.660523763338;4865.245886887538;6466.288708242089;6473.676316184172;6597.142371491724;6704.1954965403975;6732.1921369435495;4956.529129732669;4987.650864439454;4994.775235114428;4996.975015124901;5039.21892106724;5028.145215313405;5042.369703787027;5092.719900405439;5101.158308879068;5105.668131571436;5568.554515638739;5591.888162236733;5608.551315894719;5635.295588051494;5657.4059723728415;6432.935670643234;6460.483161347081;6465.028166409196;6468.288488266287;6492.35076492347;1750.3348727483728;1766.0375095572945;1768.6727955328306;1777.2002598830577;1779.8932064076198;1178.9469473873696;1211.6486218996567;1216.1608490141773;1247.8065899785547;1257.993710031444;6022.789202377983;6047.334793172748;6060.242529666348;6081;6144;6548.919428294278;6590.5716128451495;6596.505262105361;6599.967000164968;6620.867582648417;6061.939380606225;6062.696865156725;6071.51427885768;6091.269047714302;6113.388661133851;3471.5834099907665;3505.544279243733;3505.964940350615;3506.1234691327168;3530.7881527107943;7030.824229394306;7057.400120989773;7094.68073936674;7115.964420177866;7127.572345659173;6786.219584747719;6810.8637827244165;6835.384815366641;6846.459546601975;6851.588904665736;7015.89476157858;7047.154341479055;7102.68037938294;7137.393321567621;7144.607046612487;6115.480184184216;6501.414872661483;6534.444572211415;6557.901631475436;6575.638339891352;6415.326390729093;6453.354664533509;6483.4164925156965;6487.837804054936;6566.474682025522;6961.303869612998;6986.720531178696;7006.299370062953;7085.929140708527;7088.397486213729;4921.975390123026;4923.803047878045;4923.926141107814;4934.457209706858;4940.654154209171;6301.495880329471;6458.967705161444;6612.445545741408;6625.238097618835;6683.365080317362;6310.274318453253;6310.305866354605;6337.936620633919;6366.77716279928;6398.712057957497;6318.873622527615;6341.131909535087;6394.593189498303;6405.455536279282;6409.9679501602195;6288.842778930563;6353.7140828661895;6359.268684101203;6388.009858471944;6443.96778016107;6334.239891213084;6400.487960963019;6410.8076757697345;6418.261899881573;6466.838329041811;6163.660998645118;6193.504519638328;6226.750929962751;6239.75040998355;6310.305866354789 17:14:00 INFO - PID 22408 | |5;yui.html;945.730247406225;948.0093302498889;968.2834702320285;974.3325821812059;981.0581841432224;18429.078546072677;19005;19420.223191072375;19518;19595.21619135233;21066.89466552665;21420.678689819666;21551.24570640024;21569.67645485319;21772.237971670995;946.8863736351612;956.0391806234725;956.2923436656894;956.5456408206117;974.5614473486924;1032.4940779018264;1070.882202957674;1071.6410002649122;1072.4101744040786;1081.8377243413472;1147.3345459633435;1155.2548606149028;1155.566662501562;1155.8959693627571;1155.9826602600926;1116.7208197950513;1118.2675347647305;1118.9216754827166;1120.238237998161;1121.3216004317394;817.5626040068535;823.1028179284608;823.6787652815407;823.8558252305822;823.872299793533;20187.19251229949;20492.487687807923;20751.481212969644;20777;20862.26982055621;20909.686354704692;20981.68527472089;21026.053827577794;21104.472388190417;21330.5733885323;15909.363625454967;16114.919425402797;16189.514314570582;16263.756043659354;16283.592910177222;3679.22736225393;3689.7786132832116;3691.630836916314;3700.907477313062;3722.1625134344827;144710.2764486192;145678;146341;147217;147233;16202.513924582281;16800.243989020524;16866.409675661293;16876.15619219034;16882.831171688125;32031;32094.679053209635;32181.35637287288;32235.516467253008;32411.351772964408;8443.324534037261;8658.610362533702;8689.217970382695;8711.782205444915;8712.128787121237;4447.488538818013;4510.436195475566;4515.345274935116;4524.411826462539;4581.40441742578;5185.766640501187;5308;5338.652987555796;5340.332458442695;5395.973020134874;1076.860008198929;1117.1118960426481;1126.6282126898104;1126.7408496045955;1127.3405058040973;719.0903507063464;727.9672414741349;739.9223081576465;745.0128579632009;755.8979537762442;11810.881891180978;13021.609351719464;13158.868411315956;13226.470941162439;13243.93378033123;693.8022663540968;696.1194089476816;702.8559145375186;709.0286307758404;710.0804458226609;5866.677332746654;5882.764689412375;6018.548608854353;6030.668313242726;6079.05774604937;127101.36449317694;127115;133222;134198;134516;110725;112975.43512282387;113335.43332283286;115374.42312788383;118164;96678;101388;103346.48326758319;105562;107354.46322768336;13674.863251367358;13753.312334383041;13853.584392468241;13871.722565548835;14026.368813403626;43465.3480197791;46714.766426167655;49199;51153.74423127861;52118.17586384458;90059;90598.54700726454;91141.54429227811;91817;91896.54051729699;19675.8032419674;19915.800841991393;19981.500462488555;20336.898315508326;20471.59056818885;21213.469663257925;21309.36071917845;21332.36002919915;21402.250921218307;21543.784562154804;19401.32095376655;19661.115249813505;19670.213191472754;19689.8031019688;19847.404577862686;19078.332258371447;19099.427017189508;19114.52213694669;19313.90343048276;22685.43286417853;21857.234996775038;22022.779772202714;22080.779192208516;22530.774692252868;22709;14039.227842468763;14557.34491947886;14778.408863645334;15006.2496875158;15603.453879114177;14922.030068045977;15105.942584019012;16041.197940102715;16167.838321617104;16278 17:14:00 INFO - PID 22408 | __end_tp_report 17:14:00 INFO - PID 22408 | __start_cc_report 17:14:00 INFO - PID 22408 | _x_x_mozilla_cycle_collect,330 17:14:00 INFO - PID 22408 | __end_cc_report 17:14:00 INFO - PID 22408 | __startTimestamp1501719240293__endTimestamp 17:14:00 INFO - PID 22408 | 17:14:00 INFO - PID 22408 | ------- Summary: start ------- 17:14:00 INFO - PID 22408 | Number of tests: 6 17:14:00 INFO - PID 22408 | 17:14:00 INFO - PID 22408 | [#0] dojo.html Cycles:165 Average:6069.37 Median:6402.84 stddev:786.78 (12.3%) stddev-sans-first:759.40 17:14:00 INFO - PID 22408 | Values: 3335.6 3365.2 3377.2 3383.4 3385.1 6096.9 6098.1 6132.7 6138.4 6140.0 6020.7 6099.5 6104.5 6110.1 6115.8 5327.4 5338.9 5356.5 5358.5 5366.1 5387.1 5389.4 5422.9 5436.5 5469.1 5393.4 5399.8 5427.6 5442.3 5450.6 5350.2 5406.7 5417.4 5422.0 5476.4 5175.6 5252.1 5288.5 5303.6 5377.7 5911.4 5982.6 6004.4 6004.8 6072.0 6412.3 6442.4 6451.2 6501.2 6539.4 6588.7 6608.1 6628.0 6634.2 6635.2 6090.6 6104.3 6107.9 6108.2 6114.6 6750.8 6757.9 6760.8 6776.6 6842.7 6501.3 6533.6 6570.4 6573.8 6582.6 6604.1 6607.2 6653.1 6698.1 6712.7 6374.4 6493.1 6494.7 6509.9 6620.2 6497.0 6518.6 6525.0 6525.6 6552.6 4801.7 4808.0 4808.6 4815.7 4824.9 4317.4 4327.1 4373.8 4377.5 4393.4 6094.9 6153.7 6164.3 6187.0 6299.1 6536.0 6609.2 6648.9 6650.0 6660.9 6214.2 6227.4 6255.1 6273.0 6277.4 5414.9 5422.4 5456.0 5477.8 5516.5 7043.2 7054.7 7067.3 7067.9 7075.6 6569.6 6588.7 6599.9 6621.9 6638.9 6913.7 6985.9 6999.1 7091.8 7097.1 6435.3 6500.5 6503.1 6536.5 6582.0 6517.3 6672.8 6684.5 6697.4 6704.1 6429.0 6440.3 6458.3 6520.3 6529.2 6455.1 6455.6 6508.1 6541.1 6541.5 6327.9 6402.8 6409.5 6415.9 6419.5 6485.4 6485.7 6494.0 6519.6 6522.9 6180.9 6195.2 6230.0 6255.7 6268.1 17:14:00 INFO - PID 22408 | 17:14:00 INFO - PID 22408 | [#1] ext.html Cycles:180 Average:42343.27 Median:37134.35 stddev:62633.71 (168.7%) stddev-sans-first:62732.05 17:14:00 INFO - PID 22408 | Values: 883.8 908.6 935.0 955.8 964.6 42469.6 43188.6 43368.6 43440.6 43542.1 40503.6 40643.2 40820.6 40962.2 41056.0 6777.1 6907.8 6916.6 6930.1 6933.1 6775.5 6879.2 6884.8 6885.1 6892.1 6856.2 6894.6 6895.6 6897.5 7261.0 7451.5 7475.6 7498.3 7498.5 7510.1 7325.3 7399.6 7406.9 7408.6 7411.6 39903.4 40318.4 40458.0 40493.0 40765.0 3384.3 3406.7 3408.5 3408.6 3414.4 31103.0 31495.7 31541.0 31764.0 31845.5 4925.3 4983.8 5014.4 5030.5 5052.4 207995.0 209069.0 213524.0 216730.0 229324.0 53904.5 54096.7 54175.5 54185.5 54204.5 56268.7 56276.2 56416.4 56437.0 56550.7 16054.5 16185.3 16209.1 16218.5 16354.8 12927.2 12970.1 12975.6 13023.5 13113.0 15486.5 15496.4 15531.3 15542.0 15570.9 6014.8 6021.6 6131.2 6135.2 6191.3 1071.8 1081.2 1088.1 1097.4 1101.5 24801.4 24843.5 25186.0 25294.6 25550.7 1191.5 1194.8 1199.3 1209.7 1214.5 13259.5 13284.3 13507.3 13564.5 13761.2 263384.0 265400.0 266309.0 266764.0 267461.0 56784.0 56828.7 56980.1 57142.0 57301.4 1502.2 1513.9 1543.2 1543.3 1556.3 24691.9 30111.7 30127.5 30499.8 30988.5 56194.7 56795.1 56823.9 56832.7 57082.0 235586.0 237931.0 238797.0 239294.0 240178.0 36252.3 38016.4 38195.2 38251.0 38407.8 45452.0 46159.3 46671.0 46876.0 47219.5 34568.8 34900.5 34915.7 34929.7 35260.0 34344.5 34686.3 34755.7 34775.3 35072.6 39681.2 39818.6 39981.0 40070.6 40146.8 35009.6 35076.5 35787.3 35791.0 35893.3 26377.1 27977.9 28021.4 28146.6 28209.4 17:14:00 INFO - PID 22408 | 17:14:00 INFO - PID 22408 | [#2] jquery.html Cycles:180 Average:39814.26 Median:12071.92 stddev:100833.75 (835.3%) stddev-sans-first:101073.53 17:14:00 INFO - PID 22408 | Values: 558.2 573.4 584.2 585.4 592.9 21278.6 21954.0 22006.2 22088.0 23168.4 26217.2 26609.7 26809.7 26967.1 26968.6 6208.8 6313.7 6338.5 6345.8 6351.9 6032.2 6261.6 6280.4 6285.4 6292.9 6225.4 6227.7 6229.6 6236.1 6238.4 6127.0 6195.4 6219.1 6223.9 6232.8 5775.0 5789.3 5791.0 5804.0 5826.8 29454.0 30067.0 30617.2 30676.7 30776.8 2147.5 2187.6 2188.6 2189.4 2189.8 19010.8 19443.7 19476.8 19554.8 19654.5 2369.2 2403.6 2439.6 2487.7 2493.0 150673.0 154490.2 154659.0 154709.0 154802.0 14719.4 15072.3 15898.0 15997.4 16428.4 23957.0 24063.6 24075.5 24096.0 24142.8 10693.5 10793.3 10804.7 10805.8 10943.5 8949.5 9402.4 9765.0 10162.8 10208.2 9476.3 9594.6 9605.1 9683.2 9888.9 2803.4 2817.9 2818.2 2836.4 2907.8 1011.6 1019.2 1024.5 1033.3 1036.8 20532.6 20572.1 20927.5 21148.2 21243.0 1079.7 1117.0 1128.5 1134.3 1138.4 11379.3 11505.8 11568.0 11605.5 11688.4 583727.0 588791.0 597130.0 611292.0 624083.0 124342.4 126081.0 127236.4 132493.3 132740.3 1201.0 1232.2 1250.1 1253.0 1253.4 18544.7 18638.7 18649.9 22623.1 22761.0 65295.0 65363.3 66219.3 67060.0 67404.7 83145.6 86876.0 87726.0 88787.0 89263.6 15340.7 15645.3 15701.8 15712.6 15722.6 24610.9 24816.6 24933.4 24944.8 25002.6 19127.9 19332.0 19353.7 19702.7 19814.0 17645.5 17955.7 18099.5 18162.6 18609.4 19714.3 19918.2 20032.3 20033.6 20810.6 15193.2 15334.9 15340.3 15453.3 15483.1 19368.9 19574.2 19651.9 19743.3 19934.4 17:14:00 INFO - PID 22408 | 17:14:00 INFO - PID 22408 | [#3] mootools.html Cycles:180 Average:9833.67 Median:2517.50 stddev:7019.53 (278.8%) stddev-sans-first:7010.84 17:14:00 INFO - PID 22408 | Values: 1431.0 1480.9 1485.0 1495.8 1499.5 15099.8 15211.0 15760.9 15775.1 15827.3 16068.8 16351.1 16364.0 16389.3 16397.9 2648.7 2717.4 2722.8 2723.3 2726.3 2450.5 2594.2 2692.8 2715.7 2749.2 2608.5 2624.0 2639.8 2639.9 2640.8 2669.0 2683.2 2686.2 2694.6 2695.5 2584.5 2643.1 2654.9 2658.5 2662.1 15319.3 15721.3 15802.1 15813.8 15818.2 8013.2 8296.6 8301.0 8307.2 8317.3 5096.4 5256.6 5297.3 5305.5 5376.7 1963.2 1968.7 1979.7 2010.5 2021.9 27349.3 27460.7 27498.2 27634.2 27696.6 6609.5 7241.2 7242.5 7522.2 7537.9 18372.1 18512.5 18514.1 18517.1 18567.0 8019.6 8152.6 8195.5 8203.8 8237.3 7360.8 7532.1 7533.8 7575.0 7575.7 5027.1 5222.5 5262.6 5286.1 5299.0 1300.6 1342.7 1413.9 1443.5 1447.5 953.9 965.3 968.6 976.6 999.3 8796.0 9023.8 9154.3 9219.5 9290.2 1035.2 1050.3 1056.8 1058.7 1067.0 5434.5 5449.5 5549.2 5644.3 5867.1 21671.5 22832.7 22974.3 23025.7 23078.2 21164.7 21997.0 22321.9 22396.4 22396.4 12062.9 12230.8 12370.6 12375.6 12392.0 9018.1 9027.0 9104.2 9147.1 9167.0 19274.0 19314.9 19362.0 19793.1 20011.6 19856.9 19866.4 19904.6 20034.5 20280.1 14076.3 14448.9 14452.4 14455.6 14466.1 13918.5 14291.6 14362.6 14400.4 14488.5 10910.1 11134.3 11365.1 11631.9 11672.0 11092.3 11197.2 11300.4 11391.9 11487.9 10812.4 10851.8 10858.4 10961.7 10965.0 7775.7 8089.4 8122.4 8253.5 8255.5 7899.7 7903.5 7910.9 7952.3 8035.9 17:14:00 INFO - PID 22408 | 17:14:00 INFO - PID 22408 | [#4] prototype.html Cycles:180 Average:5467.04 Median:6067.11 stddev:1562.45 (25.8%) stddev-sans-first:1521.58 17:14:00 INFO - PID 22408 | Values: 493.3 506.1 513.3 516.4 539.8 6009.9 6057.9 6107.5 6119.0 6142.8 5922.3 5954.3 5956.6 5966.4 6075.4 4655.6 4732.1 4734.1 4742.7 4758.2 4726.6 4770.9 4791.8 4799.8 4805.4 4654.2 4667.6 4687.9 4747.0 4761.3 4695.0 4721.4 4749.7 4766.0 4783.2 4268.0 4310.5 4321.0 4338.1 4339.8 5856.1 5925.5 5936.2 5981.7 5992.4 5594.7 5620.9 5651.4 5705.9 5709.1 6398.0 6418.6 6422.3 6450.7 6467.4 4771.1 4798.3 4812.2 4849.7 4865.2 6466.3 6473.7 6597.1 6704.2 6732.2 4956.5 4987.7 4994.8 4997.0 5039.2 5028.1 5042.4 5092.7 5101.2 5105.7 5568.6 5591.9 5608.6 5635.3 5657.4 6432.9 6460.5 6465.0 6468.3 6492.4 1750.3 1766.0 1768.7 1777.2 1779.9 1178.9 1211.6 1216.2 1247.8 1258.0 6022.8 6047.3 6060.2 6081.0 6144.0 6548.9 6590.6 6596.5 6600.0 6620.9 6061.9 6062.7 6071.5 6091.3 6113.4 3471.6 3505.5 3506.0 3506.1 3530.8 7030.8 7057.4 7094.7 7116.0 7127.6 6786.2 6810.9 6835.4 6846.5 6851.6 7015.9 7047.2 7102.7 7137.4 7144.6 6115.5 6501.4 6534.4 6557.9 6575.6 6415.3 6453.4 6483.4 6487.8 6566.5 6961.3 6986.7 7006.3 7085.9 7088.4 4922.0 4923.8 4923.9 4934.5 4940.7 6301.5 6459.0 6612.4 6625.2 6683.4 6310.3 6310.3 6337.9 6366.8 6398.7 6318.9 6341.1 6394.6 6405.5 6410.0 6288.8 6353.7 6359.3 6388.0 6444.0 6334.2 6400.5 6410.8 6418.3 6466.8 6163.7 6193.5 6226.8 6239.8 6310.3 17:14:00 INFO - PID 22408 | 17:14:00 INFO - PID 22408 | [#5] yui.html Cycles:180 Average:27186.18 Median:20764.24 stddev:38374.16 (184.8%) stddev-sans-first:38431.23 17:14:00 INFO - PID 22408 | Values: 945.7 948.0 968.3 974.3 981.1 18429.1 19005.0 19420.2 19518.0 19595.2 21066.9 21420.7 21551.2 21569.7 21772.2 946.9 956.0 956.3 956.5 974.6 1032.5 1070.9 1071.6 1072.4 1081.8 1147.3 1155.3 1155.6 1155.9 1156.0 1116.7 1118.3 1118.9 1120.2 1121.3 817.6 823.1 823.7 823.9 823.9 20187.2 20492.5 20751.5 20777.0 20862.3 20909.7 20981.7 21026.1 21104.5 21330.6 15909.4 16114.9 16189.5 16263.8 16283.6 3679.2 3689.8 3691.6 3700.9 3722.2 144710.3 145678.0 146341.0 147217.0 147233.0 16202.5 16800.2 16866.4 16876.2 16882.8 32031.0 32094.7 32181.4 32235.5 32411.4 8443.3 8658.6 8689.2 8711.8 8712.1 4447.5 4510.4 4515.3 4524.4 4581.4 5185.8 5308.0 5338.7 5340.3 5396.0 1076.9 1117.1 1126.6 1126.7 1127.3 719.1 728.0 739.9 745.0 755.9 11810.9 13021.6 13158.9 13226.5 13243.9 693.8 696.1 702.9 709.0 710.1 5866.7 5882.8 6018.5 6030.7 6079.1 127101.4 127115.0 133222.0 134198.0 134516.0 110725.0 112975.4 113335.4 115374.4 118164.0 96678.0 101388.0 103346.5 105562.0 107354.5 13674.9 13753.3 13853.6 13871.7 14026.4 43465.3 46714.8 49199.0 51153.7 52118.2 90059.0 90598.5 91141.5 91817.0 91896.5 19675.8 19915.8 19981.5 20336.9 20471.6 21213.5 21309.4 21332.4 21402.3 21543.8 19401.3 19661.1 19670.2 19689.8 19847.4 19078.3 19099.4 19114.5 19313.9 22685.4 21857.2 22022.8 22080.8 22530.8 22709.0 14039.2 14557.3 14778.4 15006.2 15603.5 14922.0 15105.9 16041.2 16167.8 16278.0 17:14:00 INFO - PID 22408 | -------- Summary: end -------- 17:14:00 INFO - PID 22408 | 17:14:00 INFO - TEST-INFO | 22408: exit 0 17:14:00 INFO - TEST-OK | dromaeo_css | took 1109526ms 17:14:00 INFO - TEST-START | kraken 17:14:00 INFO - Initialising browser for kraken test... 17:14:00 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:58791/getInfo.html -profile /tmp/tmps_ri1c/profile 17:14:00 INFO - TEST-INFO | started process 22768 (/builds/slave/test/build/application/firefox/firefox http://localhost:58791/getInfo.html) 17:14:06 INFO - TEST-INFO | 22768: exit 0 17:14:06 INFO - Browser initialized. 17:14:06 INFO - Running cycle 1/1 for kraken test... 17:14:06 INFO - TEST-INFO | started process 22979 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmps_ri1c/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 17:14:07 INFO - PID 22979 | 17:14:07 INFO - PID 22979 | (/builds/slave/test/build/application/firefox/firefox:23027): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 17:14:07 INFO - PID 22979 | 17:14:17 INFO - PID 22979 | RSS: Main: 165285888 17:14:17 INFO - PID 22979 | 17:14:20 INFO - PID 22979 | Cycle 1(1): loaded http://localhost:58791/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:58791/tests/kraken/driver.html?testName=audio-beat-detection) 17:14:21 INFO - PID 22979 | RSS: Main: 170274816 17:14:21 INFO - PID 22979 | 17:14:23 INFO - PID 22979 | Cycle 1(1): loaded http://localhost:58791/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:58791/tests/kraken/driver.html?testName=audio-dft) 17:14:24 INFO - PID 22979 | RSS: Main: 170942464 17:14:24 INFO - PID 22979 | 17:14:27 INFO - PID 22979 | Cycle 1(1): loaded http://localhost:58791/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:58791/tests/kraken/driver.html?testName=audio-fft) 17:14:27 INFO - PID 22979 | RSS: Main: 164909056 17:14:27 INFO - PID 22979 | 17:14:29 INFO - PID 22979 | Cycle 1(1): loaded http://localhost:58791/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:58791/tests/kraken/driver.html?testName=audio-oscillator) 17:14:30 INFO - PID 22979 | RSS: Main: 166182912 17:14:30 INFO - PID 22979 | 17:14:32 INFO - PID 22979 | Cycle 1(1): loaded http://localhost:58791/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:58791/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:14:33 INFO - PID 22979 | RSS: Main: 166502400 17:14:33 INFO - PID 22979 | 17:14:36 INFO - PID 22979 | Cycle 1(1): loaded http://localhost:58791/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:58791/tests/kraken/driver.html?testName=imaging-darkroom) 17:14:36 INFO - PID 22979 | RSS: Main: 169119744 17:14:36 INFO - PID 22979 | 17:14:40 INFO - PID 22979 | Cycle 1(1): loaded http://localhost:58791/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:58791/tests/kraken/driver.html?testName=imaging-desaturate) 17:14:40 INFO - PID 22979 | RSS: Main: 171020288 17:14:40 INFO - PID 22979 | 17:14:43 INFO - PID 22979 | Cycle 1(1): loaded http://localhost:58791/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:58791/tests/kraken/driver.html?testName=json-parse-financial) 17:14:44 INFO - PID 22979 | RSS: Main: 169279488 17:14:44 INFO - PID 22979 | 17:14:45 INFO - PID 22979 | Cycle 1(1): loaded http://localhost:58791/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:58791/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:14:46 INFO - PID 22979 | RSS: Main: 169132032 17:14:46 INFO - PID 22979 | 17:14:48 INFO - PID 22979 | Cycle 1(1): loaded http://localhost:58791/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:58791/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:14:48 INFO - PID 22979 | RSS: Main: 169103360 17:14:48 INFO - PID 22979 | 17:14:50 INFO - PID 22979 | Cycle 1(1): loaded http://localhost:58791/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:58791/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:14:51 INFO - PID 22979 | RSS: Main: 166780928 17:14:51 INFO - PID 22979 | 17:14:53 INFO - PID 22979 | Cycle 1(1): loaded http://localhost:58791/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:58791/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:14:54 INFO - PID 22979 | RSS: Main: 166334464 17:14:54 INFO - PID 22979 | 17:14:57 INFO - PID 22979 | Cycle 1(1): loaded http://localhost:58791/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:58791/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:14:58 INFO - PID 22979 | RSS: Main: 167178240 17:14:58 INFO - PID 22979 | 17:14:59 INFO - PID 22979 | Cycle 1(1): loaded http://localhost:58791/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:58791/tests/kraken/driver.html?testName=ai-astar) 17:15:00 INFO - PID 22979 | RSS: Main: 168898560 17:15:00 INFO - PID 22979 | 17:15:00 INFO - PID 22979 | __start_tp_report 17:15:00 INFO - PID 22979 | _x_x_mozilla_page_load 17:15:00 INFO - PID 22979 | _x_x_mozilla_page_load_details 17:15:00 INFO - PID 22979 | |i|pagename|runs| 17:15:00 INFO - PID 22979 | |0;ai-astar;98;114;101;106;105;106;107;99;106;110 17:15:00 INFO - PID 22979 | |1;audio-beat-detection;142;112;136;168;111;146;140;115;168;113 17:15:00 INFO - PID 22979 | |2;audio-dft;163;164;162;163;162;162;163;160;166;167 17:15:00 INFO - PID 22979 | |3;audio-fft;75;74;75;169;76;75;74;89;96;75 17:15:00 INFO - PID 22979 | |4;audio-oscillator;78;78;127;79;85;77;134;78;78;123 17:15:00 INFO - PID 22979 | |5;imaging-gaussian-blur;114;114;114;115;114;114;114;115;114;114 17:15:00 INFO - PID 22979 | |6;imaging-darkroom;147;148;149;149;148;148;149;149;149;148 17:15:00 INFO - PID 22979 | |7;imaging-desaturate;94;94;94;94;94;94;95;94;94;94 17:15:00 INFO - PID 22979 | |8;json-parse-financial;46;46;52;47;48;47;50;47;46;47 17:15:00 INFO - PID 22979 | |9;json-stringify-tinderbox;54;54;54;53;54;53;54;54;53;54 17:15:00 INFO - PID 22979 | |10;stanford-crypto-aes;82;74;83;74;75;73;74;75;74;75 17:15:00 INFO - PID 22979 | |11;stanford-crypto-ccm;121;115;116;123;116;116;116;113;115;166 17:15:00 INFO - PID 22979 | |12;stanford-crypto-pbkdf2;174;163;163;165;161;163;167;159;164;164 17:15:00 INFO - PID 22979 | |13;stanford-crypto-sha256-iterative;62;61;63;63;65;61;60;59;67;62 17:15:00 INFO - PID 22979 | __end_tp_report 17:15:00 INFO - PID 22979 | __start_cc_report 17:15:00 INFO - PID 22979 | _x_x_mozilla_cycle_collect,857 17:15:00 INFO - PID 22979 | __end_cc_report 17:15:00 INFO - PID 22979 | __startTimestamp1501719300092__endTimestamp 17:15:00 INFO - PID 22979 | 17:15:00 INFO - PID 22979 | ------- Summary: start ------- 17:15:00 INFO - PID 22979 | Number of tests: 14 17:15:00 INFO - PID 22979 | 17:15:00 INFO - PID 22979 | [#0] ai-astar Cycles:10 Average:105.20 Median:108.50 stddev:4.87 (4.5%) stddev-sans-first:4.42 17:15:00 INFO - PID 22979 | Values: 98.0 114.0 101.0 106.0 105.0 106.0 107.0 99.0 106.0 110.0 17:15:00 INFO - PID 22979 | 17:15:00 INFO - PID 22979 | [#1] audio-beat-detection Cycles:10 Average:135.10 Median:141.00 stddev:22.02 (15.6%) stddev-sans-first:23.21 17:15:00 INFO - PID 22979 | Values: 142.0 112.0 136.0 168.0 111.0 146.0 140.0 115.0 168.0 113.0 17:15:00 INFO - PID 22979 | 17:15:00 INFO - PID 22979 | [#2] audio-dft Cycles:10 Average:163.20 Median:163.00 stddev:2.04 (1.3%) stddev-sans-first:2.17 17:15:00 INFO - PID 22979 | Values: 163.0 164.0 162.0 163.0 162.0 162.0 163.0 160.0 166.0 167.0 17:15:00 INFO - PID 22979 | 17:15:00 INFO - PID 22979 | [#3] audio-fft Cycles:10 Average:87.80 Median:75.00 stddev:29.51 (39.3%) stddev-sans-first:30.93 17:15:00 INFO - PID 22979 | Values: 75.0 74.0 75.0 169.0 76.0 75.0 74.0 89.0 96.0 75.0 17:15:00 INFO - PID 22979 | 17:15:00 INFO - PID 22979 | [#4] audio-oscillator Cycles:10 Average:93.70 Median:78.00 stddev:23.92 (30.7%) stddev-sans-first:24.68 17:15:00 INFO - PID 22979 | Values: 78.0 78.0 127.0 79.0 85.0 77.0 134.0 78.0 78.0 123.0 17:15:00 INFO - PID 22979 | 17:15:00 INFO - PID 22979 | [#5] imaging-gaussian-blur Cycles:10 Average:114.20 Median:114.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 17:15:00 INFO - PID 22979 | Values: 114.0 114.0 114.0 115.0 114.0 114.0 114.0 115.0 114.0 114.0 17:15:00 INFO - PID 22979 | 17:15:00 INFO - PID 22979 | [#6] imaging-darkroom Cycles:10 Average:148.40 Median:149.00 stddev:0.70 (0.5%) stddev-sans-first:0.53 17:15:00 INFO - PID 22979 | Values: 147.0 148.0 149.0 149.0 148.0 148.0 149.0 149.0 149.0 148.0 17:15:00 INFO - PID 22979 | 17:15:00 INFO - PID 22979 | [#7] imaging-desaturate Cycles:10 Average:94.10 Median:94.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 17:15:00 INFO - PID 22979 | Values: 94.0 94.0 94.0 94.0 94.0 94.0 95.0 94.0 94.0 94.0 17:15:00 INFO - PID 22979 | 17:15:00 INFO - PID 22979 | [#8] json-parse-financial Cycles:10 Average:47.60 Median:47.00 stddev:1.96 (4.2%) stddev-sans-first:1.99 17:15:00 INFO - PID 22979 | Values: 46.0 46.0 52.0 47.0 48.0 47.0 50.0 47.0 46.0 47.0 17:15:00 INFO - PID 22979 | 17:15:00 INFO - PID 22979 | [#9] json-stringify-tinderbox Cycles:10 Average:53.70 Median:54.00 stddev:0.48 (0.9%) stddev-sans-first:0.50 17:15:00 INFO - PID 22979 | Values: 54.0 54.0 54.0 53.0 54.0 53.0 54.0 54.0 53.0 54.0 17:15:00 INFO - PID 22979 | 17:15:00 INFO - PID 22979 | [#10] stanford-crypto-aes Cycles:10 Average:75.90 Median:75.00 stddev:3.54 (4.7%) stddev-sans-first:2.99 17:15:00 INFO - PID 22979 | Values: 82.0 74.0 83.0 74.0 75.0 73.0 74.0 75.0 74.0 75.0 17:15:00 INFO - PID 22979 | 17:15:00 INFO - PID 22979 | [#11] stanford-crypto-ccm Cycles:10 Average:121.70 Median:116.00 stddev:15.85 (13.7%) stddev-sans-first:16.81 17:15:00 INFO - PID 22979 | Values: 121.0 115.0 116.0 123.0 116.0 116.0 116.0 113.0 115.0 166.0 17:15:00 INFO - PID 22979 | 17:15:00 INFO - PID 22979 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:164.30 Median:164.00 stddev:4.03 (2.5%) stddev-sans-first:2.28 17:15:00 INFO - PID 22979 | Values: 174.0 163.0 163.0 165.0 161.0 163.0 167.0 159.0 164.0 164.0 17:15:00 INFO - PID 22979 | 17:15:00 INFO - PID 22979 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.30 Median:62.50 stddev:2.36 (3.8%) stddev-sans-first:2.50 17:15:00 INFO - PID 22979 | Values: 62.0 61.0 63.0 63.0 65.0 61.0 60.0 59.0 67.0 62.0 17:15:00 INFO - PID 22979 | -------- Summary: end -------- 17:15:00 INFO - PID 22979 | 17:15:00 INFO - PID 22979 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 17:15:00 INFO - TEST-INFO | 22979: exit 0 17:15:00 INFO - TEST-OK | kraken | took 59886ms 17:15:00 INFO - SUITE-END | took 1169s 17:15:01 INFO - Completed test suite (00:19:29) 17:15:01 INFO - javascript benchmark 17:15:01 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8846.258025909381, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3335.5663763710727, 3365.2428203654194, 3377.155711072232, 3383.3571621391934, 3385.06910599585, 6096.939030609698, 6098.054801505763, 6132.662703551303, 6138.416850399205, 6140.0, 6020.698965051752, 6099.5120390368775, 6104.5116390688745, 6110.144579758838, 6115.847103822407, 5327.360716714, 5338.866528336794, 5356.464353564632, 5358.4641535846495, 5366.141417373221, 5387.057264978613, 5389.434109418514, 5422.91865622016, 5436.483534064258, 5469.124940009599, 5393.406725260218, 5399.78400863965, 5427.62006659534, 5442.346918369782, 5450.618456708032, 5350.170723537858, 5406.675599464044, 5417.404085550586, 5422.024035673617, 5476.397596264408, 5175.637705360627, 5252.054630166568, 5288.524032837025, 5303.575713942875, 5377.677339359651, 5911.438413350725, 5982.581219314649, 6004.399560044004, 6004.8498787530225, 6072.0, 6412.3267056959285, 6442.4201821835795, 6451.1936007999, 6501.187351581052, 6539.44414724749, 6588.670566471706, 6608.074869518271, 6627.966860165717, 6634.203895532567, 6635.236947751022, 6090.604110732788, 6104.2980057293535, 6107.938920610826, 6108.205933228652, 6114.633122012649, 6750.797476075725, 6757.864842703217, 6760.763373281911, 6776.593404395752, 6842.657867106725, 6501.284858665543, 6533.640649764214, 6570.441512471493, 6573.83565410859, 6582.63795491243, 6604.075429439882, 6607.174103237096, 6653.135092438049, 6698.095757072733, 6712.697928593225, 6374.394432528902, 6493.058506516529, 6494.740210391531, 6509.9023514647315, 6620.205575330895, 6497.0, 6518.608883467007, 6525.0, 6525.608463492205, 6552.6068435894, 4801.663883528119, 4808.0383923215495, 4808.567228949411, 4815.686980346266, 4824.927626085611, 4317.352397140454, 4327.091310824699, 4373.781310934441, 4377.4965878923695, 4393.406890069864, 6094.908576371358, 6153.692315384213, 6164.321924588292, 6186.969065154645, 6299.118123462719, 6536.0, 6609.239937407164, 6648.900266496006, 6650.0, 6660.9000864987065, 6214.161088253028, 6227.439530442282, 6255.12428260044, 6272.968635156795, 6277.435030847154, 5414.864628384243, 5422.430644782241, 5456.0, 5477.780888764484, 5516.475934786189, 7043.225245223124, 7054.6825392857445, 7067.328603782633, 7067.89398159028, 7075.646217689198, 6569.572977756622, 6588.736450541925, 6599.934000659932, 6621.867562648817, 6638.867222655424, 6913.723451061901, 6985.860282794214, 6999.090118284794, 7091.751788687371, 7097.112860892388, 6435.2599451063725, 6500.479961602966, 6503.122078519339, 6536.542442029012, 6582.045603387578, 6517.348265173445, 6672.799816005528, 6684.465242780663, 6697.397234248941, 6704.128463299935, 6429.0, 6440.32376600469, 6458.257300410513, 6520.347965203442, 6529.249136349381, 6455.128557644658, 6455.644939528371, 6508.08886755864, 6541.08424820535, 6541.476681865344, 6327.936720632919, 6402.839929001812, 6409.455196308179, 6415.935840641534, 6419.486441084796, 6485.416312531895, 6485.740570377132, 6494.0, 6519.641419721961, 6522.934770652422, 6180.876382472416, 6195.16365290698, 6229.968850155901, 6255.749770009149, 6268.091126786499], "value": 6008.87735986348, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [883.8271495091499, 908.550267617529, 935.0, 955.7849483866127, 964.6141543382646, 42469.57530424695, 43188.56811431885, 43368.566314336844, 43440.56559434405, 43542.129157416835, 40503.59496405035, 40643.18713625733, 40820.59179408205, 40962.180756384856, 41056.0, 6777.085093512386, 6907.827304317407, 6916.585004899697, 6930.06444130041, 6933.064036355103, 6775.525713200078, 6879.174499060095, 6884.793456196322, 6885.070515480396, 6892.138482689664, 6856.211535673387, 6894.620795856226, 6895.55178913369, 6897.517173797836, 7260.963695181516, 7451.515651482637, 7475.626218689098, 7498.250174982512, 7498.47510674253, 7510.061242344707, 7325.340719335285, 7399.593022383767, 7406.8888966665545, 7408.59252741099, 7411.555306681563, 39903.401448978286, 40318.39522407166, 40458.0, 40493.0, 40765.0, 3384.255463797985, 3406.744494162923, 3408.5057666638445, 3408.642092580268, 3414.351273258073, 31103.0, 31495.685043149733, 31541.0, 31764.04707858768, 31845.52231716526, 4925.310456536088, 4983.775730092155, 5014.448410674823, 5030.496950304977, 5052.368453943257, 207995.0, 209069.0, 213524.0, 216730.0, 229323.99999999834, 53904.46095539073, 54096.72951635216, 54175.45824541704, 54185.458145418044, 54204.45795542073, 56268.718656406454, 56276.15585766217, 56416.43583564112, 56437.024704135976, 56550.71724641351, 16054.518364449084, 16185.271662775203, 16209.10849903243, 16218.513444596681, 16354.836451635569, 12927.224366538037, 12970.092093553549, 12975.610731678065, 13023.47906083765, 13113.016523760756, 15486.5354039379, 15496.380144794308, 15531.301091450912, 15541.98977066487, 15570.9221453892, 6014.8195554133445, 6021.608595441282, 6131.172291740647, 6135.202423684982, 6191.288001879751, 1071.7749272652675, 1081.1783044885785, 1088.1240601315858, 1097.434821067151, 1101.5483651702764, 24801.379965500644, 24843.5031299373, 25186.0, 25294.620580691306, 25550.744492555208, 1191.5233906437495, 1194.814803705427, 1199.3463562358536, 1209.7278112424633, 1214.4656351205442, 13259.469621215236, 13284.335783210801, 13507.324633768272, 13564.525241616497, 13761.243131627856, 263384.0, 265400.0, 266309.0, 266764.0, 267461.0, 56784.0, 56828.71585642045, 56980.14529782056, 57142.0, 57301.42698572961, 1502.1512845242403, 1513.8713209377106, 1543.1744016951102, 1543.3055125193707, 1556.3152213026233, 24691.87654061718, 30111.698883011766, 30127.548086779156, 30499.847500762353, 30988.535171971987, 56194.71902640461, 56795.148072778116, 56823.86352272849, 56832.715836422205, 57082.0, 235586.0, 237931.0, 238797.0, 239294.0, 240178.0, 36252.27495450024, 38016.429753553166, 38195.236095278495, 38251.0, 38407.80796096002, 45452.0, 46159.3076103852, 46671.0, 46876.0, 47219.527804722886, 34568.82715586406, 34900.47649285314, 34915.65084349124, 34929.65070349264, 35260.0, 34344.48483272803, 34686.30627387387, 34755.65244347524, 34775.304493909476, 35072.64927350796, 39681.20637587174, 39818.60181398149, 39981.0, 40070.59929400785, 40146.79926600348, 35009.64990350064, 35076.473852891715, 35787.28425431529, 35791.0, 35893.28213435665, 26377.08122951053, 27977.860110699316, 28021.43957120887, 28146.577801332587, 28209.43581128407], "value": 18318.506972825464, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [558.1599692462843, 573.406524247404, 584.2229834320354, 585.4496773033349, 592.8725324055328, 21278.57442851144, 21954.0, 22006.229781957634, 22088.0, 23168.42078948027, 26217.213483595475, 26609.733902661017, 26809.731902680967, 26967.05615303465, 26968.5954710679, 6208.8447788805415, 6313.747450101991, 6338.492920566344, 6345.809625711213, 6351.936480635204, 6032.155498230251, 6261.592996455239, 6280.403361680633, 6285.434310912017, 6292.905606415908, 6225.377462253761, 6227.719752611143, 6229.563930524865, 6236.126942228092, 6238.376162383748, 6127.019676851683, 6195.411435913581, 6219.067139929024, 6223.937760622381, 6232.750689972396, 5775.0, 5789.2763404574425, 5790.971045144789, 5804.0, 5826.766929322822, 29454.0, 30067.045642033198, 30617.234569135726, 30676.69323306783, 30776.846115769276, 2147.5490147069136, 2187.628103222456, 2188.616992026389, 2189.419803752007, 2189.8357623178167, 19010.80989190104, 19443.708344374845, 19476.80523194764, 19554.804451955442, 19654.508637284038, 2369.2399380578013, 2403.579373609629, 2439.5852705040184, 2487.664165337674, 2493.0, 150673.0, 154490.22754886266, 154659.0, 154709.0, 154802.0, 14719.411223550938, 15072.321745521478, 15898.0, 15997.360105595879, 16428.42500512476, 23957.0, 24063.639045414333, 24075.51848963011, 24096.0, 24142.7585724144, 10693.518791654395, 10793.29843560166, 10804.731006488568, 10805.758272518213, 10943.45282735876, 8949.507777072193, 9402.435853848654, 9765.0, 10162.796744065225, 10208.2343824212, 9476.336656433983, 9594.601349662584, 9605.135537801492, 9683.225341972626, 9888.901110988798, 2803.3832556837465, 2817.9295517612222, 2818.1827270091444, 2836.432713457317, 2907.8109922854946, 1011.630754774502, 1019.23557332001, 1024.52871679027, 1033.256055639953, 1036.8185567525652, 20532.589348213252, 20572.074256658485, 20927.47681307949, 21148.15407383718, 21243.0, 1079.6976846482996, 1117.044926587772, 1128.480898786551, 1134.325076579434, 1138.425095326871, 11379.260348077349, 11505.827412588818, 11568.0, 11605.535778568932, 11688.357140357362, 583727.0, 588791.0, 597130.0, 611292.0, 624083.0, 124342.37828810979, 126081.0, 127236.36381818031, 132493.33753331171, 132740.33629831983, 1200.9759804803855, 1232.211384713784, 1250.118666340228, 1253.035162924543, 1253.4234252243887, 18544.721829172842, 18638.72041919345, 18649.90675046616, 22623.095076197427, 22760.992581632014, 65295.0, 65363.34636653573, 66219.33780662132, 67060.0, 67404.6629766848, 83145.58427207825, 86876.0, 87726.01616668695, 88787.0, 89263.55368223117, 15340.693186136437, 15645.295961681524, 15701.842981570038, 15712.60718482047, 15722.606934826721, 24610.876945615157, 24816.627750584117, 24933.376665583506, 24944.75055249497, 25002.624960625242, 19127.904360478107, 19332.0, 19353.70969435488, 19702.704459432833, 19814.0, 17645.470635880432, 17955.73066404031, 18099.547511311797, 18162.636747265246, 18609.44171674852, 19714.309999149962, 19918.203271868962, 20032.298869539496, 20033.59932801365, 20810.583788324453, 15193.164375959428, 15334.9233253833, 15340.309686064376, 15453.304601293194, 15483.071015739091, 19368.90315548413, 19574.21703131859, 19651.90174049178, 19743.308984185485, 19934.401967940987], "value": 12594.622296579295, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1430.9642258943525, 1480.9115300254311, 1485.0495682763026, 1495.7756336549523, 1499.4976682811264, 15099.773503397459, 15211.011284266515, 15760.921195394021, 15775.132367719785, 15827.287772050257, 16068.83931160688, 16351.100689462075, 16364.018158910474, 16389.262483188257, 16397.918010409932, 2648.708642049373, 2717.415755612543, 2722.823016503931, 2723.2919440945398, 2726.2775364528416, 2450.4853980664084, 2594.2087663262696, 2692.8249663771794, 2715.701272859984, 2749.1889892481768, 2608.491344187884, 2624.042224588031, 2639.8020148488845, 2639.8944042238286, 2640.801939854509, 2668.9599656005175, 2683.235277945786, 2686.1538615336203, 2694.6227528146073, 2695.5417579011614, 2584.5218634552666, 2643.0881345935622, 2654.9469010619678, 2658.4550167215675, 2662.0948877381784, 15319.31063102163, 15721.292541835644, 15802.051876887424, 15813.841861581352, 15818.209089545477, 8013.198680131998, 8296.626651800683, 8300.958495207486, 8307.169283071706, 8317.251447369767, 5096.362954630671, 5256.579473642099, 5297.258383826267, 5305.469453054702, 5376.650517716336, 1963.2049020146862, 1968.7261213298664, 1979.6931475621302, 2010.5275260313815, 2021.858469907107, 27349.316267093283, 27460.725392746215, 27498.17505474819, 27634.170974870987, 27696.584551231746, 6609.537332386687, 7241.167265764505, 7242.49302548827, 7522.172561018284, 7537.886931696029, 18372.08139593015, 18512.537186570444, 18514.074296285133, 18517.07414629263, 18567.0, 8019.599020048974, 8152.592370381457, 8195.508269503849, 8203.8359232815, 8237.299829514439, 7360.815979600446, 7532.058492688414, 7533.773986780406, 7574.962125189449, 7575.659095340613, 5027.095122877844, 5222.529972302435, 5262.631615786858, 5286.074936886106, 5299.0, 1300.6148451898887, 1342.684469149749, 1413.922234277104, 1443.501991812823, 1447.471672839406, 953.8521529162991, 965.3194497878978, 968.6464440479174, 976.6044751875502, 999.2605471950704, 8795.98846132703, 9023.81952360949, 9154.26765858743, 9219.539023048956, 9290.16388525036, 1035.1563475767225, 1050.333038520545, 1056.7939251845817, 1058.666520046183, 1067.0183431243274, 5434.510894019557, 5449.536789372868, 5549.223108764776, 5644.294463192101, 5867.090600956859, 21671.458213544473, 22832.65751013736, 22974.310770676908, 23025.654615180785, 23078.192263270706, 21164.682529762067, 21997.0, 22321.888390557942, 22396.44008899758, 22396.440088997904, 12062.939685301517, 12230.75538489243, 12370.62888113358, 12375.62873113826, 12392.008639309013, 9018.053104424203, 9026.954865225895, 9104.180623743894, 9147.0852914708, 9167.0, 19274.0, 19314.903425482782, 19362.0, 19793.109310080792, 20011.59976800485, 19856.90071549633, 19866.404007879788, 19904.60190796205, 20034.499137521678, 20280.087396066916, 14076.296185190904, 14448.855511445172, 14452.421903124177, 14455.566333009605, 14466.132032078109, 13918.51285205013, 14291.64270893236, 14362.569122926328, 14400.415954244816, 14488.492902748354, 10910.127189824954, 11134.33194008362, 11365.090792736726, 11631.88368116308, 11672.0, 11092.33445993243, 11197.160212983896, 11300.378479183724, 11391.943040285078, 11487.94256028748, 10812.405317707602, 10851.782964340828, 10858.402787846428, 10961.725956851142, 10965.0, 7775.650095745592, 8089.393295502743, 8122.350211982909, 8253.463524870864, 8255.463394879314, 7899.68401263943, 7903.525788452711, 7910.8813367798375, 7952.284294413531, 8035.915151454479], "value": 6975.690083676927, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [493.29458873810484, 506.08903972848884, 513.304472439844, 516.4190285928331, 539.8326518779179, 6009.87980240395, 6057.878842423154, 6107.480864126549, 6118.969405152968, 6142.754289828407, 5922.289325280962, 5954.285485741706, 5956.612820166686, 5966.403359664031, 6075.3547871277215, 4655.627549796008, 4732.124556956956, 4734.124187025394, 4742.691725037862, 4758.238681810911, 4726.629277509535, 4770.904581908359, 4791.784369703371, 4799.760011999403, 4805.399325084347, 4654.232051711463, 4667.649926255544, 4687.929681054787, 4746.952530474686, 4761.333413322138, 4695.0, 4721.362616046824, 4749.667523273372, 4766.0, 4783.162946484351, 4267.957320426787, 4310.525842157392, 4320.956790432118, 4338.110687309094, 4339.849939765964, 5856.121581762727, 5925.525957923356, 5936.228290322274, 5981.671008094553, 5992.400759924017, 5594.748236329375, 5620.932022915633, 5651.350094739117, 5705.942940570582, 5709.143628455765, 6397.968010159966, 6418.646974416405, 6422.34833346652, 6450.709718062699, 6467.385598368148, 4771.139255690284, 4798.280257961299, 4812.205986012304, 4849.660523763338, 4865.245886887538, 6466.288708242089, 6473.676316184172, 6597.142371491724, 6704.1954965403975, 6732.1921369435495, 4956.529129732669, 4987.650864439454, 4994.775235114428, 4996.975015124901, 5039.21892106724, 5028.145215313405, 5042.369703787027, 5092.719900405439, 5101.158308879068, 5105.668131571436, 5568.554515638739, 5591.888162236733, 5608.551315894719, 5635.295588051494, 5657.4059723728415, 6432.935670643234, 6460.483161347081, 6465.028166409196, 6468.288488266287, 6492.35076492347, 1750.3348727483728, 1766.0375095572945, 1768.6727955328306, 1777.2002598830577, 1779.8932064076198, 1178.9469473873696, 1211.6486218996567, 1216.1608490141773, 1247.8065899785547, 1257.993710031444, 6022.789202377983, 6047.334793172748, 6060.242529666348, 6081.0, 6144.0, 6548.919428294278, 6590.5716128451495, 6596.505262105361, 6599.967000164968, 6620.867582648417, 6061.939380606225, 6062.696865156725, 6071.51427885768, 6091.269047714302, 6113.388661133851, 3471.5834099907665, 3505.544279243733, 3505.964940350615, 3506.1234691327168, 3530.7881527107943, 7030.824229394306, 7057.400120989773, 7094.68073936674, 7115.964420177866, 7127.572345659173, 6786.219584747719, 6810.8637827244165, 6835.384815366641, 6846.459546601975, 6851.588904665736, 7015.89476157858, 7047.154341479055, 7102.68037938294, 7137.393321567621, 7144.607046612487, 6115.480184184216, 6501.414872661483, 6534.444572211415, 6557.901631475436, 6575.638339891352, 6415.326390729093, 6453.354664533509, 6483.4164925156965, 6487.837804054936, 6566.474682025522, 6961.303869612998, 6986.720531178696, 7006.299370062953, 7085.929140708527, 7088.397486213729, 4921.975390123026, 4923.803047878045, 4923.926141107814, 4934.457209706858, 4940.654154209171, 6301.495880329471, 6458.967705161444, 6612.445545741408, 6625.238097618835, 6683.365080317362, 6310.274318453253, 6310.305866354605, 6337.936620633919, 6366.77716279928, 6398.712057957497, 6318.873622527615, 6341.131909535087, 6394.593189498303, 6405.455536279282, 6409.9679501602195, 6288.842778930563, 6353.7140828661895, 6359.268684101203, 6388.009858471944, 6443.96778016107, 6334.239891213084, 6400.487960963019, 6410.8076757697345, 6418.261899881573, 6466.838329041811, 6163.660998645118, 6193.504519638328, 6226.750929962751, 6239.75040998355, 6310.305866354789], "value": 5011.425187643523, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [945.730247406225, 948.0093302498889, 968.2834702320285, 974.3325821812059, 981.0581841432224, 18429.078546072677, 19005.0, 19420.223191072375, 19518.0, 19595.21619135233, 21066.89466552665, 21420.678689819666, 21551.24570640024, 21569.67645485319, 21772.237971670995, 946.8863736351612, 956.0391806234725, 956.2923436656894, 956.5456408206117, 974.5614473486924, 1032.4940779018264, 1070.882202957674, 1071.6410002649122, 1072.4101744040786, 1081.8377243413472, 1147.3345459633435, 1155.2548606149028, 1155.566662501562, 1155.8959693627571, 1155.9826602600926, 1116.7208197950513, 1118.2675347647305, 1118.9216754827166, 1120.238237998161, 1121.3216004317394, 817.5626040068535, 823.1028179284608, 823.6787652815407, 823.8558252305822, 823.872299793533, 20187.19251229949, 20492.487687807923, 20751.481212969644, 20777.0, 20862.26982055621, 20909.686354704692, 20981.68527472089, 21026.053827577794, 21104.472388190417, 21330.5733885323, 15909.363625454967, 16114.919425402797, 16189.514314570582, 16263.756043659354, 16283.592910177222, 3679.22736225393, 3689.7786132832116, 3691.630836916314, 3700.907477313062, 3722.1625134344827, 144710.2764486192, 145678.0, 146341.0, 147217.0, 147233.0, 16202.513924582281, 16800.243989020524, 16866.409675661293, 16876.15619219034, 16882.831171688125, 32031.0, 32094.679053209635, 32181.35637287288, 32235.516467253008, 32411.351772964408, 8443.324534037261, 8658.610362533702, 8689.217970382695, 8711.782205444915, 8712.128787121237, 4447.488538818013, 4510.436195475566, 4515.345274935116, 4524.411826462539, 4581.40441742578, 5185.766640501187, 5308.0, 5338.652987555796, 5340.332458442695, 5395.973020134874, 1076.860008198929, 1117.1118960426481, 1126.6282126898104, 1126.7408496045955, 1127.3405058040973, 719.0903507063464, 727.9672414741349, 739.9223081576465, 745.0128579632009, 755.8979537762442, 11810.881891180978, 13021.609351719464, 13158.868411315956, 13226.470941162439, 13243.93378033123, 693.8022663540968, 696.1194089476816, 702.8559145375186, 709.0286307758404, 710.0804458226609, 5866.677332746654, 5882.764689412375, 6018.548608854353, 6030.668313242726, 6079.05774604937, 127101.36449317694, 127115.0, 133222.0, 134198.0, 134516.0, 110725.0, 112975.43512282387, 113335.43332283286, 115374.42312788383, 118164.0, 96678.0, 101388.0, 103346.48326758319, 105562.0, 107354.46322768336, 13674.863251367358, 13753.312334383041, 13853.584392468241, 13871.722565548835, 14026.368813403626, 43465.3480197791, 46714.766426167655, 49199.0, 51153.74423127861, 52118.17586384458, 90059.0, 90598.54700726454, 91141.54429227811, 91817.0, 91896.54051729699, 19675.8032419674, 19915.800841991393, 19981.500462488555, 20336.898315508326, 20471.59056818885, 21213.469663257925, 21309.36071917845, 21332.36002919915, 21402.250921218307, 21543.784562154804, 19401.32095376655, 19661.115249813505, 19670.213191472754, 19689.8031019688, 19847.404577862686, 19078.332258371447, 19099.427017189508, 19114.52213694669, 19313.90343048276, 22685.43286417853, 21857.234996775038, 22022.779772202714, 22080.779192208516, 22530.774692252868, 22709.0, 14039.227842468763, 14557.34491947886, 14778.408863645334, 15006.2496875158, 15603.453879114177, 14922.030068045977, 15105.942584019012, 16041.197940102715, 16167.838321617104, 16278.0], "value": 9888.07807030814, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1467.2, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 114.0, 101.0, 106.0, 105.0, 106.0, 107.0, 99.0, 106.0, 110.0], "value": 105.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 112.0, 136.0, 168.0, 111.0, 146.0, 140.0, 115.0, 168.0, 113.0], "value": 135.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 164.0, 162.0, 163.0, 162.0, 162.0, 163.0, 160.0, 166.0, 167.0], "value": 163.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 74.0, 75.0, 169.0, 76.0, 75.0, 74.0, 89.0, 96.0, 75.0], "value": 87.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 78.0, 127.0, 79.0, 85.0, 77.0, 134.0, 78.0, 78.0, 123.0], "value": 93.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 114.0, 115.0, 114.0, 114.0, 114.0, 115.0, 114.0, 114.0], "value": 114.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 148.0, 149.0, 149.0, 148.0, 148.0, 149.0, 149.0, 149.0, 148.0], "value": 148.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 94.0, 94.0, 94.0, 95.0, 94.0, 94.0, 94.0], "value": 94.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [46.0, 46.0, 52.0, 47.0, 48.0, 47.0, 50.0, 47.0, 46.0, 47.0], "value": 47.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 54.0, 54.0, 53.0, 54.0, 53.0, 54.0, 54.0, 53.0, 54.0], "value": 53.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 74.0, 83.0, 74.0, 75.0, 73.0, 74.0, 75.0, 74.0, 75.0], "value": 75.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [121.0, 115.0, 116.0, 123.0, 116.0, 116.0, 116.0, 113.0, 115.0, 166.0], "value": 121.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [174.0, 163.0, 163.0, 165.0, 161.0, 163.0, 167.0, 159.0, 164.0, 164.0], "value": 164.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 61.0, 63.0, 63.0, 65.0, 61.0, 60.0, 59.0, 67.0, 62.0], "value": 62.3, "unit": "score"}]}]} 17:15:01 INFO - Return code: 0 17:15:01 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 17:15:01 INFO - # TBPL SUCCESS # 17:15:01 INFO - Running post-action listener: _package_coverage_data 17:15:01 INFO - Running post-action listener: _resource_record_post_action 17:15:01 INFO - [mozharness: 2017-08-03 00:15:01.384420Z] Finished run-tests step (success) 17:15:01 INFO - Running post-run listener: _resource_record_post_run 17:15:01 INFO - Total resource usage - Wall time: 1188s; CPU: 13.0%; Read bytes: 4096; Write bytes: 428666880; Read time: 8; Write time: 236280 17:15:01 INFO - TinderboxPrint: CPU usage
13.1% 17:15:01 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 8 17:15:01 INFO - TinderboxPrint: I/O write bytes / time
428,666,880 / 236,280 17:15:01 INFO - TinderboxPrint: CPU idle
8,234.3 (86.9%) 17:15:01 INFO - TinderboxPrint: CPU user
1,190.1 (12.6%) 17:15:01 INFO - TinderboxPrint: Swap in / out
0 / 0 17:15:01 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 107847680; Read time: 0; Write time: 56120 17:15:01 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 17:15:01 INFO - run-tests - Wall time: 1170s; CPU: 13.0%; Read bytes: 0; Write bytes: 320159744; Read time: 0; Write time: 180112 17:15:02 INFO - Running post-run listener: _upload_blobber_files 17:15:02 INFO - Blob upload gear active. 17:15:02 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:15:02 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 17:15:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 17:15:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 17:15:02 INFO - (blobuploader) - INFO - Open directory for files ... 17:15:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 17:15:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:15:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:15:05 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 17:15:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:15:05 INFO - (blobuploader) - INFO - Done attempting. 17:15:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 17:15:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:15:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:15:06 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 17:15:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:15:06 INFO - (blobuploader) - INFO - Done attempting. 17:15:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 17:15:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:15:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:15:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 17:15:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:15:06 INFO - (blobuploader) - INFO - Done attempting. 17:15:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 17:15:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:15:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:15:07 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 17:15:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:15:07 INFO - (blobuploader) - INFO - Done attempting. 17:15:07 INFO - (blobuploader) - INFO - Iteration through files over. 17:15:07 INFO - Return code: 0 17:15:07 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:15:07 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:15:07 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f2db3ecdb9bd1cd588c90195b7b3992ba8055ea30732116b093154e668c7555911c085cfcbcbe8012c65cd79199723d4e0a4eed30ebfea67eed1b8bf41dc19ba", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/38bceb5ebcbb8e9f5cecc49395e4e64dd079f4043b637431f5ddbb8ee54ff2dbf82176bab60d7397b59cf20e9b7658b9dcc08a6d0d1b51f218921b080f0f80ae", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f8291e4407df4f42f2db0b60c29da1fc75438adbc9e10cf9a2d968324e381dfc0c6ca4bb06752746fa9d48508baa341f36b38b64c1747254babf6d4c680b24aa", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/517adfabfbd55858797c8fa96195be1fbf32272f6306f526e0125e1dc22b5a63009ecc2748be214a6e17bff0b521149a9d7f73dcdefcd5124e2325e4145d6565"} 17:15:07 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:15:07 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:15:07 INFO - Contents: 17:15:07 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f2db3ecdb9bd1cd588c90195b7b3992ba8055ea30732116b093154e668c7555911c085cfcbcbe8012c65cd79199723d4e0a4eed30ebfea67eed1b8bf41dc19ba", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/38bceb5ebcbb8e9f5cecc49395e4e64dd079f4043b637431f5ddbb8ee54ff2dbf82176bab60d7397b59cf20e9b7658b9dcc08a6d0d1b51f218921b080f0f80ae", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f8291e4407df4f42f2db0b60c29da1fc75438adbc9e10cf9a2d968324e381dfc0c6ca4bb06752746fa9d48508baa341f36b38b64c1747254babf6d4c680b24aa", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/517adfabfbd55858797c8fa96195be1fbf32272f6306f526e0125e1dc22b5a63009ecc2748be214a6e17bff0b521149a9d7f73dcdefcd5124e2325e4145d6565"} 17:15:07 INFO - Running post-run listener: copy_logs_to_upload_dir 17:15:07 INFO - Copying logs to upload dir... 17:15:07 INFO - mkdir: /builds/slave/test/build/upload/logs 17:15:07 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1249.792942 ========= master_lag: 0.28 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 50 secs) (at 2017-08-02 17:15:07.657149) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-02 17:15:07.661127) ========= 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=31acecf42b6a79ec49471d4a000001bb-1501717684.416508-1647253761 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f2db3ecdb9bd1cd588c90195b7b3992ba8055ea30732116b093154e668c7555911c085cfcbcbe8012c65cd79199723d4e0a4eed30ebfea67eed1b8bf41dc19ba", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/38bceb5ebcbb8e9f5cecc49395e4e64dd079f4043b637431f5ddbb8ee54ff2dbf82176bab60d7397b59cf20e9b7658b9dcc08a6d0d1b51f218921b080f0f80ae", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f8291e4407df4f42f2db0b60c29da1fc75438adbc9e10cf9a2d968324e381dfc0c6ca4bb06752746fa9d48508baa341f36b38b64c1747254babf6d4c680b24aa", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/517adfabfbd55858797c8fa96195be1fbf32272f6306f526e0125e1dc22b5a63009ecc2748be214a6e17bff0b521149a9d7f73dcdefcd5124e2325e4145d6565"} build_url:https://queue.taskcluster.net/v1/task/YjPNvDkxRpGcvZQBdlxoDQ/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011312 build_url: 'https://queue.taskcluster.net/v1/task/YjPNvDkxRpGcvZQBdlxoDQ/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f2db3ecdb9bd1cd588c90195b7b3992ba8055ea30732116b093154e668c7555911c085cfcbcbe8012c65cd79199723d4e0a4eed30ebfea67eed1b8bf41dc19ba", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/38bceb5ebcbb8e9f5cecc49395e4e64dd079f4043b637431f5ddbb8ee54ff2dbf82176bab60d7397b59cf20e9b7658b9dcc08a6d0d1b51f218921b080f0f80ae", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f8291e4407df4f42f2db0b60c29da1fc75438adbc9e10cf9a2d968324e381dfc0c6ca4bb06752746fa9d48508baa341f36b38b64c1747254babf6d4c680b24aa", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/517adfabfbd55858797c8fa96195be1fbf32272f6306f526e0125e1dc22b5a63009ecc2748be214a6e17bff0b521149a9d7f73dcdefcd5124e2325e4145d6565"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-02 17:15:07.685550) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-02 17:15:07.685919) ========= 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=31acecf42b6a79ec49471d4a000001bb-1501717684.416508-1647253761 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004422 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-02 17:15:07.769940) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-02 17:15:07.770254) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-02 17:15:07.770567) ========= ========= Total master_lag: 0.43 =========