builder: mozilla-central_ubuntu64_hw_stylo_test-dromaeojs-e10s slave: talos-linux64-ix-011 starttime: 1502582942.43 results: success (0) revision: 59594b3879cc7b038ba8a1ed6e9e66c6423bf6e2 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-08-12 17:09:02.432601) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-08-12 17:09:02.432987) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-12 17:09:02.433259) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-12 17:09:02.579198) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-12 17:09:02.579483) ========= 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=5cf47e208febbcd81da23b2e0000026f-1502582025.59989-1127655553 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-08-12 17:09:02-- 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.2M=0s 2017-08-12 17:09:02 (46.2 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.193453 ========= master_lag: 0.05 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-12 17:09:02.821206) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-12 17:09:02.826229) ========= 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=5cf47e208febbcd81da23b2e0000026f-1502582025.59989-1127655553 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.013889 ========= master_lag: 0.07 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-12 17:09:02.910535) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-12 17:09:02.910942) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 59594b3879cc7b038ba8a1ed6e9e66c6423bf6e2 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo mozilla-central --rev 59594b3879cc7b038ba8a1ed6e9e66c6423bf6e2 --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=5cf47e208febbcd81da23b2e0000026f-1502582025.59989-1127655553 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-08-12 17:09:02,984 truncating revision to first 12 chars 2017-08-12 17:09:02,984 Setting DEBUG logging. 2017-08-12 17:09:02,984 attempt 1/10 2017-08-12 17:09:02,984 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/59594b3879cc?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-08-12 17:09:03,481 unpacking tar archive at: mozilla-central-59594b3879cc/testing/mozharness/ program finished with exit code 0 elapsedTime=0.867631 ========= master_lag: 0.05 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-12 17:09:03.824578) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-12 17:09:03.824892) ========= script_repo_revision: 59594b3879cc7b038ba8a1ed6e9e66c6423bf6e2 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-12 17:09:03.825460) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-12 17:09:03.825727) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-12 17:09:03.853291) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2017-08-12 17:09:03.853591) ========= /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=5cf47e208febbcd81da23b2e0000026f-1502582025.59989-1127655553 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 17:09:04 INFO - MultiFileLogger online at 20170812 17:09:04 in /builds/slave/test 17:09:04 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 17:09:04 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 17:09:04 INFO - {'append_to_log': False, 17:09:04 INFO - 'base_work_dir': '/builds/slave/test', 17:09:04 INFO - 'blob_upload_branch': 'Firefox', 17:09:04 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 17:09:04 INFO - 'branch': 'Firefox', 17:09:04 INFO - 'buildbot_json_path': 'buildprops.json', 17:09:04 INFO - 'code_coverage': False, 17:09:04 INFO - 'config_files': ('talos/linux_config.py',), 17:09:04 INFO - 'default_actions': ('clobber', 17:09:04 INFO - 'read-buildbot-config', 17:09:04 INFO - 'download-and-extract', 17:09:04 INFO - 'populate-webroot', 17:09:04 INFO - 'create-virtualenv', 17:09:04 INFO - 'install', 17:09:04 INFO - 'setup-mitmproxy', 17:09:04 INFO - 'run-tests'), 17:09:04 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:09:04 INFO - 'disable_ccov_upload': False, 17:09:04 INFO - 'download_minidump_stackwalk': True, 17:09:04 INFO - 'download_symbols': 'ondemand', 17:09:04 INFO - 'enable_stylo': False, 17:09:04 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 17:09:04 INFO - 'tooltool.py': '/tools/tooltool.py', 17:09:04 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 17:09:04 INFO - '/tools/misc-python/virtualenv.py')}, 17:09:04 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:09:04 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:09:04 INFO - 'gecko_profile': False, 17:09:04 INFO - 'gecko_profile_interval': 0, 17:09:04 INFO - 'installer_path': 'installer.exe', 17:09:04 INFO - 'log_level': 'info', 17:09:04 INFO - 'log_name': 'talos', 17:09:04 INFO - 'log_to_console': True, 17:09:04 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 17:09:04 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 17:09:04 INFO - 'opt_config_files': (), 17:09:04 INFO - 'pip_index': False, 17:09:04 INFO - 'suite': 'dromaeojs-e10s', 17:09:04 INFO - 'system_bits': '32', 17:09:04 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:09:04 INFO - 'title': 'talos-linux64-ix-011', 17:09:04 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:09:04 INFO - 'use_talos_json': True, 17:09:04 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 17:09:04 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:09:04 INFO - 'work_dir': 'build'} 17:09:04 INFO - [mozharness: 2017-08-13 00:09:04.155845Z] Running clobber step. 17:09:04 INFO - Running pre-action listener: _resource_record_pre_action 17:09:04 INFO - Running main action method: clobber 17:09:04 INFO - rmtree: /builds/slave/test/build 17:09:04 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 17:09:05 INFO - Running post-action listener: _resource_record_post_action 17:09:05 INFO - [mozharness: 2017-08-13 00:09:05.136392Z] Finished clobber step (success) 17:09:05 INFO - [mozharness: 2017-08-13 00:09:05.136495Z] Running read-buildbot-config step. 17:09:05 INFO - Running pre-action listener: _resource_record_pre_action 17:09:05 INFO - Running main action method: read_buildbot_config 17:09:05 INFO - Using buildbot properties: 17:09:05 INFO - { 17:09:05 INFO - "project": "", 17:09:05 INFO - "product": "firefox", 17:09:05 INFO - "who": "archaeopteryx@coole-files.de", 17:09:05 INFO - "installer_path": "public/build/target.tar.bz2", 17:09:05 INFO - "builddir": "mozilla-central_ubuntu64_hw_stylo_test-dromaeojs-e10s", 17:09:05 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 17:09:05 INFO - "buildername": "Ubuntu HW 12.04 x64 stylo mozilla-central talos dromaeojs-e10s", 17:09:05 INFO - "stage_platform": "linux64-stylo", 17:09:05 INFO - "basedir": "/builds/slave/test", 17:09:05 INFO - "buildnumber": 27, 17:09:05 INFO - "platform": "ubuntu64_hw_stylo", 17:09:05 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 17:09:05 INFO - "slavebuilddir": "test", 17:09:05 INFO - "taskId": "Jw8U_7qQQvGBAlwzrcilHA", 17:09:05 INFO - "branch": "mozilla-central", 17:09:05 INFO - "script_repo_revision": "production", 17:09:05 INFO - "revision": "59594b3879cc7b038ba8a1ed6e9e66c6423bf6e2", 17:09:05 INFO - "slavename": "talos-linux64-ix-011", 17:09:05 INFO - "repo_path": "mozilla-central" 17:09:05 INFO - } 17:09:05 INFO - Finding installer, test and symbols from parent task. 17:09:05 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Jw8U_7qQQvGBAlwzrcilHA'}, attempt #1 17:09:05 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Jw8U_7qQQvGBAlwzrcilHA'}, attempt #1 17:09:06 INFO - Task dependencies: CP2xHjBSS1Kh1OeG7_y_fA 17:09:06 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CP2xHjBSS1Kh1OeG7_y_fA'}, attempt #1 17:09:06 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/CP2xHjBSS1Kh1OeG7_y_fA/artifacts/public/build/target.tar.bz2 17:09:06 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/CP2xHjBSS1Kh1OeG7_y_fA/artifacts/public/build/target.test_packages.json 17:09:06 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/CP2xHjBSS1Kh1OeG7_y_fA/artifacts/public/build/target.crashreporter-symbols.zip 17:09:06 INFO - Running post-action listener: _resource_record_post_action 17:09:06 INFO - [mozharness: 2017-08-13 00:09:06.492528Z] Finished read-buildbot-config step (success) 17:09:06 INFO - [mozharness: 2017-08-13 00:09:06.492749Z] Running download-and-extract step. 17:09:06 INFO - Running pre-action listener: _resource_record_pre_action 17:09:06 INFO - Running main action method: download_and_extract 17:09:06 INFO - mkdir: /builds/slave/test/build/tests 17:09:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:09:06 INFO - trying https://queue.taskcluster.net/v1/task/CP2xHjBSS1Kh1OeG7_y_fA/artifacts/public/build/target.test_packages.json 17:09:06 INFO - Downloading https://queue.taskcluster.net/v1/task/CP2xHjBSS1Kh1OeG7_y_fA/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 17:09:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CP2xHjBSS1Kh1OeG7_y_fA/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 17:09:07 INFO - Downloaded 989 bytes. 17:09:07 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 17:09:07 INFO - Using the following test package requirements: 17:09:07 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 17:09:07 INFO - u'common': [u'target.common.tests.zip'], 17:09:07 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 17:09:07 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 17:09:07 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 17:09:07 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 17:09:07 INFO - u'mozbase': [u'target.common.tests.zip'], 17:09:07 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 17:09:07 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 17:09:07 INFO - u'web-platform': [u'target.common.tests.zip', 17:09:07 INFO - u'target.web-platform.tests.tar.gz'], 17:09:07 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 17:09:07 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 17:09:07 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CP2xHjBSS1Kh1OeG7_y_fA/artifacts/public/build/target.common.tests.zip 17:09:07 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CP2xHjBSS1Kh1OeG7_y_fA/artifacts/public/build/target.common.tests.zip'}, attempt #1 17:09:07 INFO - Fetch https://queue.taskcluster.net/v1/task/CP2xHjBSS1Kh1OeG7_y_fA/artifacts/public/build/target.common.tests.zip into memory 17:09:09 INFO - Content-Length response header: 54034669 17:09:09 INFO - Bytes received: 54034669 17:09:21 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 17:09:21 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CP2xHjBSS1Kh1OeG7_y_fA/artifacts/public/build/target.common.tests.zip 17:09:21 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CP2xHjBSS1Kh1OeG7_y_fA/artifacts/public/build/target.common.tests.zip'}, attempt #1 17:09:21 INFO - Fetch https://queue.taskcluster.net/v1/task/CP2xHjBSS1Kh1OeG7_y_fA/artifacts/public/build/target.common.tests.zip into memory 17:09:22 INFO - Content-Length response header: 54034669 17:09:22 INFO - Bytes received: 54034669 17:09:35 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CP2xHjBSS1Kh1OeG7_y_fA/artifacts/public/build/target.talos.tests.zip 17:09:35 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CP2xHjBSS1Kh1OeG7_y_fA/artifacts/public/build/target.talos.tests.zip'}, attempt #1 17:09:35 INFO - Fetch https://queue.taskcluster.net/v1/task/CP2xHjBSS1Kh1OeG7_y_fA/artifacts/public/build/target.talos.tests.zip into memory 17:09:36 INFO - Content-Length response header: 14496050 17:09:36 INFO - Bytes received: 14496050 17:09:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:09:36 INFO - trying https://queue.taskcluster.net/v1/task/CP2xHjBSS1Kh1OeG7_y_fA/artifacts/public/build/target.tar.bz2 17:09:36 INFO - Downloading https://queue.taskcluster.net/v1/task/CP2xHjBSS1Kh1OeG7_y_fA/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 17:09:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CP2xHjBSS1Kh1OeG7_y_fA/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 17:09:39 INFO - Downloaded 61674492 bytes. 17:09:39 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/CP2xHjBSS1Kh1OeG7_y_fA/artifacts/public/build/target.tar.bz2 17:09:39 INFO - mkdir: /builds/slave/test/properties 17:09:39 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 17:09:39 INFO - Writing to file /builds/slave/test/properties/build_url 17:09:39 INFO - Contents: 17:09:39 INFO - build_url:https://queue.taskcluster.net/v1/task/CP2xHjBSS1Kh1OeG7_y_fA/artifacts/public/build/target.tar.bz2 17:09:39 INFO - Running post-action listener: _resource_record_post_action 17:09:39 INFO - Running post-action listener: set_extra_try_arguments 17:09:39 INFO - [mozharness: 2017-08-13 00:09:39.538354Z] Finished download-and-extract step (success) 17:09:39 INFO - [mozharness: 2017-08-13 00:09:39.538564Z] Running populate-webroot step. 17:09:39 INFO - Running pre-action listener: _resource_record_pre_action 17:09:39 INFO - Running main action method: populate_webroot 17:09:39 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 17:09:39 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 17:09:39 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 17:09:39 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:09:39 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 17:09:39 INFO - 'tests': ['damp', 'tps']}, 17:09:39 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:09:39 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 17:09:39 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 17:09:39 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 17:09:39 INFO - 'other-e10s': {'tests': ['a11yr', 17:09:39 INFO - 'ts_paint', 17:09:39 INFO - 'tpaint', 17:09:39 INFO - 'sessionrestore', 17:09:39 INFO - 'sessionrestore_many_windows', 17:09:39 INFO - 'sessionrestore_no_auto_restore', 17:09:39 INFO - 'tabpaint']}, 17:09:39 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 17:09:39 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 17:09:39 INFO - 'svgr-e10s': {'tests': ['tsvgx', 17:09:39 INFO - 'tsvgr_opacity', 17:09:39 INFO - 'tart', 17:09:39 INFO - 'tscrollx', 17:09:39 INFO - 'tsvg_static']}, 17:09:39 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 17:09:39 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 17:09:39 INFO - 'talos_options': ['--mitmproxy', 17:09:39 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 17:09:39 INFO - '--firstNonBlankPaint'], 17:09:39 INFO - 'tests': ['tp6_google', 17:09:39 INFO - 'tp6_youtube', 17:09:39 INFO - 'tp6_amazon', 17:09:39 INFO - 'tp6_facebook']}, 17:09:39 INFO - 'tp6-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 17:09:39 INFO - 'talos_options': ['--stylo', 17:09:39 INFO - '--mitmproxy', 17:09:39 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 17:09:39 INFO - '--firstNonBlankPaint'], 17:09:39 INFO - 'tests': ['tp6_google', 17:09:39 INFO - 'tp6_youtube', 17:09:39 INFO - 'tp6_amazon', 17:09:39 INFO - 'tp6_facebook']}, 17:09:39 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 17:09:39 INFO - 'talos_options': ['--stylo', 17:09:39 INFO - '--stylo-threads=1', 17:09:39 INFO - '--mitmproxy', 17:09:39 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 17:09:39 INFO - '--firstNonBlankPaint'], 17:09:39 INFO - 'tests': ['tp6_google', 17:09:39 INFO - 'tp6_youtube', 17:09:39 INFO - 'tp6_amazon', 17:09:39 INFO - 'tp6_facebook']}, 17:09:39 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 17:09:39 INFO - 'talos_options': ['--xperf_path', 17:09:39 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:09:39 INFO - 'tests': ['tp5n']}}} 17:09:39 INFO - Running post-action listener: _resource_record_post_action 17:09:39 INFO - [mozharness: 2017-08-13 00:09:39.547633Z] Finished populate-webroot step (success) 17:09:39 INFO - [mozharness: 2017-08-13 00:09:39.547724Z] Running create-virtualenv step. 17:09:39 INFO - Running pre-action listener: _resource_record_pre_action 17:09:39 INFO - Running main action method: create_virtualenv 17:09:39 INFO - Creating virtualenv /builds/slave/test/build/venv 17:09:39 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 17:09:39 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 17:09:39 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 17:09:39 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:09:39 INFO - Using real prefix '/usr' 17:09:39 INFO - New python executable in /builds/slave/test/build/venv/bin/python 17:09:40 INFO - Installing distribute.............................................................................................................................................................................................done. 17:09:42 INFO - Installing pip.................done. 17:09:42 INFO - Return code: 0 17:09:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 17:09:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 17:09:42 INFO - Reading from file tmpfile_stdout 17:09:42 INFO - Output received: 17:09:42 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 17:09:42 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 17:09:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:09:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:09:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:09:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:09:42 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 0x14cf1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x169ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x183cb20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x183ff00>, '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 0x183aba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x183b050>, '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': '5cf47e208febbcd81da23b2e0000026f-1502582025.59989-1127655553', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 17:09:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 17:09:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 17:09:42 INFO - Using env: {'DISPLAY': ':0', 17:09:42 INFO - 'HOME': '/home/cltbld', 17:09:42 INFO - 'LANG': 'en_US.UTF-8', 17:09:42 INFO - 'LANGUAGE': 'en_US:en', 17:09:42 INFO - 'LOGNAME': 'cltbld', 17:09:42 INFO - 'MAIL': '/var/mail/cltbld', 17:09:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:09:42 INFO - 'MOZ_NO_REMOTE': '1', 17:09:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:09:42 INFO - 'NO_EM_RESTART': '1', 17:09:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:09:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:09:42 INFO - 'PWD': '/builds/slave/test', 17:09:42 INFO - 'SHELL': '/bin/bash', 17:09:42 INFO - 'SHLVL': '1', 17:09:42 INFO - 'TERM': 'linux', 17:09:42 INFO - 'TMOUT': '86400', 17:09:42 INFO - 'USER': 'cltbld', 17:09:42 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1502582025.59989-1127655553', 17:09:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:09:42 INFO - '_': '/tools/buildbot/bin/python'} 17:09:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:09:42 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 17:09:42 INFO - Cleaning up... 17:09:42 INFO - Return code: 0 17:09:42 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 17:09:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:09:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:09:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:09:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:09:42 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 0x14cf1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x169ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x183cb20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x183ff00>, '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 0x183aba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x183b050>, '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': '5cf47e208febbcd81da23b2e0000026f-1502582025.59989-1127655553', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 17:09:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 17:09:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 17:09:42 INFO - Using env: {'DISPLAY': ':0', 17:09:42 INFO - 'HOME': '/home/cltbld', 17:09:42 INFO - 'LANG': 'en_US.UTF-8', 17:09:42 INFO - 'LANGUAGE': 'en_US:en', 17:09:42 INFO - 'LOGNAME': 'cltbld', 17:09:42 INFO - 'MAIL': '/var/mail/cltbld', 17:09:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:09:42 INFO - 'MOZ_NO_REMOTE': '1', 17:09:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:09:42 INFO - 'NO_EM_RESTART': '1', 17:09:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:09:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:09:42 INFO - 'PWD': '/builds/slave/test', 17:09:42 INFO - 'SHELL': '/bin/bash', 17:09:42 INFO - 'SHLVL': '1', 17:09:42 INFO - 'TERM': 'linux', 17:09:42 INFO - 'TMOUT': '86400', 17:09:42 INFO - 'USER': 'cltbld', 17:09:42 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1502582025.59989-1127655553', 17:09:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:09:42 INFO - '_': '/tools/buildbot/bin/python'} 17:09:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:09:43 INFO - Downloading/unpacking psutil>=3.1.1 17:09:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:09:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:09:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:09:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:09:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 17:09:44 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:09:44 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:09:44 INFO - Installing collected packages: psutil 17:09:44 INFO - Running setup.py install for psutil 17:09:44 INFO - building 'psutil._psutil_linux' extension 17:09:44 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 17:09:45 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 17:09:45 INFO - building 'psutil._psutil_posix' extension 17:09:45 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 17:09:45 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 17:09:46 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:09:46 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:09:46 INFO - Successfully installed psutil 17:09:46 INFO - Cleaning up... 17:09:46 INFO - Return code: 0 17:09:46 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 17:09:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:09:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:09:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:09:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:09:46 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 0x14cf1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x169ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x183cb20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x183ff00>, '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 0x183aba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x183b050>, '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': '5cf47e208febbcd81da23b2e0000026f-1502582025.59989-1127655553', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 17:09:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 17:09:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 17:09:46 INFO - Using env: {'DISPLAY': ':0', 17:09:46 INFO - 'HOME': '/home/cltbld', 17:09:46 INFO - 'LANG': 'en_US.UTF-8', 17:09:46 INFO - 'LANGUAGE': 'en_US:en', 17:09:46 INFO - 'LOGNAME': 'cltbld', 17:09:46 INFO - 'MAIL': '/var/mail/cltbld', 17:09:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:09:46 INFO - 'MOZ_NO_REMOTE': '1', 17:09:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:09:46 INFO - 'NO_EM_RESTART': '1', 17:09:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:09:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:09:46 INFO - 'PWD': '/builds/slave/test', 17:09:46 INFO - 'SHELL': '/bin/bash', 17:09:46 INFO - 'SHLVL': '1', 17:09:46 INFO - 'TERM': 'linux', 17:09:46 INFO - 'TMOUT': '86400', 17:09:46 INFO - 'USER': 'cltbld', 17:09:46 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1502582025.59989-1127655553', 17:09:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:09:46 INFO - '_': '/tools/buildbot/bin/python'} 17:09:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:09:46 INFO - Downloading/unpacking mozsystemmonitor==0.3 17:09:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:09:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:09:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:09:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:09:47 INFO - Downloading mozsystemmonitor-0.3.tar.gz 17:09:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:09:47 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 17:09:47 INFO - Installing collected packages: mozsystemmonitor 17:09:47 INFO - Running setup.py install for mozsystemmonitor 17:09:47 INFO - Successfully installed mozsystemmonitor 17:09:47 INFO - Cleaning up... 17:09:47 INFO - Return code: 0 17:09:47 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 17:09:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:09:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:09:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:09:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:09:47 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 0x14cf1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x169ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x183cb20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x183ff00>, '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 0x183aba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x183b050>, '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': '5cf47e208febbcd81da23b2e0000026f-1502582025.59989-1127655553', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 17:09:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 17:09:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 17:09:47 INFO - Using env: {'DISPLAY': ':0', 17:09:47 INFO - 'HOME': '/home/cltbld', 17:09:47 INFO - 'LANG': 'en_US.UTF-8', 17:09:47 INFO - 'LANGUAGE': 'en_US:en', 17:09:47 INFO - 'LOGNAME': 'cltbld', 17:09:47 INFO - 'MAIL': '/var/mail/cltbld', 17:09:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:09:47 INFO - 'MOZ_NO_REMOTE': '1', 17:09:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:09:47 INFO - 'NO_EM_RESTART': '1', 17:09:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:09:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:09:47 INFO - 'PWD': '/builds/slave/test', 17:09:47 INFO - 'SHELL': '/bin/bash', 17:09:47 INFO - 'SHLVL': '1', 17:09:47 INFO - 'TERM': 'linux', 17:09:47 INFO - 'TMOUT': '86400', 17:09:47 INFO - 'USER': 'cltbld', 17:09:47 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1502582025.59989-1127655553', 17:09:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:09:47 INFO - '_': '/tools/buildbot/bin/python'} 17:09:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:09:47 INFO - Downloading/unpacking jsonschema==2.5.1 17:09:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:09:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:09:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:09:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:09:48 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:09:48 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 17:09:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:09:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:09:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:09:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:09:49 INFO - Downloading functools32-3.2.3-2.tar.gz 17:09:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 17:09:49 INFO - warning: no files found matching '*.txt' 17:09:49 INFO - no previously-included directories found matching 'build' 17:09:49 INFO - no previously-included directories found matching 'dist' 17:09:49 INFO - no previously-included directories found matching '.git*' 17:09:49 INFO - Installing collected packages: jsonschema, functools32 17:09:49 INFO - Running setup.py install for functools32 17:09:49 INFO - warning: no files found matching '*.txt' 17:09:49 INFO - no previously-included directories found matching 'build' 17:09:49 INFO - no previously-included directories found matching 'dist' 17:09:49 INFO - no previously-included directories found matching '.git*' 17:09:49 INFO - Successfully installed jsonschema functools32 17:09:49 INFO - Cleaning up... 17:09:49 INFO - Return code: 0 17:09:49 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 17:09:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:09:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:09:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:09:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:09:49 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 0x14cf1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x169ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x183cb20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x183ff00>, '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 0x183aba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x183b050>, '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': '5cf47e208febbcd81da23b2e0000026f-1502582025.59989-1127655553', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 17:09:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 17:09:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 17:09:49 INFO - Using env: {'DISPLAY': ':0', 17:09:49 INFO - 'HOME': '/home/cltbld', 17:09:49 INFO - 'LANG': 'en_US.UTF-8', 17:09:49 INFO - 'LANGUAGE': 'en_US:en', 17:09:49 INFO - 'LOGNAME': 'cltbld', 17:09:49 INFO - 'MAIL': '/var/mail/cltbld', 17:09:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:09:49 INFO - 'MOZ_NO_REMOTE': '1', 17:09:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:09:49 INFO - 'NO_EM_RESTART': '1', 17:09:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:09:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:09:49 INFO - 'PWD': '/builds/slave/test', 17:09:49 INFO - 'SHELL': '/bin/bash', 17:09:49 INFO - 'SHLVL': '1', 17:09:49 INFO - 'TERM': 'linux', 17:09:49 INFO - 'TMOUT': '86400', 17:09:49 INFO - 'USER': 'cltbld', 17:09:49 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1502582025.59989-1127655553', 17:09:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:09:49 INFO - '_': '/tools/buildbot/bin/python'} 17:09:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:09:49 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 17:09:49 INFO - Cleaning up... 17:09:49 INFO - Return code: 0 17:09:49 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 17:09:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:09:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:09:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:09:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:09:49 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 0x14cf1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x169ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x183cb20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x183ff00>, '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 0x183aba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x183b050>, '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': '5cf47e208febbcd81da23b2e0000026f-1502582025.59989-1127655553', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 17:09:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 17:09:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 17:09:49 INFO - Using env: {'DISPLAY': ':0', 17:09:49 INFO - 'HOME': '/home/cltbld', 17:09:49 INFO - 'LANG': 'en_US.UTF-8', 17:09:49 INFO - 'LANGUAGE': 'en_US:en', 17:09:49 INFO - 'LOGNAME': 'cltbld', 17:09:49 INFO - 'MAIL': '/var/mail/cltbld', 17:09:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:09:49 INFO - 'MOZ_NO_REMOTE': '1', 17:09:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:09:49 INFO - 'NO_EM_RESTART': '1', 17:09:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:09:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:09:49 INFO - 'PWD': '/builds/slave/test', 17:09:49 INFO - 'SHELL': '/bin/bash', 17:09:49 INFO - 'SHLVL': '1', 17:09:49 INFO - 'TERM': 'linux', 17:09:49 INFO - 'TMOUT': '86400', 17:09:49 INFO - 'USER': 'cltbld', 17:09:49 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1502582025.59989-1127655553', 17:09:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:09:49 INFO - '_': '/tools/buildbot/bin/python'} 17:09:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:09:49 INFO - Downloading/unpacking blobuploader==1.2.4 17:09:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:09:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:09:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:09:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:09:50 INFO - Downloading blobuploader-1.2.4.tar.gz 17:09:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:09:50 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:09:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:09:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:09:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:09:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:09:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 17:09:51 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:09:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:09:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:09:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:09:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:09:51 INFO - Downloading docopt-0.6.1.tar.gz 17:09:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 17:09:51 INFO - Installing collected packages: blobuploader, requests, docopt 17:09:51 INFO - Running setup.py install for blobuploader 17:09:51 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:09:51 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 17:09:51 INFO - Running setup.py install for requests 17:09:51 INFO - Running setup.py install for docopt 17:09:51 INFO - Successfully installed blobuploader requests docopt 17:09:51 INFO - Cleaning up... 17:09:52 INFO - Return code: 0 17:09:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:09:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:09:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:09:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:09:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:09:52 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 0x14cf1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x169ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x183cb20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x183ff00>, '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 0x183aba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x183b050>, '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': '5cf47e208febbcd81da23b2e0000026f-1502582025.59989-1127655553', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 17:09:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 17:09:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 17:09:52 INFO - Using env: {'DISPLAY': ':0', 17:09:52 INFO - 'HOME': '/home/cltbld', 17:09:52 INFO - 'LANG': 'en_US.UTF-8', 17:09:52 INFO - 'LANGUAGE': 'en_US:en', 17:09:52 INFO - 'LOGNAME': 'cltbld', 17:09:52 INFO - 'MAIL': '/var/mail/cltbld', 17:09:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:09:52 INFO - 'MOZ_NO_REMOTE': '1', 17:09:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:09:52 INFO - 'NO_EM_RESTART': '1', 17:09:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:09:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:09:52 INFO - 'PWD': '/builds/slave/test', 17:09:52 INFO - 'SHELL': '/bin/bash', 17:09:52 INFO - 'SHLVL': '1', 17:09:52 INFO - 'TERM': 'linux', 17:09:52 INFO - 'TMOUT': '86400', 17:09:52 INFO - 'USER': 'cltbld', 17:09:52 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1502582025.59989-1127655553', 17:09:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:09:52 INFO - '_': '/tools/buildbot/bin/python'} 17:09:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:09:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:09:52 INFO - Running setup.py (path:/tmp/pip-tpL6RK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:09:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:09:52 INFO - Running setup.py (path:/tmp/pip-T2KUXW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:09:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:09:52 INFO - Running setup.py (path:/tmp/pip-9ufTrG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:09:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:09:52 INFO - Running setup.py (path:/tmp/pip-bFjviu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:09:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:09:52 INFO - Running setup.py (path:/tmp/pip-URS8ai-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:09:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:09:52 INFO - Running setup.py (path:/tmp/pip-cqeLKF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:09:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:09:52 INFO - Running setup.py (path:/tmp/pip-v1hEWV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:09:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:09:52 INFO - Running setup.py (path:/tmp/pip-7uLVta-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:09:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:09:52 INFO - Running setup.py (path:/tmp/pip-6n37cS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:09:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:09:52 INFO - Running setup.py (path:/tmp/pip-1oEAJH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:09:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:09:52 INFO - Running setup.py (path:/tmp/pip-XE6Q6S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:09:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:09:53 INFO - Running setup.py (path:/tmp/pip-XAv0bW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:09:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:09:53 INFO - Running setup.py (path:/tmp/pip-fm2MX7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:09:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:09:53 INFO - Running setup.py (path:/tmp/pip-x8tX1D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:09:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:09:53 INFO - Running setup.py (path:/tmp/pip-PeaRQZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:09:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:09:53 INFO - Running setup.py (path:/tmp/pip-xDGCyg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:09:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:09:53 INFO - Running setup.py (path:/tmp/pip-1mraFh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:09:53 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:09:53 INFO - Running setup.py install for manifestparser 17:09:53 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 17:09:53 INFO - Running setup.py install for mozcrash 17:09:53 INFO - Running setup.py install for mozdebug 17:09:53 INFO - Running setup.py install for mozdevice 17:09:53 INFO - Installing dm script to /builds/slave/test/build/venv/bin 17:09:53 INFO - Running setup.py install for mozfile 17:09:53 INFO - Running setup.py install for mozhttpd 17:09:54 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 17:09:54 INFO - Running setup.py install for mozinfo 17:09:54 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 17:09:54 INFO - Running setup.py install for mozInstall 17:09:54 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 17:09:54 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 17:09:54 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 17:09:54 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 17:09:54 INFO - Running setup.py install for mozleak 17:09:54 INFO - Running setup.py install for mozlog 17:09:54 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 17:09:54 INFO - Running setup.py install for moznetwork 17:09:54 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 17:09:54 INFO - Running setup.py install for mozprocess 17:09:54 INFO - Running setup.py install for mozprofile 17:09:54 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 17:09:54 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 17:09:54 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 17:09:54 INFO - Running setup.py install for mozrunner 17:09:54 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 17:09:54 INFO - Running setup.py install for mozscreenshot 17:09:55 INFO - Running setup.py install for moztest 17:09:55 INFO - Running setup.py install for mozversion 17:09:55 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 17:09:55 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:09:55 INFO - Cleaning up... 17:09:55 INFO - Return code: 0 17:09:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:09: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')]} 17:09:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:09: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')]} 17:09:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:09:55 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 0x14cf1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x169ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x183cb20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x183ff00>, '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 0x183aba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x183b050>, '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': '5cf47e208febbcd81da23b2e0000026f-1502582025.59989-1127655553', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 17:09:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 17:09:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 17:09:55 INFO - Using env: {'DISPLAY': ':0', 17:09:55 INFO - 'HOME': '/home/cltbld', 17:09:55 INFO - 'LANG': 'en_US.UTF-8', 17:09:55 INFO - 'LANGUAGE': 'en_US:en', 17:09:55 INFO - 'LOGNAME': 'cltbld', 17:09:55 INFO - 'MAIL': '/var/mail/cltbld', 17:09:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:09:55 INFO - 'MOZ_NO_REMOTE': '1', 17:09:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:09:55 INFO - 'NO_EM_RESTART': '1', 17:09:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:09:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:09:55 INFO - 'PWD': '/builds/slave/test', 17:09:55 INFO - 'SHELL': '/bin/bash', 17:09:55 INFO - 'SHLVL': '1', 17:09:55 INFO - 'TERM': 'linux', 17:09:55 INFO - 'TMOUT': '86400', 17:09:55 INFO - 'USER': 'cltbld', 17:09:55 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1502582025.59989-1127655553', 17:09:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:09:55 INFO - '_': '/tools/buildbot/bin/python'} 17:09:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:09:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:09:55 INFO - Running setup.py (path:/tmp/pip-j_1sI_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:09:55 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 17:09:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:09:55 INFO - Running setup.py (path:/tmp/pip-9iF8zd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:09:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 17:09:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:09:55 INFO - Running setup.py (path:/tmp/pip-nCRJeW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:09:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 17:09:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:09:55 INFO - Running setup.py (path:/tmp/pip-LvBDV6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:09:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 17:09:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:09:55 INFO - Running setup.py (path:/tmp/pip-X8ZQYq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:09:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 17:09:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:09:55 INFO - Running setup.py (path:/tmp/pip-w3YKQz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:09:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 17:09:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:09:55 INFO - Running setup.py (path:/tmp/pip-nhXX2u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:09:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 17:09:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:09:55 INFO - Running setup.py (path:/tmp/pip-2diyPN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:09:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.13 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 17:09:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:09:56 INFO - Running setup.py (path:/tmp/pip-6qkChB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:09:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 17:09:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:09:56 INFO - Running setup.py (path:/tmp/pip-34Uyg9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:09:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.5 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:09:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:09:56 INFO - Running setup.py (path:/tmp/pip-ptxmNs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:09:56 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 17:09:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:09:56 INFO - Running setup.py (path:/tmp/pip-9pmnaR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:09:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 17:09:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:09:56 INFO - Running setup.py (path:/tmp/pip-k1Ph5v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:09:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 17:09:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:09:56 INFO - Running setup.py (path:/tmp/pip-YhpRow-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:09:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 17:09:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:09:56 INFO - Running setup.py (path:/tmp/pip-MID_RD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:09:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 17:09:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:09:56 INFO - Running setup.py (path:/tmp/pip-tpYwlS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:09:56 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 17:09:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:09:56 INFO - Running setup.py (path:/tmp/pip-bWUo14-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:09:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 17:09:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 17:09:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 17:09:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 17:09:56 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 17:09:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 17:09:56 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.13->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 17:09:56 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:09: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 17:09: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 17:09: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 17:09: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 17:09:57 INFO - Downloading blessings-1.6.tar.gz 17:09:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 17:09:57 INFO - Installing collected packages: blessings 17:09:57 INFO - Running setup.py install for blessings 17:09:58 INFO - Successfully installed blessings 17:09:58 INFO - Cleaning up... 17:09:58 INFO - Return code: 0 17:09:58 INFO - Done creating virtualenv /builds/slave/test/build/venv. 17:09:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:09:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:09:58 INFO - Reading from file tmpfile_stdout 17:09:58 INFO - Current package versions: 17:09:58 INFO - argparse == 1.2.1 17:09:58 INFO - blessings == 1.6 17:09:58 INFO - blobuploader == 1.2.4 17:09:58 INFO - docopt == 0.6.1 17:09:58 INFO - functools32 == 3.2.3-2 17:09:58 INFO - jsonschema == 2.5.1 17:09:58 INFO - manifestparser == 1.1 17:09:58 INFO - mozInstall == 1.13 17:09:58 INFO - mozcrash == 1.0 17:09:58 INFO - mozdebug == 0.1 17:09:58 INFO - mozdevice == 0.51 17:09:58 INFO - mozfile == 1.2 17:09:58 INFO - mozhttpd == 0.7 17:09:58 INFO - mozinfo == 0.10 17:09:58 INFO - mozleak == 0.1 17:09:58 INFO - mozlog == 3.5 17:09:58 INFO - moznetwork == 0.27 17:09:58 INFO - mozprocess == 0.25 17:09:58 INFO - mozprofile == 0.28 17:09:58 INFO - mozrunner == 6.13 17:09:58 INFO - mozscreenshot == 0.1 17:09:58 INFO - mozsystemmonitor == 0.3 17:09:58 INFO - moztest == 0.8 17:09:58 INFO - mozversion == 1.4 17:09:58 INFO - psutil == 3.1.1 17:09:58 INFO - requests == 1.2.3 17:09:58 INFO - wsgiref == 0.1.2 17:09:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:09:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:09:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:09:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:09:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:09:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-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 0x14cf1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x169ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x183cb20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x183ff00>, '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 0x183aba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x183b050>, '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': '5cf47e208febbcd81da23b2e0000026f-1502582025.59989-1127655553', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 17:09:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 17:09:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 17:09:58 INFO - Using env: {'DISPLAY': ':0', 17:09:58 INFO - 'HOME': '/home/cltbld', 17:09:58 INFO - 'LANG': 'en_US.UTF-8', 17:09:58 INFO - 'LANGUAGE': 'en_US:en', 17:09:58 INFO - 'LOGNAME': 'cltbld', 17:09:58 INFO - 'MAIL': '/var/mail/cltbld', 17:09:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:09:58 INFO - 'MOZ_NO_REMOTE': '1', 17:09:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:09:58 INFO - 'NO_EM_RESTART': '1', 17:09:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:09:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:09:58 INFO - 'PWD': '/builds/slave/test', 17:09:58 INFO - 'SHELL': '/bin/bash', 17:09:58 INFO - 'SHLVL': '1', 17:09:58 INFO - 'TERM': 'linux', 17:09:58 INFO - 'TMOUT': '86400', 17:09:58 INFO - 'USER': 'cltbld', 17:09:58 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1502582025.59989-1127655553', 17:09:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:09:58 INFO - '_': '/tools/buildbot/bin/python'} 17:09:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:09:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 17:09:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 17:09:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 17:09:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 17:09:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 17:09:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 17:09:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 17:09:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 17:09:58 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 17:09:58 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 17:09: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 17:09: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 17:09: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 17:09: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 17:09:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 17:09:59 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 17:09:59 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 17:09:59 INFO - Installing collected packages: simplejson 17:09:59 INFO - Running setup.py install for simplejson 17:09:59 INFO - building 'simplejson._speedups' extension 17:09:59 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c simplejson/_speedups.c -o build/temp.linux-x86_64-2.7/simplejson/_speedups.o 17:10:00 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/simplejson/_speedups.o -o build/lib.linux-x86_64-2.7/simplejson/_speedups.so 17:10:00 INFO - Successfully installed simplejson 17:10:00 INFO - Cleaning up... 17:10:00 INFO - Return code: 0 17:10:00 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 17:10: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')]} 17:10:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:10: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')]} 17:10:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:10: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14cf1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x169ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x183cb20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x183ff00>, '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 0x183aba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x183b050>, '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': '5cf47e208febbcd81da23b2e0000026f-1502582025.59989-1127655553', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 17:10: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', 'jsonschema'] in /builds/slave/test/build 17:10: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 jsonschema 17:10:00 INFO - Using env: {'DISPLAY': ':0', 17:10:00 INFO - 'HOME': '/home/cltbld', 17:10:00 INFO - 'LANG': 'en_US.UTF-8', 17:10:00 INFO - 'LANGUAGE': 'en_US:en', 17:10:00 INFO - 'LOGNAME': 'cltbld', 17:10:00 INFO - 'MAIL': '/var/mail/cltbld', 17:10:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:10:00 INFO - 'MOZ_NO_REMOTE': '1', 17:10:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:10:00 INFO - 'NO_EM_RESTART': '1', 17:10:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:10:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:10:00 INFO - 'PWD': '/builds/slave/test', 17:10:00 INFO - 'SHELL': '/bin/bash', 17:10:00 INFO - 'SHLVL': '1', 17:10:00 INFO - 'TERM': 'linux', 17:10:00 INFO - 'TMOUT': '86400', 17:10:00 INFO - 'USER': 'cltbld', 17:10:00 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1502582025.59989-1127655553', 17:10:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:10:00 INFO - '_': '/tools/buildbot/bin/python'} 17:10:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:10:00 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 17:10:00 INFO - Cleaning up... 17:10:00 INFO - Return code: 0 17:10:00 INFO - Running post-action listener: _resource_record_post_action 17:10:00 INFO - Running post-action listener: _start_resource_monitoring 17:10:01 INFO - Starting resource monitoring. 17:10:01 INFO - [mozharness: 2017-08-13 00:10:01.003303Z] Finished create-virtualenv step (success) 17:10:01 INFO - [mozharness: 2017-08-13 00:10:01.003886Z] Running install step. 17:10:01 INFO - Running pre-action listener: _resource_record_pre_action 17:10:01 INFO - Running main action method: install 17:10:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:10:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:10:01 INFO - Reading from file tmpfile_stdout 17:10:01 INFO - Detecting whether we're running mozinstall >=1.0... 17:10:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 17:10:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 17:10:01 INFO - Reading from file tmpfile_stdout 17:10:01 INFO - Output received: 17:10:01 INFO - Usage: mozinstall [options] installer 17:10:01 INFO - Options: 17:10:01 INFO - -h, --help show this help message and exit 17:10:01 INFO - -d DEST, --destination=DEST 17:10:01 INFO - Directory to install application into. [default: 17:10:01 INFO - "/builds/slave/test"] 17:10:01 INFO - --app=APP Application being installed. [default: firefox] 17:10:01 INFO - mkdir: /builds/slave/test/build/application 17:10:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 17:10:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 17:10:19 INFO - Reading from file tmpfile_stdout 17:10:19 INFO - Output received: 17:10:19 INFO - /builds/slave/test/build/application/firefox/firefox 17:10:19 INFO - Running post-action listener: _resource_record_post_action 17:10:19 INFO - [mozharness: 2017-08-13 00:10:19.274642Z] Finished install step (success) 17:10:19 INFO - [mozharness: 2017-08-13 00:10:19.274790Z] Running setup-mitmproxy step. 17:10:19 INFO - Running pre-action listener: _resource_record_pre_action 17:10:19 INFO - Running main action method: setup_mitmproxy 17:10:19 INFO - Skipping: mitmproxy is not required 17:10:19 INFO - Running post-action listener: _resource_record_post_action 17:10:19 INFO - [mozharness: 2017-08-13 00:10:19.275333Z] Finished setup-mitmproxy step (success) 17:10:19 INFO - [mozharness: 2017-08-13 00:10:19.275438Z] Running run-tests step. 17:10:19 INFO - Running pre-action listener: _resource_record_pre_action 17:10:19 INFO - Running pre-action listener: _set_gcov_prefix 17:10:19 INFO - Running main action method: run_tests 17:10:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 17:10:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 17:10:19 INFO - Python 2.7.3 17:10:19 INFO - Return code: 0 17:10:19 INFO - grabbing minidump binary from tooltool 17:10:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:10:19 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 0x183ff00>, '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 0x183aba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x183b050>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 17:10:19 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 17:10:19 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 17:10:19 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 17:10:19 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:10:19 INFO - Return code: 0 17:10:19 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 17:10:19 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 17:10:19 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 17:10:19 INFO - ENV: RUST_BACKTRACE is now full 17:10:19 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 17:10:19 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 17:10:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/CP2xHjBSS1Kh1OeG7_y_fA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-011', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 17:10:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/CP2xHjBSS1Kh1OeG7_y_fA/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-011 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 17:10:19 INFO - Using env: {'DISPLAY': ':0', 17:10:19 INFO - 'HOME': '/home/cltbld', 17:10:19 INFO - 'LANG': 'en_US.UTF-8', 17:10:19 INFO - 'LANGUAGE': 'en_US:en', 17:10:19 INFO - 'LOGNAME': 'cltbld', 17:10:19 INFO - 'MAIL': '/var/mail/cltbld', 17:10:19 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:10:19 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 17:10:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:10:19 INFO - 'MOZ_NO_REMOTE': '1', 17:10:19 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:10:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:10:19 INFO - 'NO_EM_RESTART': '1', 17:10:19 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:10:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:10:19 INFO - 'PWD': '/builds/slave/test', 17:10:19 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:10:19 INFO - 'RUST_BACKTRACE': 'full', 17:10:19 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 17:10:19 INFO - 'SHELL': '/bin/bash', 17:10:19 INFO - 'SHLVL': '1', 17:10:19 INFO - 'STYLO_FORCE_ENABLED': '1', 17:10:19 INFO - 'TERM': 'linux', 17:10:19 INFO - 'TMOUT': '86400', 17:10:19 INFO - 'USER': 'cltbld', 17:10:19 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1502582025.59989-1127655553', 17:10:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:10:19 INFO - '_': '/tools/buildbot/bin/python'} 17:10:19 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/CP2xHjBSS1Kh1OeG7_y_fA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-011', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 17:10:20 INFO - mozversion application_buildid: 20170812232023 17:10:20 INFO - mozversion application_changeset: 59594b3879cc7b038ba8a1ed6e9e66c6423bf6e2 17:10:20 INFO - mozversion application_display_name: Nightly 17:10:20 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:10:20 INFO - mozversion application_name: Firefox 17:10:20 INFO - mozversion application_remotingname: firefox 17:10:20 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 17:10:20 INFO - mozversion application_vendor: Mozilla 17:10:20 INFO - mozversion application_version: 57.0a1 17:10:20 INFO - mozversion platform_buildid: 20170812232023 17:10:20 INFO - mozversion platform_changeset: 59594b3879cc7b038ba8a1ed6e9e66c6423bf6e2 17:10:20 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 17:10:20 INFO - mozversion platform_version: 57.0a1 17:10:20 INFO - using testdate: 1502583020 17:10:20 INFO - actual date: 1502583020 17:10:20 INFO - starting webserver on 'localhost:51686' 17:10:20 INFO - SUITE-START | Running 2 tests 17:10:20 INFO - TEST-START | dromaeo_css 17:10:20 INFO - Initialising browser for dromaeo_css test... 17:10:20 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:51686/getInfo.html -profile /tmp/tmpRc_yNR/profile 17:10:20 INFO - TEST-INFO | started process 3326 (/builds/slave/test/build/application/firefox/firefox http://localhost:51686/getInfo.html) 17:10:29 INFO - TEST-INFO | 3326: exit 0 17:10:30 INFO - Browser initialized. 17:10:30 INFO - Running cycle 1/1 for dromaeo_css test... 17:10:30 INFO - TEST-INFO | started process 3561 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpRc_yNR/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 17:10:31 INFO - PID 3561 | 17:10:31 INFO - PID 3561 | (/builds/slave/test/build/application/firefox/firefox:3616): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 17:10:31 INFO - PID 3561 | 17:10:41 INFO - PID 3561 | RSS: Main: 171474944 17:10:41 INFO - PID 3561 | 17:13:40 INFO - PID 3561 | Cycle 1(1): loaded http://localhost:51686/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51686/tests/dromaeo/cssquery-ext.html) 17:13:42 INFO - PID 3561 | RSS: Main: 175976448 17:13:42 INFO - PID 3561 | 17:16:43 INFO - PID 3561 | Cycle 1(1): loaded http://localhost:51686/tests/dromaeo/cssquery-ext.html (next: http://localhost:51686/tests/dromaeo/cssquery-jquery.html) 17:16:44 INFO - PID 3561 | RSS: Main: 171335680 17:16:44 INFO - PID 3561 | 17:19:46 INFO - PID 3561 | Cycle 1(1): loaded http://localhost:51686/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51686/tests/dromaeo/cssquery-mootools.html) 17:19:46 INFO - PID 3561 | RSS: Main: 174804992 17:19:46 INFO - PID 3561 | 17:22:48 INFO - PID 3561 | Cycle 1(1): loaded http://localhost:51686/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51686/tests/dromaeo/cssquery-prototype.html) 17:22:49 INFO - PID 3561 | RSS: Main: 173178880 17:22:49 INFO - PID 3561 | 17:25:53 INFO - PID 3561 | Cycle 1(1): loaded http://localhost:51686/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51686/tests/dromaeo/cssquery-yui.html) 17:25:53 INFO - PID 3561 | RSS: Main: 177020928 17:25:53 INFO - PID 3561 | 17:28:55 INFO - PID 3561 | Cycle 1(1): loaded http://localhost:51686/tests/dromaeo/cssquery-yui.html (next: http://localhost:51686/tests/dromaeo/cssquery-dojo.html) 17:28:55 INFO - PID 3561 | RSS: Main: 177807360 17:28:55 INFO - PID 3561 | 17:28:55 INFO - PID 3561 | __start_tp_report 17:28:55 INFO - PID 3561 | _x_x_mozilla_page_load 17:28:55 INFO - PID 3561 | _x_x_mozilla_page_load_details 17:28:55 INFO - PID 3561 | |i|pagename|runs| 17:28:55 INFO - PID 3561 | |0;dojo.html;3100.968990310096;3137.9686203137962;3154.6056742907135;3185.0444866540033;3210.117217765114;6108.389161083889;6229.439350458458;6254.968725156374;6264.655443950581;6279.18370611821;6017.849553761158;6155.569110162291;6162.784302549411;6171.290301615304;6198.814035578928;5661.688607126606;5680.829575112753;5688.374278829326;5692.943070569303;5713.028785106521;5702.885942281152;5723.885522289552;5745.5690823188215;5751.194832723422;5814.069748840187;5725.513331366838;5759.5968282220265;5776.277965254343;5792.971035144839;5822.330432000332;5516.282883225155;5710.714464276791;5761.769529218826;5784.537237021028;5808.448197421239;5627.296587926509;5670.8015219467125;5697.173909783059;5698.601097923147;5730.398308177623;6075.574709770318;6166.229221347332;6181.690915454209;6224.253089629274;6241.3446588108045;6379.681015949184;6606.867862642768;6628.403443690043;6649.767258145942;6761.594304341756;6636.303188165221;6703.430208432289;6703.631300278483;6704.296048914843;6706.966465167643;6166.660833654102;6211.47202487789;6221.968890155611;6225.688715564203;6229.719662615148;6892.310768923067;6900.2409734929115;6911.343422374866;6912.585244885323;6913.10129683138;6529.053287273414;6554.606723596599;6618.040384144272;6619.86760264792;6633.502487313471;6595.307492713292;6625.536212465081;6712.53012289135;6738.258791532928;6755.392014718699;6460.709268082947;6554.082428460019;6577.3422657733845;6602.3067577904585;6715.764948226789;6708.496862735315;6710.060591517192;6722.428593569503;6728.394444500019;6793.762218322335;5383.703896285663;5466.781328746885;5469.671819690831;5474.315710536183;5484.369297530755;4553.476350219701;4622.976885115553;4639.118567472169;4659.487456379796;4680.110778951988;5646.717664116778;5699.059655156852;5722.484976352148;5723.942760572341;5725.570582206351;6764.898526522106;6767.255601883789;6770.627615481098;6772.018057381751;6774.559653622598;5777.480026797609;5811.88376232473;5818.709064546756;5832.708364581754;5842.970785146047;5843.649381037151;5879.412058794172;5936.436038576415;5936.851578710481;5952.761889524457;6355.713992870332;6441.838954026187;6475.514336424692;6484.708188131545;6586.110875031905;6736.326367363225;6738.9217725163735;6754.59472431636;6757.45940324763;6769.796906092825;6419.165508483868;6470.805875823733;6512.251091124392;6547.148870646785;6556.147700798866;6558.672066396756;6564.4748420127235;6605.438537724347;6661.533692641468;6693.832654183684;6583.3087525811015;6689.130413046273;6699.43054840344;6700.865982680221;6775.525713200029;6423.454006409321;6436.935630643634;6445.838854028687;6452.35476452351;6455.386738259858;6397.3282805306635;6451.3548645135115;6463.612183269019;6473.77341793035;6481.254655714653;6144.477719393902;6394.360563943755;6410.935890641033;6469.8706025880165;6488.740450381932;6297.559170857996;6505.479561634963;6526.118973938457;6544.3782840630065;6638.867222655616;6294.339094395023;6297.96851015742;6398.872022559616;6407.679616019273;6415.582987105823 17:28:55 INFO - PID 3561 | |1;ext.html;756.772968109567;835.2816577743142;860.298856432008;860.7632900952237;1124.3197865291493;29259;30159;40299.798501007455;40957.18085638285;40995.79502102485;41818;42005;42264.57735422645;42266.57733422673;42315.78842105785;7461.5523068615785;7595.848083038336;7609.010828592275;7613.505122167041;7615.048118985127;7481.289277518627;7532.359749421305;7583.203763604824;7589.165191828894;7589.582572958485;7067.823304417405;7552.358049565793;7563.470557061008;7575.090989081318;7609.543427394374;7505.849883002336;7562.886556701654;7570.886436703398;7571.659275332596;7572.7728168155045;7404.518706284074;7484.550926944401;7485.288897554724;7491.6254187290415;7491.887621685679;37215.441768373494;37323;38039.61960380388;38041;38184.61815381838;3379.678930501598;3420.555327807369;3420.9828950855335;3423.8116903570294;3424.4692072728767;30979.22551936197;31191.844040779877;31217.687823121705;31223.687763122307;31322.530162047588;4727.267273572602;4918.188498897677;4939.851804445872;4966.726830024347;4997.6501644884875;190525;191210;191380;191465;192162;46753.5324646756;46756.53243467522;47045.76477117593;47088;47371.52628473671;49584.75207623939;49601.25598116031;49683.751581242585;49729;49829.50170498321;15252.923735381251;15305.46430874914;15358.769618455732;15413.460528881435;15415.922920385327;12545.310007949467;12570.748585028248;12576.30830304342;12577.874221257853;12594.43325050375;14274.785878211627;14425.20661363635;14571.562853114212;14647.340869660678;14690.11859288446;5876.382979787147;5926.081457374114;5985.700714964234;6151.84620384494;6154.78458253959;1001.7445551384351;1002.8044531316469;1016.3444578246989;1021.6934919524261;1025.5231317437429;24030;24376.39059023503;24472.388190295384;24497;24664.753352466603;1112.5660992213043;1116.7989761842946;1123.0622430270816;1127.1208457403238;1127.5940661361903;12805.103642745105;13078.346082695827;13081.542146024844;13101.868981310256;13185.53850615224;237874;238744;239003;239494;239676;53003;53091.46908530942;53175;53371.19943200855;53492;1414.6816966182528;1452.9200893950933;1463.27567853913;1466.0763718857265;1471.448206922404;23069.423264417852;23080.4229894254;23226.187083451998;23245.65131523062;23396.6490502646;36356.454653179695;36399.45400819043;36485.63514364822;36527.45208821923;36545.26909461849;198644;199231;199822;200074;200220;33986.3202735939;35345;35447.64552354546;35576.28847423089;35701.46447803233;38473.42289865598;39328;43804;43959.56040439683;44204.77897610491;26126.477470450864;31716.524252136696;32079.67920320767;32417.67582324241;32759.67240327566;31425.21436964094;31458;31634.841825790725;32230.35539289248;32415.189620259684;35236.47145292874;35256.29487410289;35367;35403.291934160654;35470.11324716903;30807;31070;31135;31338.686613133577;31582;22423;24825.62761558614;25409.61885571755;25495.391013976896;25856.612150818128 17:28:55 INFO - PID 3561 | |2;jquery.html;565.2284631478034;565.7850016993542;566.0970751651117;573.3893403525245;591.2874985642302;21969;22678.88660556699;22773.31680049597;22930.08279668811;22943.885280573573;25670;26322.07872724455;26404.33989150272;26441.338966525847;26452.73547264527;6381.361863813617;6490.837729056764;6515.869682606345;6533.05270735743;6534.640594767286;6359.904601430982;6431.42117209451;6623.2052153741615;6643.169603799525;6658.633775142365;6606.8017959461295;6610.636414997173;6613.173353330833;6625.271220165778;6626.900596491057;6617.834554136137;6632.137822083122;6632.469402447792;6639.302873198341;6646.435053020499;6122.295935967376;6125.142480052797;6129.1725617041575;6131.172291740602;6135.877282454326;28058;28726.138215853556;29267.219614136837;29355.85322073376;29435.852820735974;1848.5655870870335;1860.8883466991888;1869.953251168718;1869.962600747991;1872.925082996692;18880.5279868003;19221.423357299303;19379.612407751905;19457.02714864251;19511.707324390147;2378.444363500345;2390.3665528634924;2466.2847774145475;2481.9131330403347;2485.1302044284535;127536;130482.34758826144;130829;130968.34515827455;131615;13885.514007009706;14703.55889323322;15026;15157.848421515864;15405.460808871636;22805.657915131287;23491.295261142193;23628;23654.4086397838;23709.644355334683;9119;9134.817303654023;9198.862017069749;9237.538123093818;9309.674161404319;8217.58912054395;8281.503109813431;8301.875471867927;8336.166383361615;8369.665213391518;9048.592813323416;9360.812783744423;9366.203872670763;9449.952750236205;9661.758456038655;2619.2142357292737;2659.1091984185514;2748.532749432601;2760.83434993897;2811.7750579953554;935.8409070458039;938.3525367496405;943.42451104826;965.4545181972163;967.685502211784;19107.61784764297;20112.296069637494;23874.761252387252;24249;24968.750312497006;1008.566316483919;1013.4628646817199;1029.3772267777886;1034.5965073621294;1043.8642976413169;11281.60514382009;11673;11680.70798230034;11759.118066145073;11766.294022358687;506380;519144;527182;527741;532490;119674;122079.38960305142;122463.38768306101;123911;124975;1220.0849362977767;1232.0759430427179;1266.4617537546505;1276.7957126859844;1278.0861683895873;21347;21375.358739237847;21383.893080534497;21462.463438414165;21769.23807666724;49091.75454122706;51906;54878.45121548893;56427.71786141043;58520.4147958532;69398;69898;70071;70332.29667703257;70907;14801;14978.925105374403;15048.623784405478;15128.773068403763;15146.924265379044;23386.415339616644;23474.17840375647;23562;23775.286741397787;23928.76071239335;17550.912245438692;18634.81365186385;18982.810171898658;19088.236470541022;19106.90446547814;16977;16979.40572080021;17225.913870430566;17620.64758704793;17636;19034.714479282546;19049.428517144508;19192;19388.709169362188;19424;14636.341364638829;15173.393064277747;15178.848211518185;15183;15187.544373668808;17907.37324193699;18080.09599520045;18205.362812301504;18209.45371638853;18232.36186733458 17:28:55 INFO - PID 3561 | |3;mootools.html;1305.5365345302419;1380.5168191133105;1389.9513517026903;1392.8607139286073;1396.2390497179038;13970.441182352706;14244.430222791101;14572.417103315855;14576.927115364435;14579.562613121598;13919.164850109;14941.775873361908;14951.925240373783;14954.626134346647;14964.32660530276;2667.293167310664;2713.6200931869553;2720.6327145835257;2721.3876877702555;2726.195772247182;2621.89512419503;2796.4407118576264;2804.5652923796742;2807.4244779820087;2814.577813328007;2636.156429942419;2654.588538776493;2678.370582913014;2697.8111532192756;2700.3654141276784;2650.6819181698224;2684.8254863433813;2687.193841847448;2695.932601684954;2710.6340644013003;2580.5484040293063;2607.8044146688885;2612.0857699805106;2618.2930608735724;2622.868856557184;13957.162570245819;14206;14213.715725685532;14215.431382744679;14220;7259.382952449048;7482.326590606817;7496.175420703718;7523;7523.586202758846;5077.619178561623;5222.63441559091;5261.657992230493;5321.574274058066;5335.653182543122;1810.4116162247321;1845.769278840145;1871.5788947486842;1894.9526261843425;1910.837578805803;22741.204057857896;23819.618038196215;24031.399215019763;24071;24124.27627171188;6375.649339286291;6577.736890524421;6724.562903411263;6734.8989765153565;6746.561473504206;16192.75710864338;16193.109378984032;16213.189340532927;16301.673966520602;16317.510474685778;7287.744928927462;7492.662830172655;7508.211637778064;7527.736529221451;7528.096628404626;6561.606303621798;6701.966490167518;6704.631245281556;6832.282610325944;6960.860782784316;4960.553550180429;5100.0819852426175;5112.642115051911;5145.176771716508;5160.767765450564;1466.9193194374197;1477.637978695205;1483.339913738376;1499.175453500592;1502.6393665520416;848.8344772769251;860.0582362313257;868.9608967596473;877.8551538995994;882.250087425683;8135.372925414822;8183.222593853575;8354.248117669318;8376.53929033909;8478.618462169217;721.1850608812009;724.4240828541323;726.7056841979007;730.3974221267475;733.1898252430987;4670.299455081765;4686.086213188463;4710.882227944328;4729.4797572267025;4733.384659994179;19484.220631174594;19924.60150796976;20036.499087522636;20068;20107.798922010887;17325.2203650836;18698.81301186998;18708.25166993332;18728.34450794243;18771.436856894314;9228.95385523068;10118.645847395306;10211.489425528842;10262.743431414274;10263.794724105475;6708.8993665094085;8487;8488.193621605938;8505.064442911398;8602.956985215034;15862.365505380118;16299.266533006281;16684.49946501607;16764.497065088068;16935.23791429364;16863;17350.913245434196;17811;18265.634687305912;18386.356477523223;10320.329178603366;10360.430176340373;10438;10442.269041167348;10443.738906527398;12886.806697899352;13270.668233294246;13326.93336533311;13339.733205335644;13344.399502022627;10851.348919064882;10940.671779846618;11028.558857645605;11200.663980080612;11359.602413915472;10733.570657173937;10805.189610779065;10860.131189504978;10965.287256328313;11163.776724465302;9631.855522167032;9672.613095476103;9716.659916902874;9798.608055677692;9915.256355773201;7116.608586527791;7312.268773122858;7321.707131714672;7338.339549440576;7399.186089530255;7054.470914681522;7099.893501597377;7137.571745695275;7163.785086447415;7198.71205151788 17:28:55 INFO - PID 3561 | |4;prototype.html;496.8329374505146;500.11767474699917;516.5118962580364;520.6979951628056;521.0932976620682;6215.192025036745;6280.434760871521;6284.717187726552;6286;6383.212700443872;5992.550558708103;6132.167395377889;6198.96900515498;6217.9378206217925;6222.12890195373;4919.933627876283;4942.456329803718;4994.900101997949;5004.599632029429;5019.673721208128;4943.703377797344;4979.427365852937;5000.899982000358;5015.5486006259425;5073.416557095944;4729.810807567693;4935.161022626145;4961.727105009241;4964.5531902128805;4969.130402179622;4989.401271847383;5013.523715247064;5040.092783299005;5041.319421878036;5053.393592768891;4406.383106365111;4443.888902777424;4450.577195166454;4461.330800379937;4507.729536227804;5959.791407300723;5999.190109335227;6054.212952316171;6070.150178974947;6071.544634152457;5723.141528770718;5748.338941021794;5751.281089863767;5764.654120752768;5765.423457654243;6622.1722284714415;6634.810512530853;6640.203175618908;6659.93340066598;6683.93316066838;4829.323894654751;4853.126437241295;4853.150698627726;4872.707637541759;4878.585320247783;6837.3162683731725;6944.1666999959825;6975.720971161199;6984.196817365966;7029.332213439715;4987.950120498748;4996.325496058058;5035.320231768665;5070.214116811826;5124.871878203;5089.745512724349;5090.211017292326;5096.439391666913;5126.256692779526;5153.561947234452;5745.741441635137;5747.310322761212;5816.709164541756;5829.8251052468495;5846.269216347957;6687.598744075371;6689.799306020827;6742.292059333798;6761.222459417131;6777.3900348967845;1679.8068222154363;1718.788254280729;1735.9566010849578;1763.8941663500232;1764.5059383372677;1196.6350263169643;1220.9633710988685;1230.7415442756942;1240.1628900492312;1244.1974926172568;5695.493982647622;5723.481668066038;5808.3901190375245;5810.738516766755;5823.515003674121;6660.966695166493;6698.531102822853;6728.764493242713;6734.898976515258;6793.558418702769;5638.6052976292085;5780.653160810365;5815.662397648564;5845.269341332333;5872.973824030702;3604.819759012039;3625;3629.3467175908063;3646.2889736501656;3672.155404257036;6593.120960526227;6597.538172327987;6675.432588229958;6685.966570167118;6726.529142959946;6994.650267486707;7026.508144429943;7044.22513523512;7063.434925205971;7115.53749006313;6645;6671.1994560652065;6711.798646040626;6712.530122891449;6767.154105736783;6743.696533655899;6913.75801846933;6922.58464492132;6922.723091076301;6923.238443771282;6719.345858386008;6766.4248538874745;6832.248452670103;6836.93163068363;6858.211305699709;6516.934830651633;6580.868382632416;6604.801855944137;6681.465482761465;6724.29394913527;5043.092243396224;5064.974010395724;5072.492750724898;5098.439171691037;5104.336436263262;6822.795316140524;6866.34769696878;6884.896726549206;6920.757773477904;6936.410405115622;6554.934450655433;6657.3342665733035;6657.567258128206;6690.498212633975;6730.721162979018;6612.8346791330605;6618.768343107968;6638.805015097329;6647.235567909559;6679.398854103154;6357.185688586811;6504.113806995918;6515.706793194223;6544.934550654623;6560.3111673273615;6468.6442245676935;6549.083128361938;6613.081292243319;6636.203655561363;6648.634325112165;6404.583702059351;6441.452476539546;6473.902891456726;6490.578112422678;6492.642904640101 17:28:55 INFO - PID 3561 | |5;yui.html;892.5180402582606;914.2183433164644;914.8627705844125;916.4913473022469;932.8320902237595;18632.720509192357;19536.21855125793;19556.11997460114;19633.607327853453;21214.681779773317;21154.25960091397;21724.674129888066;21778.237761678345;21945.67081493775;21972.560548789013;1022.5347466902541;1052.163529993657;1060.4220699718633;1066.7493139112303;1068.2949253492695;955.904409559042;1065.7974984752911;1067.6370034188374;1068.2201992545447;1068.4123731947398;1066.1524088349781;1067.0076828549484;1073.420353009374;1075.2097208551709;1110.3282514078987;1059.8887116852773;1062.6493257225136;1063.8670166229222;1066.4774260612282;1105.7346236903165;780.582388422197;781.2187812187813;782.3858271257036;789.2068471186421;819.622973432216;17551.561210969827;17673.911630441766;17709.557261068447;17713.645727085513;18931.526711832314;21444;21634.24280150187;21989.340319790273;22159.22442714497;22177.44556386087;16958;17381.39165129227;17548.91225543864;17583.472495825146;17598.208080636403;3556.3776339140804;3559.4126969050076;3562.590302115238;3563.6080031196548;3569.678728914384;123289;126105;126958.36520817429;128504;129732;15273.465428710164;15283.159426231665;15383.307751151226;15710.057396556243;15806.92096539533;31347.686523134933;31813.20466988344;31873.840630796698;31879.362412752078;32291.838540807614;7693.4614576979075;7983.2415920487465;8080.313173380211;8627.309815214769;8939.508327042075;4433.866983990485;4496.145732310851;4502.887427814331;4503.41455610775;4522.389477420572;5308.893822123613;5438.15708565173;5447.645903016291;5459.344878614592;5508.173773933942;1051.2168434516182;1071.4267866691282;1085.3650614390513;1087.3801932898173;1087.7933192693363;601.5217901768104;618.9473894718999;622.9937700623026;625.1310678157365;639.4277121975898;10993.780124397626;11167.83248251277;11550.94224528872;12077;12238.388080595934;609.1745684597307;626.7117126121943;627;630.0328994992665;636.045931103345;5446.291982042389;5480.369757477862;5481.424450432647;5505.229267902497;5608.69152196625;112884.43557782158;113022.43488782503;113368;116035.41982290204;117959.41020294843;99557.50221248847;99771;102458;104255;104626.47686761517;87392.5630371844;87796.12203877879;90480.5475972616;91676;92165.53917230236;9842.311038227255;9914.454704991007;10123.59505619767;10160.441175735405;10310.639127630497;40160.1967960645;41232.58767412407;41248.1750364997;41340;41870.371944421466;50093.49906500888;50892.236616451526;50925.49074509207;51694.74152629213;52594.21108683296;15468;15495;15711.371545138067;15751;15778.526644200692;16632.50102496927;16840.326386944384;20054.59890802205;20294.492637684176;20302;17352.392666257125;17443.12784360802;17621.647567048843;17671.38150164738;17752.46742597724;17923.193456294757;17993.910030449762;18103.547411314823;18410.815891841445;18440.077996099873;16460.83539164593;16557.834421656113;16574.254158563133;16663.3334666612;16694.833051669328;11837.526498940291;12068.215565988183;12393.380330983595;12421.441035153259;12844.422001010164;12860.164089334163;13217.669558261121;13277.40251688657;13286.86713132895;13629.773320401211 17:28:55 INFO - PID 3561 | __end_tp_report 17:28:55 INFO - PID 3561 | __start_cc_report 17:28:55 INFO - PID 3561 | _x_x_mozilla_cycle_collect,379 17:28:55 INFO - PID 3561 | __end_cc_report 17:28:55 INFO - PID 3561 | __startTimestamp1502584135704__endTimestamp 17:28:55 INFO - PID 3561 | 17:28:55 INFO - PID 3561 | ------- Summary: start ------- 17:28:55 INFO - PID 3561 | Number of tests: 6 17:28:55 INFO - PID 3561 | 17:28:55 INFO - PID 3561 | [#0] dojo.html Cycles:165 Average:6144.78 Median:6397.33 stddev:722.29 (11.3%) stddev-sans-first:683.90 17:28:55 INFO - PID 3561 | Values: 3101.0 3138.0 3154.6 3185.0 3210.1 6108.4 6229.4 6255.0 6264.7 6279.2 6017.8 6155.6 6162.8 6171.3 6198.8 5661.7 5680.8 5688.4 5692.9 5713.0 5702.9 5723.9 5745.6 5751.2 5814.1 5725.5 5759.6 5776.3 5793.0 5822.3 5516.3 5710.7 5761.8 5784.5 5808.4 5627.3 5670.8 5697.2 5698.6 5730.4 6075.6 6166.2 6181.7 6224.3 6241.3 6379.7 6606.9 6628.4 6649.8 6761.6 6636.3 6703.4 6703.6 6704.3 6707.0 6166.7 6211.5 6222.0 6225.7 6229.7 6892.3 6900.2 6911.3 6912.6 6913.1 6529.1 6554.6 6618.0 6619.9 6633.5 6595.3 6625.5 6712.5 6738.3 6755.4 6460.7 6554.1 6577.3 6602.3 6715.8 6708.5 6710.1 6722.4 6728.4 6793.8 5383.7 5466.8 5469.7 5474.3 5484.4 4553.5 4623.0 4639.1 4659.5 4680.1 5646.7 5699.1 5722.5 5723.9 5725.6 6764.9 6767.3 6770.6 6772.0 6774.6 5777.5 5811.9 5818.7 5832.7 5843.0 5843.6 5879.4 5936.4 5936.9 5952.8 6355.7 6441.8 6475.5 6484.7 6586.1 6736.3 6738.9 6754.6 6757.5 6769.8 6419.2 6470.8 6512.3 6547.1 6556.1 6558.7 6564.5 6605.4 6661.5 6693.8 6583.3 6689.1 6699.4 6700.9 6775.5 6423.5 6436.9 6445.8 6452.4 6455.4 6397.3 6451.4 6463.6 6473.8 6481.3 6144.5 6394.4 6410.9 6469.9 6488.7 6297.6 6505.5 6526.1 6544.4 6638.9 6294.3 6298.0 6398.9 6407.7 6415.6 17:28:55 INFO - PID 3561 | 17:28:55 INFO - PID 3561 | [#1] ext.html Cycles:180 Average:37628.15 Median:3420.77 stddev:54681.72 (1598.5%) stddev-sans-first:54765.03 17:28:55 INFO - PID 3561 | Values: 756.8 835.3 860.3 860.8 1124.3 29259.0 30159.0 40299.8 40957.2 40995.8 41818.0 42005.0 42264.6 42266.6 42315.8 7461.6 7595.8 7609.0 7613.5 7615.0 7481.3 7532.4 7583.2 7589.2 7589.6 7067.8 7552.4 7563.5 7575.1 7609.5 7505.8 7562.9 7570.9 7571.7 7572.8 7404.5 7484.6 7485.3 7491.6 7491.9 37215.4 37323.0 38039.6 38041.0 38184.6 3379.7 3420.6 3421.0 3423.8 3424.5 30979.2 31191.8 31217.7 31223.7 31322.5 4727.3 4918.2 4939.9 4966.7 4997.7 190525.0 191210.0 191380.0 191465.0 192162.0 46753.5 46756.5 47045.8 47088.0 47371.5 49584.8 49601.3 49683.8 49729.0 49829.5 15252.9 15305.5 15358.8 15413.5 15415.9 12545.3 12570.7 12576.3 12577.9 12594.4 14274.8 14425.2 14571.6 14647.3 14690.1 5876.4 5926.1 5985.7 6151.8 6154.8 1001.7 1002.8 1016.3 1021.7 1025.5 24030.0 24376.4 24472.4 24497.0 24664.8 1112.6 1116.8 1123.1 1127.1 1127.6 12805.1 13078.3 13081.5 13101.9 13185.5 237874.0 238744.0 239003.0 239494.0 239676.0 53003.0 53091.5 53175.0 53371.2 53492.0 1414.7 1452.9 1463.3 1466.1 1471.4 23069.4 23080.4 23226.2 23245.7 23396.6 36356.5 36399.5 36485.6 36527.5 36545.3 198644.0 199231.0 199822.0 200074.0 200220.0 33986.3 35345.0 35447.6 35576.3 35701.5 38473.4 39328.0 43804.0 43959.6 44204.8 26126.5 31716.5 32079.7 32417.7 32759.7 31425.2 31458.0 31634.8 32230.4 32415.2 35236.5 35256.3 35367.0 35403.3 35470.1 30807.0 31070.0 31135.0 31338.7 31582.0 22423.0 24825.6 25409.6 25495.4 25856.6 17:28:55 INFO - PID 3561 | 17:28:55 INFO - PID 3561 | [#2] jquery.html Cycles:180 Average:35776.53 Median:13076.87 stddev:87501.35 (669.1%) stddev-sans-first:87706.88 17:28:55 INFO - PID 3561 | Values: 565.2 565.8 566.1 573.4 591.3 21969.0 22678.9 22773.3 22930.1 22943.9 25670.0 26322.1 26404.3 26441.3 26452.7 6381.4 6490.8 6515.9 6533.1 6534.6 6359.9 6431.4 6623.2 6643.2 6658.6 6606.8 6610.6 6613.2 6625.3 6626.9 6617.8 6632.1 6632.5 6639.3 6646.4 6122.3 6125.1 6129.2 6131.2 6135.9 28058.0 28726.1 29267.2 29355.9 29435.9 1848.6 1860.9 1870.0 1870.0 1872.9 18880.5 19221.4 19379.6 19457.0 19511.7 2378.4 2390.4 2466.3 2481.9 2485.1 127536.0 130482.3 130829.0 130968.3 131615.0 13885.5 14703.6 15026.0 15157.8 15405.5 22805.7 23491.3 23628.0 23654.4 23709.6 9119.0 9134.8 9198.9 9237.5 9309.7 8217.6 8281.5 8301.9 8336.2 8369.7 9048.6 9360.8 9366.2 9450.0 9661.8 2619.2 2659.1 2748.5 2760.8 2811.8 935.8 938.4 943.4 965.5 967.7 19107.6 20112.3 23874.8 24249.0 24968.8 1008.6 1013.5 1029.4 1034.6 1043.9 11281.6 11673.0 11680.7 11759.1 11766.3 506380.0 519144.0 527182.0 527741.0 532490.0 119674.0 122079.4 122463.4 123911.0 124975.0 1220.1 1232.1 1266.5 1276.8 1278.1 21347.0 21375.4 21383.9 21462.5 21769.2 49091.8 51906.0 54878.5 56427.7 58520.4 69398.0 69898.0 70071.0 70332.3 70907.0 14801.0 14978.9 15048.6 15128.8 15146.9 23386.4 23474.2 23562.0 23775.3 23928.8 17550.9 18634.8 18982.8 19088.2 19106.9 16977.0 16979.4 17225.9 17620.6 17636.0 19034.7 19049.4 19192.0 19388.7 19424.0 14636.3 15173.4 15178.8 15183.0 15187.5 17907.4 18080.1 18205.4 18209.5 18232.4 17:28:55 INFO - PID 3561 | 17:28:55 INFO - PID 3561 | [#3] mootools.html Cycles:180 Average:8758.60 Median:2643.42 stddev:6025.53 (227.9%) stddev-sans-first:6016.41 17:28:55 INFO - PID 3561 | Values: 1305.5 1380.5 1390.0 1392.9 1396.2 13970.4 14244.4 14572.4 14576.9 14579.6 13919.2 14941.8 14951.9 14954.6 14964.3 2667.3 2713.6 2720.6 2721.4 2726.2 2621.9 2796.4 2804.6 2807.4 2814.6 2636.2 2654.6 2678.4 2697.8 2700.4 2650.7 2684.8 2687.2 2695.9 2710.6 2580.5 2607.8 2612.1 2618.3 2622.9 13957.2 14206.0 14213.7 14215.4 14220.0 7259.4 7482.3 7496.2 7523.0 7523.6 5077.6 5222.6 5261.7 5321.6 5335.7 1810.4 1845.8 1871.6 1895.0 1910.8 22741.2 23819.6 24031.4 24071.0 24124.3 6375.6 6577.7 6724.6 6734.9 6746.6 16192.8 16193.1 16213.2 16301.7 16317.5 7287.7 7492.7 7508.2 7527.7 7528.1 6561.6 6702.0 6704.6 6832.3 6960.9 4960.6 5100.1 5112.6 5145.2 5160.8 1466.9 1477.6 1483.3 1499.2 1502.6 848.8 860.1 869.0 877.9 882.3 8135.4 8183.2 8354.2 8376.5 8478.6 721.2 724.4 726.7 730.4 733.2 4670.3 4686.1 4710.9 4729.5 4733.4 19484.2 19924.6 20036.5 20068.0 20107.8 17325.2 18698.8 18708.3 18728.3 18771.4 9229.0 10118.6 10211.5 10262.7 10263.8 6708.9 8487.0 8488.2 8505.1 8603.0 15862.4 16299.3 16684.5 16764.5 16935.2 16863.0 17350.9 17811.0 18265.6 18386.4 10320.3 10360.4 10438.0 10442.3 10443.7 12886.8 13270.7 13326.9 13339.7 13344.4 10851.3 10940.7 11028.6 11200.7 11359.6 10733.6 10805.2 10860.1 10965.3 11163.8 9631.9 9672.6 9716.7 9798.6 9915.3 7116.6 7312.3 7321.7 7338.3 7399.2 7054.5 7099.9 7137.6 7163.8 7198.7 17:28:55 INFO - PID 3561 | 17:28:55 INFO - PID 3561 | [#4] prototype.html Cycles:180 Average:5573.55 Median:6101.86 stddev:1582.38 (25.9%) stddev-sans-first:1540.26 17:28:55 INFO - PID 3561 | Values: 496.8 500.1 516.5 520.7 521.1 6215.2 6280.4 6284.7 6286.0 6383.2 5992.6 6132.2 6199.0 6217.9 6222.1 4919.9 4942.5 4994.9 5004.6 5019.7 4943.7 4979.4 5000.9 5015.5 5073.4 4729.8 4935.2 4961.7 4964.6 4969.1 4989.4 5013.5 5040.1 5041.3 5053.4 4406.4 4443.9 4450.6 4461.3 4507.7 5959.8 5999.2 6054.2 6070.2 6071.5 5723.1 5748.3 5751.3 5764.7 5765.4 6622.2 6634.8 6640.2 6659.9 6683.9 4829.3 4853.1 4853.2 4872.7 4878.6 6837.3 6944.2 6975.7 6984.2 7029.3 4988.0 4996.3 5035.3 5070.2 5124.9 5089.7 5090.2 5096.4 5126.3 5153.6 5745.7 5747.3 5816.7 5829.8 5846.3 6687.6 6689.8 6742.3 6761.2 6777.4 1679.8 1718.8 1736.0 1763.9 1764.5 1196.6 1221.0 1230.7 1240.2 1244.2 5695.5 5723.5 5808.4 5810.7 5823.5 6661.0 6698.5 6728.8 6734.9 6793.6 5638.6 5780.7 5815.7 5845.3 5873.0 3604.8 3625.0 3629.3 3646.3 3672.2 6593.1 6597.5 6675.4 6686.0 6726.5 6994.7 7026.5 7044.2 7063.4 7115.5 6645.0 6671.2 6711.8 6712.5 6767.2 6743.7 6913.8 6922.6 6922.7 6923.2 6719.3 6766.4 6832.2 6836.9 6858.2 6516.9 6580.9 6604.8 6681.5 6724.3 5043.1 5065.0 5072.5 5098.4 5104.3 6822.8 6866.3 6884.9 6920.8 6936.4 6554.9 6657.3 6657.6 6690.5 6730.7 6612.8 6618.8 6638.8 6647.2 6679.4 6357.2 6504.1 6515.7 6544.9 6560.3 6468.6 6549.1 6613.1 6636.2 6648.6 6404.6 6441.5 6473.9 6490.6 6492.6 17:28:55 INFO - PID 3561 | 17:28:55 INFO - PID 3561 | [#5] yui.html Cycles:180 Average:23376.84 Median:18536.40 stddev:32537.30 (175.5%) stddev-sans-first:32584.78 17:28:55 INFO - PID 3561 | Values: 892.5 914.2 914.9 916.5 932.8 18632.7 19536.2 19556.1 19633.6 21214.7 21154.3 21724.7 21778.2 21945.7 21972.6 1022.5 1052.2 1060.4 1066.7 1068.3 955.9 1065.8 1067.6 1068.2 1068.4 1066.2 1067.0 1073.4 1075.2 1110.3 1059.9 1062.6 1063.9 1066.5 1105.7 780.6 781.2 782.4 789.2 819.6 17551.6 17673.9 17709.6 17713.6 18931.5 21444.0 21634.2 21989.3 22159.2 22177.4 16958.0 17381.4 17548.9 17583.5 17598.2 3556.4 3559.4 3562.6 3563.6 3569.7 123289.0 126105.0 126958.4 128504.0 129732.0 15273.5 15283.2 15383.3 15710.1 15806.9 31347.7 31813.2 31873.8 31879.4 32291.8 7693.5 7983.2 8080.3 8627.3 8939.5 4433.9 4496.1 4502.9 4503.4 4522.4 5308.9 5438.2 5447.6 5459.3 5508.2 1051.2 1071.4 1085.4 1087.4 1087.8 601.5 618.9 623.0 625.1 639.4 10993.8 11167.8 11550.9 12077.0 12238.4 609.2 626.7 627.0 630.0 636.0 5446.3 5480.4 5481.4 5505.2 5608.7 112884.4 113022.4 113368.0 116035.4 117959.4 99557.5 99771.0 102458.0 104255.0 104626.5 87392.6 87796.1 90480.5 91676.0 92165.5 9842.3 9914.5 10123.6 10160.4 10310.6 40160.2 41232.6 41248.2 41340.0 41870.4 50093.5 50892.2 50925.5 51694.7 52594.2 15468.0 15495.0 15711.4 15751.0 15778.5 16632.5 16840.3 20054.6 20294.5 20302.0 17352.4 17443.1 17621.6 17671.4 17752.5 17923.2 17993.9 18103.5 18410.8 18440.1 16460.8 16557.8 16574.3 16663.3 16694.8 11837.5 12068.2 12393.4 12421.4 12844.4 12860.2 13217.7 13277.4 13286.9 13629.8 17:28:55 INFO - PID 3561 | -------- Summary: end -------- 17:28:55 INFO - PID 3561 | 17:28:56 INFO - TEST-INFO | 3561: exit 0 17:28:56 INFO - TEST-OK | dromaeo_css | took 1116342ms 17:28:56 INFO - TEST-START | kraken 17:28:56 INFO - Initialising browser for kraken test... 17:28:56 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:51686/getInfo.html -profile /tmp/tmpCfYul6/profile 17:28:56 INFO - TEST-INFO | started process 3950 (/builds/slave/test/build/application/firefox/firefox http://localhost:51686/getInfo.html) 17:29:02 INFO - TEST-INFO | 3950: exit 0 17:29:03 INFO - Browser initialized. 17:29:03 INFO - Running cycle 1/1 for kraken test... 17:29:03 INFO - TEST-INFO | started process 4184 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpCfYul6/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 17:29:04 INFO - PID 4184 | 17:29:04 INFO - PID 4184 | (/builds/slave/test/build/application/firefox/firefox:4238): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 17:29:04 INFO - PID 4184 | 17:29:14 INFO - PID 4184 | RSS: Main: 167882752 17:29:14 INFO - PID 4184 | 17:29:17 INFO - PID 4184 | Cycle 1(1): loaded http://localhost:51686/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51686/tests/kraken/driver.html?testName=audio-beat-detection) 17:29:18 INFO - PID 4184 | RSS: Main: 173760512 17:29:18 INFO - PID 4184 | 17:29:20 INFO - PID 4184 | Cycle 1(1): loaded http://localhost:51686/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51686/tests/kraken/driver.html?testName=audio-dft) 17:29:21 INFO - PID 4184 | RSS: Main: 174694400 17:29:21 INFO - PID 4184 | 17:29:24 INFO - PID 4184 | Cycle 1(1): loaded http://localhost:51686/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51686/tests/kraken/driver.html?testName=audio-fft) 17:29:25 INFO - PID 4184 | RSS: Main: 169959424 17:29:25 INFO - PID 4184 | 17:29:27 INFO - PID 4184 | Cycle 1(1): loaded http://localhost:51686/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51686/tests/kraken/driver.html?testName=audio-oscillator) 17:29:27 INFO - PID 4184 | RSS: Main: 170160128 17:29:27 INFO - PID 4184 | 17:29:30 INFO - PID 4184 | Cycle 1(1): loaded http://localhost:51686/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51686/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:29:30 INFO - PID 4184 | RSS: Main: 170192896 17:29:30 INFO - PID 4184 | 17:29:33 INFO - PID 4184 | Cycle 1(1): loaded http://localhost:51686/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51686/tests/kraken/driver.html?testName=imaging-darkroom) 17:29:34 INFO - PID 4184 | RSS: Main: 173289472 17:29:34 INFO - PID 4184 | 17:29:38 INFO - PID 4184 | Cycle 1(1): loaded http://localhost:51686/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51686/tests/kraken/driver.html?testName=imaging-desaturate) 17:29:38 INFO - PID 4184 | RSS: Main: 172724224 17:29:38 INFO - PID 4184 | 17:29:41 INFO - PID 4184 | Cycle 1(1): loaded http://localhost:51686/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51686/tests/kraken/driver.html?testName=json-parse-financial) 17:29:42 INFO - PID 4184 | RSS: Main: 173408256 17:29:42 INFO - PID 4184 | 17:29:43 INFO - PID 4184 | Cycle 1(1): loaded http://localhost:51686/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51686/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:29:44 INFO - PID 4184 | RSS: Main: 173297664 17:29:44 INFO - PID 4184 | 17:29:46 INFO - PID 4184 | Cycle 1(1): loaded http://localhost:51686/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51686/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:29:47 INFO - PID 4184 | RSS: Main: 173277184 17:29:47 INFO - PID 4184 | 17:29:49 INFO - PID 4184 | Cycle 1(1): loaded http://localhost:51686/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51686/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:29:49 INFO - PID 4184 | RSS: Main: 173752320 17:29:49 INFO - PID 4184 | 17:29:52 INFO - PID 4184 | Cycle 1(1): loaded http://localhost:51686/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51686/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:29:53 INFO - PID 4184 | RSS: Main: 174292992 17:29:53 INFO - PID 4184 | 17:29:56 INFO - PID 4184 | Cycle 1(1): loaded http://localhost:51686/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51686/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:29:57 INFO - PID 4184 | RSS: Main: 174321664 17:29:57 INFO - PID 4184 | 17:29:58 INFO - PID 4184 | Cycle 1(1): loaded http://localhost:51686/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51686/tests/kraken/driver.html?testName=ai-astar) 17:29:59 INFO - PID 4184 | RSS: Main: 175611904 17:29:59 INFO - PID 4184 | 17:29:59 INFO - PID 4184 | __start_tp_report 17:29:59 INFO - PID 4184 | _x_x_mozilla_page_load 17:29:59 INFO - PID 4184 | _x_x_mozilla_page_load_details 17:29:59 INFO - PID 4184 | |i|pagename|runs| 17:29:59 INFO - PID 4184 | |0;ai-astar;103;107;102;106;105;107;112;99;108;118 17:29:59 INFO - PID 4184 | |1;audio-beat-detection;146;116;161;185;122;146;146;120;184;117 17:29:59 INFO - PID 4184 | |2;audio-dft;163;162;163;167;161;163;165;169;162;164 17:29:59 INFO - PID 4184 | |3;audio-fft;75;76;79;171;84;76;75;73;169;77 17:29:59 INFO - PID 4184 | |4;audio-oscillator;79;81;136;78;78;79;121;79;79;141 17:29:59 INFO - PID 4184 | |5;imaging-gaussian-blur;113;114;113;117;116;116;122;115;116;117 17:29:59 INFO - PID 4184 | |6;imaging-darkroom;149;150;150;149;148;152;152;153;149;151 17:29:59 INFO - PID 4184 | |7;imaging-desaturate;93;95;94;94;95;97;95;93;93;95 17:29:59 INFO - PID 4184 | |8;json-parse-financial;55;62;54;57;55;58;61;58;55;57 17:29:59 INFO - PID 4184 | |9;json-stringify-tinderbox;56;55;54;58;58;54;56;55;59;61 17:29:59 INFO - PID 4184 | |10;stanford-crypto-aes;77;76;75;76;76;78;77;76;78;87 17:29:59 INFO - PID 4184 | |11;stanford-crypto-ccm;124;131;136;122;124;127;125;128;131;177 17:29:59 INFO - PID 4184 | |12;stanford-crypto-pbkdf2;185;173;175;191;174;174;185;176;180;182 17:29:59 INFO - PID 4184 | |13;stanford-crypto-sha256-iterative;68;69;70;69;68;74;67;73;68;66 17:29:59 INFO - PID 4184 | __end_tp_report 17:29:59 INFO - PID 4184 | __start_cc_report 17:29:59 INFO - PID 4184 | _x_x_mozilla_cycle_collect,1004 17:29:59 INFO - PID 4184 | __end_cc_report 17:29:59 INFO - PID 4184 | __startTimestamp1502584199190__endTimestamp 17:29:59 INFO - PID 4184 | 17:29:59 INFO - PID 4184 | ------- Summary: start ------- 17:29:59 INFO - PID 4184 | Number of tests: 14 17:29:59 INFO - PID 4184 | 17:29:59 INFO - PID 4184 | [#0] ai-astar Cycles:10 Average:106.70 Median:107.50 stddev:5.33 (5.0%) stddev-sans-first:5.49 17:29:59 INFO - PID 4184 | Values: 103.0 107.0 102.0 106.0 105.0 107.0 112.0 99.0 108.0 118.0 17:29:59 INFO - PID 4184 | 17:29:59 INFO - PID 4184 | [#1] audio-beat-detection Cycles:10 Average:144.30 Median:146.00 stddev:26.15 (17.9%) stddev-sans-first:27.73 17:29:59 INFO - PID 4184 | Values: 146.0 116.0 161.0 185.0 122.0 146.0 146.0 120.0 184.0 117.0 17:29:59 INFO - PID 4184 | 17:29:59 INFO - PID 4184 | [#2] audio-dft Cycles:10 Average:163.90 Median:163.50 stddev:2.47 (1.5%) stddev-sans-first:2.60 17:29:59 INFO - PID 4184 | Values: 163.0 162.0 163.0 167.0 161.0 163.0 165.0 169.0 162.0 164.0 17:29:59 INFO - PID 4184 | 17:29:59 INFO - PID 4184 | [#3] audio-fft Cycles:10 Average:95.50 Median:76.00 stddev:39.38 (51.8%) stddev-sans-first:41.06 17:29:59 INFO - PID 4184 | Values: 75.0 76.0 79.0 171.0 84.0 76.0 75.0 73.0 169.0 77.0 17:29:59 INFO - PID 4184 | 17:29:59 INFO - PID 4184 | [#4] audio-oscillator Cycles:10 Average:95.10 Median:79.00 stddev:26.40 (33.4%) stddev-sans-first:27.35 17:29:59 INFO - PID 4184 | Values: 79.0 81.0 136.0 78.0 78.0 79.0 121.0 79.0 79.0 141.0 17:29:59 INFO - PID 4184 | 17:29:59 INFO - PID 4184 | [#5] imaging-gaussian-blur Cycles:10 Average:115.90 Median:116.00 stddev:2.60 (2.2%) stddev-sans-first:2.54 17:29:59 INFO - PID 4184 | Values: 113.0 114.0 113.0 117.0 116.0 116.0 122.0 115.0 116.0 117.0 17:29:59 INFO - PID 4184 | 17:29:59 INFO - PID 4184 | [#6] imaging-darkroom Cycles:10 Average:150.30 Median:150.50 stddev:1.64 (1.1%) stddev-sans-first:1.67 17:29:59 INFO - PID 4184 | Values: 149.0 150.0 150.0 149.0 148.0 152.0 152.0 153.0 149.0 151.0 17:29:59 INFO - PID 4184 | 17:29:59 INFO - PID 4184 | [#7] imaging-desaturate Cycles:10 Average:94.40 Median:95.00 stddev:1.26 (1.3%) stddev-sans-first:1.24 17:29:59 INFO - PID 4184 | Values: 93.0 95.0 94.0 94.0 95.0 97.0 95.0 93.0 93.0 95.0 17:29:59 INFO - PID 4184 | 17:29:59 INFO - PID 4184 | [#8] json-parse-financial Cycles:10 Average:57.20 Median:57.50 stddev:2.66 (4.6%) stddev-sans-first:2.70 17:29:59 INFO - PID 4184 | Values: 55.0 62.0 54.0 57.0 55.0 58.0 61.0 58.0 55.0 57.0 17:29:59 INFO - PID 4184 | 17:29:59 INFO - PID 4184 | [#9] json-stringify-tinderbox Cycles:10 Average:56.60 Median:57.00 stddev:2.32 (4.1%) stddev-sans-first:2.45 17:29:59 INFO - PID 4184 | Values: 56.0 55.0 54.0 58.0 58.0 54.0 56.0 55.0 59.0 61.0 17:29:59 INFO - PID 4184 | 17:29:59 INFO - PID 4184 | [#10] stanford-crypto-aes Cycles:10 Average:77.60 Median:77.00 stddev:3.44 (4.5%) stddev-sans-first:3.64 17:29:59 INFO - PID 4184 | Values: 77.0 76.0 75.0 76.0 76.0 78.0 77.0 76.0 78.0 87.0 17:29:59 INFO - PID 4184 | 17:29:59 INFO - PID 4184 | [#11] stanford-crypto-ccm Cycles:10 Average:132.50 Median:129.50 stddev:16.19 (12.5%) stddev-sans-first:16.88 17:29:59 INFO - PID 4184 | Values: 124.0 131.0 136.0 122.0 124.0 127.0 125.0 128.0 131.0 177.0 17:29:59 INFO - PID 4184 | 17:29:59 INFO - PID 4184 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:179.50 Median:181.00 stddev:6.10 (3.4%) stddev-sans-first:6.13 17:29:59 INFO - PID 4184 | Values: 185.0 173.0 175.0 191.0 174.0 174.0 185.0 176.0 180.0 182.0 17:29:59 INFO - PID 4184 | 17:29:59 INFO - PID 4184 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.20 Median:69.00 stddev:2.53 (3.7%) stddev-sans-first:2.65 17:29:59 INFO - PID 4184 | Values: 68.0 69.0 70.0 69.0 68.0 74.0 67.0 73.0 68.0 66.0 17:29:59 INFO - PID 4184 | -------- Summary: end -------- 17:29:59 INFO - PID 4184 | 17:29:59 INFO - PID 4184 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 17:29:59 INFO - TEST-INFO | 4184: exit 0 17:29:59 INFO - TEST-OK | kraken | took 63402ms 17:29:59 INFO - SUITE-END | took 1179s 17:30:00 INFO - Completed test suite (00:19:40) 17:30:00 INFO - javascript benchmark 17:30:00 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8447.74563000642, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3100.968990310096, 3137.9686203137962, 3154.6056742907135, 3185.0444866540033, 3210.117217765114, 6108.389161083889, 6229.439350458458, 6254.968725156374, 6264.655443950581, 6279.18370611821, 6017.849553761158, 6155.569110162291, 6162.784302549411, 6171.290301615304, 6198.814035578928, 5661.688607126606, 5680.829575112753, 5688.374278829326, 5692.943070569303, 5713.028785106521, 5702.885942281152, 5723.885522289552, 5745.5690823188215, 5751.194832723422, 5814.069748840187, 5725.513331366838, 5759.5968282220265, 5776.277965254343, 5792.971035144839, 5822.330432000332, 5516.282883225155, 5710.714464276791, 5761.769529218826, 5784.537237021028, 5808.448197421239, 5627.296587926509, 5670.8015219467125, 5697.173909783059, 5698.601097923147, 5730.398308177623, 6075.574709770318, 6166.229221347332, 6181.690915454209, 6224.253089629274, 6241.3446588108045, 6379.681015949184, 6606.867862642768, 6628.403443690043, 6649.767258145942, 6761.594304341756, 6636.303188165221, 6703.430208432289, 6703.631300278483, 6704.296048914843, 6706.966465167643, 6166.660833654102, 6211.47202487789, 6221.968890155611, 6225.688715564203, 6229.719662615148, 6892.310768923067, 6900.2409734929115, 6911.343422374866, 6912.585244885323, 6913.10129683138, 6529.053287273414, 6554.606723596599, 6618.040384144272, 6619.86760264792, 6633.502487313471, 6595.307492713292, 6625.536212465081, 6712.53012289135, 6738.258791532928, 6755.392014718699, 6460.709268082947, 6554.082428460019, 6577.3422657733845, 6602.3067577904585, 6715.764948226789, 6708.496862735315, 6710.060591517192, 6722.428593569503, 6728.394444500019, 6793.762218322335, 5383.703896285663, 5466.781328746885, 5469.671819690831, 5474.315710536183, 5484.369297530755, 4553.476350219701, 4622.976885115553, 4639.118567472169, 4659.487456379796, 4680.110778951988, 5646.717664116778, 5699.059655156852, 5722.484976352148, 5723.942760572341, 5725.570582206351, 6764.898526522106, 6767.255601883789, 6770.627615481098, 6772.018057381751, 6774.559653622598, 5777.480026797609, 5811.88376232473, 5818.709064546756, 5832.708364581754, 5842.970785146047, 5843.649381037151, 5879.412058794172, 5936.436038576415, 5936.851578710481, 5952.761889524457, 6355.713992870332, 6441.838954026187, 6475.514336424692, 6484.708188131545, 6586.110875031905, 6736.326367363225, 6738.9217725163735, 6754.59472431636, 6757.45940324763, 6769.796906092825, 6419.165508483868, 6470.805875823733, 6512.251091124392, 6547.148870646785, 6556.147700798866, 6558.672066396756, 6564.4748420127235, 6605.438537724347, 6661.533692641468, 6693.832654183684, 6583.3087525811015, 6689.130413046273, 6699.43054840344, 6700.865982680221, 6775.525713200029, 6423.454006409321, 6436.935630643634, 6445.838854028687, 6452.35476452351, 6455.386738259858, 6397.3282805306635, 6451.3548645135115, 6463.612183269019, 6473.77341793035, 6481.254655714653, 6144.477719393902, 6394.360563943755, 6410.935890641033, 6469.8706025880165, 6488.740450381932, 6297.559170857996, 6505.479561634963, 6526.118973938457, 6544.3782840630065, 6638.867222655616, 6294.339094395023, 6297.96851015742, 6398.872022559616, 6407.679616019273, 6415.582987105823], "value": 6090.821521779977, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [756.772968109567, 835.2816577743142, 860.298856432008, 860.7632900952237, 1124.3197865291493, 29259.0, 30159.0, 40299.798501007455, 40957.18085638285, 40995.79502102485, 41818.0, 42005.0, 42264.57735422645, 42266.57733422673, 42315.78842105785, 7461.5523068615785, 7595.848083038336, 7609.010828592275, 7613.505122167041, 7615.048118985127, 7481.289277518627, 7532.359749421305, 7583.203763604824, 7589.165191828894, 7589.582572958485, 7067.823304417405, 7552.358049565793, 7563.470557061008, 7575.090989081318, 7609.543427394374, 7505.849883002336, 7562.886556701654, 7570.886436703398, 7571.659275332596, 7572.7728168155045, 7404.518706284074, 7484.550926944401, 7485.288897554724, 7491.6254187290415, 7491.887621685679, 37215.441768373494, 37323.0, 38039.61960380388, 38041.0, 38184.61815381838, 3379.678930501598, 3420.555327807369, 3420.9828950855335, 3423.8116903570294, 3424.4692072728767, 30979.22551936197, 31191.844040779877, 31217.687823121705, 31223.687763122307, 31322.530162047588, 4727.267273572602, 4918.188498897677, 4939.851804445872, 4966.726830024347, 4997.6501644884875, 190525.0, 191210.0, 191380.0, 191465.0, 192162.0, 46753.5324646756, 46756.53243467522, 47045.76477117593, 47088.0, 47371.52628473671, 49584.75207623939, 49601.25598116031, 49683.751581242585, 49729.0, 49829.50170498321, 15252.923735381251, 15305.46430874914, 15358.769618455732, 15413.460528881435, 15415.922920385327, 12545.310007949467, 12570.748585028248, 12576.30830304342, 12577.874221257853, 12594.43325050375, 14274.785878211627, 14425.20661363635, 14571.562853114212, 14647.340869660678, 14690.11859288446, 5876.382979787147, 5926.081457374114, 5985.700714964234, 6151.84620384494, 6154.78458253959, 1001.7445551384351, 1002.8044531316469, 1016.3444578246989, 1021.6934919524261, 1025.5231317437429, 24030.0, 24376.39059023503, 24472.388190295384, 24497.0, 24664.753352466603, 1112.5660992213043, 1116.7989761842946, 1123.0622430270816, 1127.1208457403238, 1127.5940661361903, 12805.103642745105, 13078.346082695827, 13081.542146024844, 13101.868981310256, 13185.53850615224, 237874.0, 238744.0, 239003.0, 239494.0, 239676.0, 53003.0, 53091.46908530942, 53175.0, 53371.19943200855, 53492.0, 1414.6816966182528, 1452.9200893950933, 1463.27567853913, 1466.0763718857265, 1471.448206922404, 23069.423264417852, 23080.4229894254, 23226.187083451998, 23245.65131523062, 23396.6490502646, 36356.454653179695, 36399.45400819043, 36485.63514364822, 36527.45208821923, 36545.26909461849, 198644.0, 199231.0, 199822.0, 200074.0, 200220.0, 33986.3202735939, 35345.0, 35447.64552354546, 35576.28847423089, 35701.46447803233, 38473.42289865598, 39328.0, 43804.0, 43959.56040439683, 44204.77897610491, 26126.477470450864, 31716.524252136696, 32079.67920320767, 32417.67582324241, 32759.67240327566, 31425.21436964094, 31458.0, 31634.841825790725, 32230.35539289248, 32415.189620259684, 35236.47145292874, 35256.29487410289, 35367.0, 35403.291934160654, 35470.11324716903, 30807.0, 31070.0, 31135.0, 31338.686613133577, 31582.0, 22423.0, 24825.62761558614, 25409.61885571755, 25495.391013976896, 25856.612150818128], "value": 17052.655602472834, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [565.2284631478034, 565.7850016993542, 566.0970751651117, 573.3893403525245, 591.2874985642302, 21969.0, 22678.88660556699, 22773.31680049597, 22930.08279668811, 22943.885280573573, 25670.0, 26322.07872724455, 26404.33989150272, 26441.338966525847, 26452.73547264527, 6381.361863813617, 6490.837729056764, 6515.869682606345, 6533.05270735743, 6534.640594767286, 6359.904601430982, 6431.42117209451, 6623.2052153741615, 6643.169603799525, 6658.633775142365, 6606.8017959461295, 6610.636414997173, 6613.173353330833, 6625.271220165778, 6626.900596491057, 6617.834554136137, 6632.137822083122, 6632.469402447792, 6639.302873198341, 6646.435053020499, 6122.295935967376, 6125.142480052797, 6129.1725617041575, 6131.172291740602, 6135.877282454326, 28058.0, 28726.138215853556, 29267.219614136837, 29355.85322073376, 29435.852820735974, 1848.5655870870335, 1860.8883466991888, 1869.953251168718, 1869.962600747991, 1872.925082996692, 18880.5279868003, 19221.423357299303, 19379.612407751905, 19457.02714864251, 19511.707324390147, 2378.444363500345, 2390.3665528634924, 2466.2847774145475, 2481.9131330403347, 2485.1302044284535, 127536.0, 130482.34758826144, 130829.0, 130968.34515827455, 131615.0, 13885.514007009706, 14703.55889323322, 15026.0, 15157.848421515864, 15405.460808871636, 22805.657915131287, 23491.295261142193, 23628.0, 23654.4086397838, 23709.644355334683, 9119.0, 9134.817303654023, 9198.862017069749, 9237.538123093818, 9309.674161404319, 8217.58912054395, 8281.503109813431, 8301.875471867927, 8336.166383361615, 8369.665213391518, 9048.592813323416, 9360.812783744423, 9366.203872670763, 9449.952750236205, 9661.758456038655, 2619.2142357292737, 2659.1091984185514, 2748.532749432601, 2760.83434993897, 2811.7750579953554, 935.8409070458039, 938.3525367496405, 943.42451104826, 965.4545181972163, 967.685502211784, 19107.61784764297, 20112.296069637494, 23874.761252387252, 24249.0, 24968.750312497006, 1008.566316483919, 1013.4628646817199, 1029.3772267777886, 1034.5965073621294, 1043.8642976413169, 11281.60514382009, 11673.0, 11680.70798230034, 11759.118066145073, 11766.294022358687, 506380.0, 519144.0, 527182.0, 527741.0, 532490.0, 119674.0, 122079.38960305142, 122463.38768306101, 123911.0, 124975.0, 1220.0849362977767, 1232.0759430427179, 1266.4617537546505, 1276.7957126859844, 1278.0861683895873, 21347.0, 21375.358739237847, 21383.893080534497, 21462.463438414165, 21769.23807666724, 49091.75454122706, 51906.0, 54878.45121548893, 56427.71786141043, 58520.4147958532, 69398.0, 69898.0, 70071.0, 70332.29667703257, 70907.0, 14801.0, 14978.925105374403, 15048.623784405478, 15128.773068403763, 15146.924265379044, 23386.415339616644, 23474.17840375647, 23562.0, 23775.286741397787, 23928.76071239335, 17550.912245438692, 18634.81365186385, 18982.810171898658, 19088.236470541022, 19106.90446547814, 16977.0, 16979.40572080021, 17225.913870430566, 17620.64758704793, 17636.0, 19034.714479282546, 19049.428517144508, 19192.0, 19388.709169362188, 19424.0, 14636.341364638829, 15173.393064277747, 15178.848211518185, 15183.0, 15187.544373668808, 17907.37324193699, 18080.09599520045, 18205.362812301504, 18209.45371638853, 18232.36186733458], "value": 12073.035469485721, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1305.5365345302419, 1380.5168191133105, 1389.9513517026903, 1392.8607139286073, 1396.2390497179038, 13970.441182352706, 14244.430222791101, 14572.417103315855, 14576.927115364435, 14579.562613121598, 13919.164850109, 14941.775873361908, 14951.925240373783, 14954.626134346647, 14964.32660530276, 2667.293167310664, 2713.6200931869553, 2720.6327145835257, 2721.3876877702555, 2726.195772247182, 2621.89512419503, 2796.4407118576264, 2804.5652923796742, 2807.4244779820087, 2814.577813328007, 2636.156429942419, 2654.588538776493, 2678.370582913014, 2697.8111532192756, 2700.3654141276784, 2650.6819181698224, 2684.8254863433813, 2687.193841847448, 2695.932601684954, 2710.6340644013003, 2580.5484040293063, 2607.8044146688885, 2612.0857699805106, 2618.2930608735724, 2622.868856557184, 13957.162570245819, 14206.0, 14213.715725685532, 14215.431382744679, 14220.0, 7259.382952449048, 7482.326590606817, 7496.175420703718, 7523.0, 7523.586202758846, 5077.619178561623, 5222.63441559091, 5261.657992230493, 5321.574274058066, 5335.653182543122, 1810.4116162247321, 1845.769278840145, 1871.5788947486842, 1894.9526261843425, 1910.837578805803, 22741.204057857896, 23819.618038196215, 24031.399215019763, 24071.0, 24124.27627171188, 6375.649339286291, 6577.736890524421, 6724.562903411263, 6734.8989765153565, 6746.561473504206, 16192.75710864338, 16193.109378984032, 16213.189340532927, 16301.673966520602, 16317.510474685778, 7287.744928927462, 7492.662830172655, 7508.211637778064, 7527.736529221451, 7528.096628404626, 6561.606303621798, 6701.966490167518, 6704.631245281556, 6832.282610325944, 6960.860782784316, 4960.553550180429, 5100.0819852426175, 5112.642115051911, 5145.176771716508, 5160.767765450564, 1466.9193194374197, 1477.637978695205, 1483.339913738376, 1499.175453500592, 1502.6393665520416, 848.8344772769251, 860.0582362313257, 868.9608967596473, 877.8551538995994, 882.250087425683, 8135.372925414822, 8183.222593853575, 8354.248117669318, 8376.53929033909, 8478.618462169217, 721.1850608812009, 724.4240828541323, 726.7056841979007, 730.3974221267475, 733.1898252430987, 4670.299455081765, 4686.086213188463, 4710.882227944328, 4729.4797572267025, 4733.384659994179, 19484.220631174594, 19924.60150796976, 20036.499087522636, 20068.0, 20107.798922010887, 17325.2203650836, 18698.81301186998, 18708.25166993332, 18728.34450794243, 18771.436856894314, 9228.95385523068, 10118.645847395306, 10211.489425528842, 10262.743431414274, 10263.794724105475, 6708.8993665094085, 8487.0, 8488.193621605938, 8505.064442911398, 8602.956985215034, 15862.365505380118, 16299.266533006281, 16684.49946501607, 16764.497065088068, 16935.23791429364, 16863.0, 17350.913245434196, 17811.0, 18265.634687305912, 18386.356477523223, 10320.329178603366, 10360.430176340373, 10438.0, 10442.269041167348, 10443.738906527398, 12886.806697899352, 13270.668233294246, 13326.93336533311, 13339.733205335644, 13344.399502022627, 10851.348919064882, 10940.671779846618, 11028.558857645605, 11200.663980080612, 11359.602413915472, 10733.570657173937, 10805.189610779065, 10860.131189504978, 10965.287256328313, 11163.776724465302, 9631.855522167032, 9672.613095476103, 9716.659916902874, 9798.608055677692, 9915.256355773201, 7116.608586527791, 7312.268773122858, 7321.707131714672, 7338.339549440576, 7399.186089530255, 7054.470914681522, 7099.893501597377, 7137.571745695275, 7163.785086447415, 7198.71205151788], "value": 6342.061914690469, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [496.8329374505146, 500.11767474699917, 516.5118962580364, 520.6979951628056, 521.0932976620682, 6215.192025036745, 6280.434760871521, 6284.717187726552, 6286.0, 6383.212700443872, 5992.550558708103, 6132.167395377889, 6198.96900515498, 6217.9378206217925, 6222.12890195373, 4919.933627876283, 4942.456329803718, 4994.900101997949, 5004.599632029429, 5019.673721208128, 4943.703377797344, 4979.427365852937, 5000.899982000358, 5015.5486006259425, 5073.416557095944, 4729.810807567693, 4935.161022626145, 4961.727105009241, 4964.5531902128805, 4969.130402179622, 4989.401271847383, 5013.523715247064, 5040.092783299005, 5041.319421878036, 5053.393592768891, 4406.383106365111, 4443.888902777424, 4450.577195166454, 4461.330800379937, 4507.729536227804, 5959.791407300723, 5999.190109335227, 6054.212952316171, 6070.150178974947, 6071.544634152457, 5723.141528770718, 5748.338941021794, 5751.281089863767, 5764.654120752768, 5765.423457654243, 6622.1722284714415, 6634.810512530853, 6640.203175618908, 6659.93340066598, 6683.93316066838, 4829.323894654751, 4853.126437241295, 4853.150698627726, 4872.707637541759, 4878.585320247783, 6837.3162683731725, 6944.1666999959825, 6975.720971161199, 6984.196817365966, 7029.332213439715, 4987.950120498748, 4996.325496058058, 5035.320231768665, 5070.214116811826, 5124.871878203, 5089.745512724349, 5090.211017292326, 5096.439391666913, 5126.256692779526, 5153.561947234452, 5745.741441635137, 5747.310322761212, 5816.709164541756, 5829.8251052468495, 5846.269216347957, 6687.598744075371, 6689.799306020827, 6742.292059333798, 6761.222459417131, 6777.3900348967845, 1679.8068222154363, 1718.788254280729, 1735.9566010849578, 1763.8941663500232, 1764.5059383372677, 1196.6350263169643, 1220.9633710988685, 1230.7415442756942, 1240.1628900492312, 1244.1974926172568, 5695.493982647622, 5723.481668066038, 5808.3901190375245, 5810.738516766755, 5823.515003674121, 6660.966695166493, 6698.531102822853, 6728.764493242713, 6734.898976515258, 6793.558418702769, 5638.6052976292085, 5780.653160810365, 5815.662397648564, 5845.269341332333, 5872.973824030702, 3604.819759012039, 3625.0, 3629.3467175908063, 3646.2889736501656, 3672.155404257036, 6593.120960526227, 6597.538172327987, 6675.432588229958, 6685.966570167118, 6726.529142959946, 6994.650267486707, 7026.508144429943, 7044.22513523512, 7063.434925205971, 7115.53749006313, 6645.0, 6671.1994560652065, 6711.798646040626, 6712.530122891449, 6767.154105736783, 6743.696533655899, 6913.75801846933, 6922.58464492132, 6922.723091076301, 6923.238443771282, 6719.345858386008, 6766.4248538874745, 6832.248452670103, 6836.93163068363, 6858.211305699709, 6516.934830651633, 6580.868382632416, 6604.801855944137, 6681.465482761465, 6724.29394913527, 5043.092243396224, 5064.974010395724, 5072.492750724898, 5098.439171691037, 5104.336436263262, 6822.795316140524, 6866.34769696878, 6884.896726549206, 6920.757773477904, 6936.410405115622, 6554.934450655433, 6657.3342665733035, 6657.567258128206, 6690.498212633975, 6730.721162979018, 6612.8346791330605, 6618.768343107968, 6638.805015097329, 6647.235567909559, 6679.398854103154, 6357.185688586811, 6504.113806995918, 6515.706793194223, 6544.934550654623, 6560.3111673273615, 6468.6442245676935, 6549.083128361938, 6613.081292243319, 6636.203655561363, 6648.634325112165, 6404.583702059351, 6441.452476539546, 6473.902891456726, 6490.578112422678, 6492.642904640101], "value": 5104.914889447707, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [892.5180402582606, 914.2183433164644, 914.8627705844125, 916.4913473022469, 932.8320902237595, 18632.720509192357, 19536.21855125793, 19556.11997460114, 19633.607327853453, 21214.681779773317, 21154.25960091397, 21724.674129888066, 21778.237761678345, 21945.67081493775, 21972.560548789013, 1022.5347466902541, 1052.163529993657, 1060.4220699718633, 1066.7493139112303, 1068.2949253492695, 955.904409559042, 1065.7974984752911, 1067.6370034188374, 1068.2201992545447, 1068.4123731947398, 1066.1524088349781, 1067.0076828549484, 1073.420353009374, 1075.2097208551709, 1110.3282514078987, 1059.8887116852773, 1062.6493257225136, 1063.8670166229222, 1066.4774260612282, 1105.7346236903165, 780.582388422197, 781.2187812187813, 782.3858271257036, 789.2068471186421, 819.622973432216, 17551.561210969827, 17673.911630441766, 17709.557261068447, 17713.645727085513, 18931.526711832314, 21444.0, 21634.24280150187, 21989.340319790273, 22159.22442714497, 22177.44556386087, 16958.0, 17381.39165129227, 17548.91225543864, 17583.472495825146, 17598.208080636403, 3556.3776339140804, 3559.4126969050076, 3562.590302115238, 3563.6080031196548, 3569.678728914384, 123289.0, 126105.0, 126958.36520817429, 128504.0, 129732.0, 15273.465428710164, 15283.159426231665, 15383.307751151226, 15710.057396556243, 15806.92096539533, 31347.686523134933, 31813.20466988344, 31873.840630796698, 31879.362412752078, 32291.838540807614, 7693.4614576979075, 7983.2415920487465, 8080.313173380211, 8627.309815214769, 8939.508327042075, 4433.866983990485, 4496.145732310851, 4502.887427814331, 4503.41455610775, 4522.389477420572, 5308.893822123613, 5438.15708565173, 5447.645903016291, 5459.344878614592, 5508.173773933942, 1051.2168434516182, 1071.4267866691282, 1085.3650614390513, 1087.3801932898173, 1087.7933192693363, 601.5217901768104, 618.9473894718999, 622.9937700623026, 625.1310678157365, 639.4277121975898, 10993.780124397626, 11167.83248251277, 11550.94224528872, 12077.0, 12238.388080595934, 609.1745684597307, 626.7117126121943, 627.0, 630.0328994992665, 636.045931103345, 5446.291982042389, 5480.369757477862, 5481.424450432647, 5505.229267902497, 5608.69152196625, 112884.43557782158, 113022.43488782503, 113368.0, 116035.41982290204, 117959.41020294843, 99557.50221248847, 99771.0, 102458.0, 104255.0, 104626.47686761517, 87392.5630371844, 87796.12203877879, 90480.5475972616, 91676.0, 92165.53917230236, 9842.311038227255, 9914.454704991007, 10123.59505619767, 10160.441175735405, 10310.639127630497, 40160.1967960645, 41232.58767412407, 41248.1750364997, 41340.0, 41870.371944421466, 50093.49906500888, 50892.236616451526, 50925.49074509207, 51694.74152629213, 52594.21108683296, 15468.0, 15495.0, 15711.371545138067, 15751.0, 15778.526644200692, 16632.50102496927, 16840.326386944384, 20054.59890802205, 20294.492637684176, 20302.0, 17352.392666257125, 17443.12784360802, 17621.647567048843, 17671.38150164738, 17752.46742597724, 17923.193456294757, 17993.910030449762, 18103.547411314823, 18410.815891841445, 18440.077996099873, 16460.83539164593, 16557.834421656113, 16574.254158563133, 16663.3334666612, 16694.833051669328, 11837.526498940291, 12068.215565988183, 12393.380330983595, 12421.441035153259, 12844.422001010164, 12860.164089334163, 13217.669558261121, 13277.40251688657, 13286.86713132895, 13629.773320401211], "value": 8951.900969495407, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1538.7, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 107.0, 102.0, 106.0, 105.0, 107.0, 112.0, 99.0, 108.0, 118.0], "value": 106.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 116.0, 161.0, 185.0, 122.0, 146.0, 146.0, 120.0, 184.0, 117.0], "value": 144.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 162.0, 163.0, 167.0, 161.0, 163.0, 165.0, 169.0, 162.0, 164.0], "value": 163.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 79.0, 171.0, 84.0, 76.0, 75.0, 73.0, 169.0, 77.0], "value": 95.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 81.0, 136.0, 78.0, 78.0, 79.0, 121.0, 79.0, 79.0, 141.0], "value": 95.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 114.0, 113.0, 117.0, 116.0, 116.0, 122.0, 115.0, 116.0, 117.0], "value": 115.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 150.0, 150.0, 149.0, 148.0, 152.0, 152.0, 153.0, 149.0, 151.0], "value": 150.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 95.0, 94.0, 94.0, 95.0, 97.0, 95.0, 93.0, 93.0, 95.0], "value": 94.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 62.0, 54.0, 57.0, 55.0, 58.0, 61.0, 58.0, 55.0, 57.0], "value": 57.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 55.0, 54.0, 58.0, 58.0, 54.0, 56.0, 55.0, 59.0, 61.0], "value": 56.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 76.0, 75.0, 76.0, 76.0, 78.0, 77.0, 76.0, 78.0, 87.0], "value": 77.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [124.0, 131.0, 136.0, 122.0, 124.0, 127.0, 125.0, 128.0, 131.0, 177.0], "value": 132.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 173.0, 175.0, 191.0, 174.0, 174.0, 185.0, 176.0, 180.0, 182.0], "value": 179.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 69.0, 70.0, 69.0, 68.0, 74.0, 67.0, 73.0, 68.0, 66.0], "value": 69.2, "unit": "score"}]}]} 17:30:00 INFO - Return code: 0 17:30:00 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 17:30:00 INFO - # TBPL SUCCESS # 17:30:00 INFO - Running post-action listener: _package_coverage_data 17:30:00 INFO - Running post-action listener: _resource_record_post_action 17:30:00 INFO - [mozharness: 2017-08-13 00:30:00.488681Z] Finished run-tests step (success) 17:30:00 INFO - Running post-run listener: _resource_record_post_run 17:30:00 INFO - Total resource usage - Wall time: 1199s; CPU: 13.0%; Read bytes: 5746688; Write bytes: 445624320; Read time: 3528; Write time: 560584 17:30:00 INFO - TinderboxPrint: CPU usage
13.2% 17:30:00 INFO - TinderboxPrint: I/O read bytes / time
5,746,688 / 3,528 17:30:00 INFO - TinderboxPrint: I/O write bytes / time
445,624,320 / 560,584 17:30:00 INFO - TinderboxPrint: CPU idle
8,301.7 (86.8%) 17:30:00 INFO - TinderboxPrint: CPU user
1,202.8 (12.6%) 17:30:00 INFO - TinderboxPrint: Swap in / out
0 / 0 17:30:00 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 8192; Write bytes: 149417984; Read time: 84; Write time: 117668 17:30:00 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 17:30:00 INFO - run-tests - Wall time: 1181s; CPU: 13.0%; Read bytes: 3706880; Write bytes: 244187136; Read time: 2984; Write time: 357888 17:30:01 INFO - Running post-run listener: _upload_blobber_files 17:30:01 INFO - Blob upload gear active. 17:30:01 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:30:01 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 17:30:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 17:30:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 17:30:01 INFO - (blobuploader) - INFO - Open directory for files ... 17:30:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 17:30:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:30:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:30:04 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 17:30:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:30:04 INFO - (blobuploader) - INFO - Done attempting. 17:30:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 17:30:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:30:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:30:05 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 17:30:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:30:05 INFO - (blobuploader) - INFO - Done attempting. 17:30:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 17:30:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:30:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:30:08 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 17:30:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:30:08 INFO - (blobuploader) - INFO - Done attempting. 17:30:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 17:30:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:30:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:30:09 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 17:30:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:30:09 INFO - (blobuploader) - INFO - Done attempting. 17:30:09 INFO - (blobuploader) - INFO - Iteration through files over. 17:30:09 INFO - Return code: 0 17:30:09 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:30:09 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:30:09 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/19dc2b0cb320c7d79bddf5ff8cafc4d14441176a1ad3257c91a7399a6167ed089e0b6b403a7388b7636acb8d3ca2e5de61a02808db647b25e5a6449108e03c6e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/578820eb90d8bc3029245b7dc2884645b126afad61b5c3e6d3ab5665efbd5329fefb00039097440300dd215dc2c623b5ae680eac4bf35c3cf132f68a8621437e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8d08f7df3a33afb086f0e77949bb42272bad1927cd42900e1c4eed220fdeef20236cf89f86de0d98888c8c78dac3fbac3cd08d0b1df577bb379b90199dec6940", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d877ff6a22dd8a5fda87f5fd2d5e47ad55c910ef17bb43470f0c825355b0549035237b14497192e0ff3d286f3d4adad322511bdc9bd82d3ae29c8b2e713a2cc2"} 17:30:09 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:30:09 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:30:09 INFO - Contents: 17:30:09 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/19dc2b0cb320c7d79bddf5ff8cafc4d14441176a1ad3257c91a7399a6167ed089e0b6b403a7388b7636acb8d3ca2e5de61a02808db647b25e5a6449108e03c6e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/578820eb90d8bc3029245b7dc2884645b126afad61b5c3e6d3ab5665efbd5329fefb00039097440300dd215dc2c623b5ae680eac4bf35c3cf132f68a8621437e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8d08f7df3a33afb086f0e77949bb42272bad1927cd42900e1c4eed220fdeef20236cf89f86de0d98888c8c78dac3fbac3cd08d0b1df577bb379b90199dec6940", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d877ff6a22dd8a5fda87f5fd2d5e47ad55c910ef17bb43470f0c825355b0549035237b14497192e0ff3d286f3d4adad322511bdc9bd82d3ae29c8b2e713a2cc2"} 17:30:09 INFO - Running post-run listener: copy_logs_to_upload_dir 17:30:09 INFO - Copying logs to upload dir... 17:30:09 INFO - mkdir: /builds/slave/test/build/upload/logs 17:30:09 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1265.693126 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2017-08-12 17:30:09.582532) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-12 17:30:09.589266) ========= 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=5cf47e208febbcd81da23b2e0000026f-1502582025.59989-1127655553 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/19dc2b0cb320c7d79bddf5ff8cafc4d14441176a1ad3257c91a7399a6167ed089e0b6b403a7388b7636acb8d3ca2e5de61a02808db647b25e5a6449108e03c6e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/578820eb90d8bc3029245b7dc2884645b126afad61b5c3e6d3ab5665efbd5329fefb00039097440300dd215dc2c623b5ae680eac4bf35c3cf132f68a8621437e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8d08f7df3a33afb086f0e77949bb42272bad1927cd42900e1c4eed220fdeef20236cf89f86de0d98888c8c78dac3fbac3cd08d0b1df577bb379b90199dec6940", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d877ff6a22dd8a5fda87f5fd2d5e47ad55c910ef17bb43470f0c825355b0549035237b14497192e0ff3d286f3d4adad322511bdc9bd82d3ae29c8b2e713a2cc2"} build_url:https://queue.taskcluster.net/v1/task/CP2xHjBSS1Kh1OeG7_y_fA/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011068 build_url: 'https://queue.taskcluster.net/v1/task/CP2xHjBSS1Kh1OeG7_y_fA/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/19dc2b0cb320c7d79bddf5ff8cafc4d14441176a1ad3257c91a7399a6167ed089e0b6b403a7388b7636acb8d3ca2e5de61a02808db647b25e5a6449108e03c6e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/578820eb90d8bc3029245b7dc2884645b126afad61b5c3e6d3ab5665efbd5329fefb00039097440300dd215dc2c623b5ae680eac4bf35c3cf132f68a8621437e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8d08f7df3a33afb086f0e77949bb42272bad1927cd42900e1c4eed220fdeef20236cf89f86de0d98888c8c78dac3fbac3cd08d0b1df577bb379b90199dec6940", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d877ff6a22dd8a5fda87f5fd2d5e47ad55c910ef17bb43470f0c825355b0549035237b14497192e0ff3d286f3d4adad322511bdc9bd82d3ae29c8b2e713a2cc2"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-12 17:30:09.615920) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-12 17:30:09.616237) ========= 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=5cf47e208febbcd81da23b2e0000026f-1502582025.59989-1127655553 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004500 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-12 17:30:09.699018) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-12 17:30:09.699336) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-12 17:30:09.699652) ========= ========= Total master_lag: 0.29 =========