builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-077 starttime: 1503376455.8 results: success (0) revision: c8da3a874d4ad8245711e7c618bb0d5a561f86d8 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-08-21 21:34:15.804744) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-08-21 21:34:15.805215) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-21 21:34:15.805489) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-21 21:34:15.822076) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-21 21:34:15.822395) ========= 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=20e20d796406ba5117c4a6f1000001ba-1503376454.619861-1506835213 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-08-21 21:34:16-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 94.6M=0s 2017-08-21 21:34:16 (94.6 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.208397 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-21 21:34:16.049319) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-21 21:34:16.049687) ========= 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=20e20d796406ba5117c4a6f1000001ba-1503376454.619861-1506835213 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019076 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-21 21:34:16.098931) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-21 21:34:16.101823) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev c8da3a874d4ad8245711e7c618bb0d5a561f86d8 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev c8da3a874d4ad8245711e7c618bb0d5a561f86d8 --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=20e20d796406ba5117c4a6f1000001ba-1503376454.619861-1506835213 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-08-21 21:34:16,325 truncating revision to first 12 chars 2017-08-21 21:34:16,325 Setting DEBUG logging. 2017-08-21 21:34:16,326 attempt 1/10 2017-08-21 21:34:16,326 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/c8da3a874d4a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-08-21 21:34:16,692 unpacking tar archive at: mozilla-beta-c8da3a874d4a/testing/mozharness/ program finished with exit code 0 elapsedTime=0.664462 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-21 21:34:16.778800) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-21 21:34:16.782242) ========= script_repo_revision: c8da3a874d4ad8245711e7c618bb0d5a561f86d8 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-21 21:34:16.782730) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-21 21:34:16.782995) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-21 21:34:16.794528) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 43 secs) (at 2017-08-21 21:34:16.794912) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=20e20d796406ba5117c4a6f1000001ba-1503376454.619861-1506835213 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 21:34:17 INFO - MultiFileLogger online at 20170821 21:34:17 in /builds/slave/test 21:34:17 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta-Non-PGO 21:34:17 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 21:34:17 INFO - {'append_to_log': False, 21:34:17 INFO - 'base_work_dir': '/builds/slave/test', 21:34:17 INFO - 'blob_upload_branch': 'Mozilla-Beta-Non-PGO', 21:34:17 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 21:34:17 INFO - 'branch': 'Mozilla-Beta-Non-PGO', 21:34:17 INFO - 'buildbot_json_path': 'buildprops.json', 21:34:17 INFO - 'code_coverage': False, 21:34:17 INFO - 'config_files': ('talos/linux_config.py',), 21:34:17 INFO - 'default_actions': ('clobber', 21:34:17 INFO - 'read-buildbot-config', 21:34:17 INFO - 'download-and-extract', 21:34:17 INFO - 'populate-webroot', 21:34:17 INFO - 'create-virtualenv', 21:34:17 INFO - 'install', 21:34:17 INFO - 'setup-mitmproxy', 21:34:17 INFO - 'run-tests'), 21:34:17 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 21:34:17 INFO - 'disable_ccov_upload': False, 21:34:17 INFO - 'download_minidump_stackwalk': True, 21:34:17 INFO - 'download_symbols': 'ondemand', 21:34:17 INFO - 'enable_stylo': False, 21:34:17 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 21:34:17 INFO - 'tooltool.py': '/tools/tooltool.py', 21:34:17 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 21:34:17 INFO - '/tools/misc-python/virtualenv.py')}, 21:34:17 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 21:34:17 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 21:34:17 INFO - 'gecko_profile': False, 21:34:17 INFO - 'gecko_profile_interval': 0, 21:34:17 INFO - 'installer_path': 'installer.exe', 21:34:17 INFO - 'log_level': 'info', 21:34:17 INFO - 'log_name': 'talos', 21:34:17 INFO - 'log_to_console': True, 21:34:17 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 21:34:17 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 21:34:17 INFO - 'opt_config_files': (), 21:34:17 INFO - 'pip_index': False, 21:34:17 INFO - 'suite': 'dromaeojs-e10s', 21:34:17 INFO - 'system_bits': '32', 21:34:17 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 21:34:17 INFO - 'title': 'talos-linux64-ix-077', 21:34:17 INFO - 'tooltool_cache': '/builds/tooltool_cache', 21:34:17 INFO - 'use_talos_json': True, 21:34:17 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 21:34:17 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 21:34:17 INFO - 'work_dir': 'build'} 21:34:17 INFO - [mozharness: 2017-08-22 04:34:17.202473Z] Running clobber step. 21:34:17 INFO - Running pre-action listener: _resource_record_pre_action 21:34:17 INFO - Running main action method: clobber 21:34:17 INFO - rmtree: /builds/slave/test/build 21:34:17 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 21:34:18 INFO - Running post-action listener: _resource_record_post_action 21:34:18 INFO - [mozharness: 2017-08-22 04:34:18.221520Z] Finished clobber step (success) 21:34:18 INFO - [mozharness: 2017-08-22 04:34:18.221622Z] Running read-buildbot-config step. 21:34:18 INFO - Running pre-action listener: _resource_record_pre_action 21:34:18 INFO - Running main action method: read_buildbot_config 21:34:18 INFO - Using buildbot properties: 21:34:18 INFO - { 21:34:18 INFO - "project": "", 21:34:18 INFO - "product": "firefox", 21:34:18 INFO - "who": "ryanvm@gmail.com", 21:34:18 INFO - "installer_path": "public/build/target.tar.bz2", 21:34:18 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs-e10s", 21:34:18 INFO - "repository": "https://hg.mozilla.org/releases/mozilla-beta", 21:34:18 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta talos dromaeojs-e10s", 21:34:18 INFO - "stage_platform": "linux64", 21:34:18 INFO - "basedir": "/builds/slave/test", 21:34:18 INFO - "buildnumber": 58, 21:34:18 INFO - "platform": "ubuntu64_hw", 21:34:18 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 21:34:18 INFO - "slavebuilddir": "test", 21:34:18 INFO - "taskId": "ApyD3Ew7QbGrTvrXBZlqRA", 21:34:18 INFO - "branch": "mozilla-beta", 21:34:18 INFO - "script_repo_revision": "production", 21:34:18 INFO - "revision": "c8da3a874d4ad8245711e7c618bb0d5a561f86d8", 21:34:18 INFO - "slavename": "talos-linux64-ix-077", 21:34:18 INFO - "repo_path": "releases/mozilla-beta" 21:34:18 INFO - } 21:34:18 INFO - Finding installer, test and symbols from parent task. 21:34:18 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ApyD3Ew7QbGrTvrXBZlqRA'}, attempt #1 21:34:18 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ApyD3Ew7QbGrTvrXBZlqRA'}, attempt #1 21:34:19 INFO - Task dependencies: E6haCD-NS52AoxaYgNb96Q 21:34:19 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/E6haCD-NS52AoxaYgNb96Q'}, attempt #1 21:34:19 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/E6haCD-NS52AoxaYgNb96Q/artifacts/public/build/target.tar.bz2 21:34:19 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/E6haCD-NS52AoxaYgNb96Q/artifacts/public/build/target.test_packages.json 21:34:19 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/E6haCD-NS52AoxaYgNb96Q/artifacts/public/build/target.crashreporter-symbols.zip 21:34:19 INFO - Running post-action listener: _resource_record_post_action 21:34:19 INFO - [mozharness: 2017-08-22 04:34:19.633110Z] Finished read-buildbot-config step (success) 21:34:19 INFO - [mozharness: 2017-08-22 04:34:19.633334Z] Running download-and-extract step. 21:34:19 INFO - Running pre-action listener: _resource_record_pre_action 21:34:19 INFO - Running main action method: download_and_extract 21:34:19 INFO - mkdir: /builds/slave/test/build/tests 21:34: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')]} 21:34:19 INFO - trying https://queue.taskcluster.net/v1/task/E6haCD-NS52AoxaYgNb96Q/artifacts/public/build/target.test_packages.json 21:34:19 INFO - Downloading https://queue.taskcluster.net/v1/task/E6haCD-NS52AoxaYgNb96Q/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 21:34:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/E6haCD-NS52AoxaYgNb96Q/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 21:34:20 INFO - Downloaded 989 bytes. 21:34:20 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 21:34:20 INFO - Using the following test package requirements: 21:34:20 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 21:34:20 INFO - u'common': [u'target.common.tests.zip'], 21:34:20 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 21:34:20 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 21:34:20 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 21:34:20 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 21:34:20 INFO - u'mozbase': [u'target.common.tests.zip'], 21:34:20 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 21:34:20 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 21:34:20 INFO - u'web-platform': [u'target.common.tests.zip', 21:34:20 INFO - u'target.web-platform.tests.tar.gz'], 21:34:20 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 21:34:20 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 21:34:20 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/E6haCD-NS52AoxaYgNb96Q/artifacts/public/build/target.common.tests.zip 21:34:20 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/E6haCD-NS52AoxaYgNb96Q/artifacts/public/build/target.common.tests.zip'}, attempt #1 21:34:20 INFO - Fetch https://queue.taskcluster.net/v1/task/E6haCD-NS52AoxaYgNb96Q/artifacts/public/build/target.common.tests.zip into memory 21:34:21 INFO - Content-Length response header: 72851378 21:34:21 INFO - Bytes received: 72851378 21:34:32 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 21:34:32 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/E6haCD-NS52AoxaYgNb96Q/artifacts/public/build/target.common.tests.zip 21:34:32 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/E6haCD-NS52AoxaYgNb96Q/artifacts/public/build/target.common.tests.zip'}, attempt #1 21:34:32 INFO - Fetch https://queue.taskcluster.net/v1/task/E6haCD-NS52AoxaYgNb96Q/artifacts/public/build/target.common.tests.zip into memory 21:34:34 INFO - Content-Length response header: 72851378 21:34:34 INFO - Bytes received: 72851378 21:34:46 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/E6haCD-NS52AoxaYgNb96Q/artifacts/public/build/target.talos.tests.zip 21:34:46 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/E6haCD-NS52AoxaYgNb96Q/artifacts/public/build/target.talos.tests.zip'}, attempt #1 21:34:46 INFO - Fetch https://queue.taskcluster.net/v1/task/E6haCD-NS52AoxaYgNb96Q/artifacts/public/build/target.talos.tests.zip into memory 21:34:47 INFO - Content-Length response header: 14496472 21:34:47 INFO - Bytes received: 14496472 21:34: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')]} 21:34:47 INFO - trying https://queue.taskcluster.net/v1/task/E6haCD-NS52AoxaYgNb96Q/artifacts/public/build/target.tar.bz2 21:34:47 INFO - Downloading https://queue.taskcluster.net/v1/task/E6haCD-NS52AoxaYgNb96Q/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 21:34:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/E6haCD-NS52AoxaYgNb96Q/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 21:34:48 INFO - Downloaded 54128205 bytes. 21:34:48 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/E6haCD-NS52AoxaYgNb96Q/artifacts/public/build/target.tar.bz2 21:34:48 INFO - mkdir: /builds/slave/test/properties 21:34:48 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 21:34:48 INFO - Writing to file /builds/slave/test/properties/build_url 21:34:48 INFO - Contents: 21:34:48 INFO - build_url:https://queue.taskcluster.net/v1/task/E6haCD-NS52AoxaYgNb96Q/artifacts/public/build/target.tar.bz2 21:34:48 INFO - Running post-action listener: _resource_record_post_action 21:34:48 INFO - Running post-action listener: set_extra_try_arguments 21:34:48 INFO - [mozharness: 2017-08-22 04:34:48.910599Z] Finished download-and-extract step (success) 21:34:48 INFO - [mozharness: 2017-08-22 04:34:48.910696Z] Running populate-webroot step. 21:34:48 INFO - Running pre-action listener: _resource_record_pre_action 21:34:48 INFO - Running main action method: populate_webroot 21:34:48 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 21:34:48 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 21:34:48 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 21:34:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:34:48 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 21:34:48 INFO - 'tests': ['damp', 'tps']}, 21:34:48 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 21:34:48 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 21:34:48 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 21:34:48 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 21:34:48 INFO - 'other-e10s': {'tests': ['a11yr', 21:34:48 INFO - 'ts_paint', 21:34:48 INFO - 'tpaint', 21:34:48 INFO - 'sessionrestore', 21:34:48 INFO - 'sessionrestore_many_windows', 21:34:48 INFO - 'sessionrestore_no_auto_restore', 21:34:48 INFO - 'tabpaint']}, 21:34:48 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 21:34:48 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 21:34:48 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 21:34:48 INFO - 'talos_options': ['--mitmproxy', 21:34:48 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 21:34:48 INFO - '--firstNonBlankPaint'], 21:34:48 INFO - 'tests': ['quantum_pageload_google', 21:34:48 INFO - 'quantum_pageload_youtube', 21:34:48 INFO - 'quantum_pageload_amazon', 21:34:48 INFO - 'quantum_pageload_facebook']}, 21:34:48 INFO - 'quantum-pageload-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 21:34:48 INFO - 'talos_options': ['--stylo', 21:34:48 INFO - '--mitmproxy', 21:34:48 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 21:34:48 INFO - '--firstNonBlankPaint'], 21:34:48 INFO - 'tests': ['quantum_pageload_google', 21:34:48 INFO - 'quantum_pageload_youtube', 21:34:48 INFO - 'quantum_pageload_amazon', 21:34:48 INFO - 'quantum_pageload_facebook']}, 21:34:48 INFO - 'svgr-e10s': {'tests': ['tsvgx', 21:34:48 INFO - 'tsvgr_opacity', 21:34:48 INFO - 'tart', 21:34:48 INFO - 'tscrollx', 21:34:48 INFO - 'cart', 21:34:48 INFO - 'tsvg_static']}, 21:34:48 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 21:34:48 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 21:34:48 INFO - 'talos_options': ['--stylo', 21:34:48 INFO - '--stylo-threads=1', 21:34:48 INFO - '--mitmproxy', 21:34:48 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 21:34:48 INFO - '--firstNonBlankPaint'], 21:34:48 INFO - 'tests': ['tp6_google', 21:34:48 INFO - 'tp6_youtube', 21:34:48 INFO - 'tp6_amazon', 21:34:48 INFO - 'tp6_facebook']}, 21:34:48 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 21:34:48 INFO - 'talos_options': ['--xperf_path', 21:34:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:34:48 INFO - 'tests': ['tp5n']}}} 21:34:48 INFO - Running post-action listener: _resource_record_post_action 21:34:48 INFO - [mozharness: 2017-08-22 04:34:48.919461Z] Finished populate-webroot step (success) 21:34:48 INFO - [mozharness: 2017-08-22 04:34:48.919552Z] Running create-virtualenv step. 21:34:48 INFO - Running pre-action listener: _resource_record_pre_action 21:34:48 INFO - Running main action method: create_virtualenv 21:34:48 INFO - Creating virtualenv /builds/slave/test/build/venv 21:34:48 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 21:34:48 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 21:34:48 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 21:34:49 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 21:34:49 INFO - Using real prefix '/usr' 21:34:49 INFO - New python executable in /builds/slave/test/build/venv/bin/python 21:34:50 INFO - Installing distribute.............................................................................................................................................................................................done. 21:34:52 INFO - Installing pip.................done. 21:34:52 INFO - Return code: 0 21:34:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 21:34:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 21:34:52 INFO - Reading from file tmpfile_stdout 21:34:52 INFO - Output received: 21:34:52 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) 21:34:52 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 21:34: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')]} 21:34:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:34: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')]} 21:34:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:34:52 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 0x11bb1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1387a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x152aeb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1528050>, '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 0x1528f50>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15293e0>, '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': '20e20d796406ba5117c4a6f1000001ba-1503376454.619861-1506835213', '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 21:34:52 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 21:34:52 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 21:34:52 INFO - Using env: {'DISPLAY': ':0', 21:34:52 INFO - 'HOME': '/home/cltbld', 21:34:52 INFO - 'LANG': 'en_US.UTF-8', 21:34:52 INFO - 'LANGUAGE': 'en_US:en', 21:34:52 INFO - 'LOGNAME': 'cltbld', 21:34:52 INFO - 'MAIL': '/var/mail/cltbld', 21:34:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:34:52 INFO - 'MOZ_NO_REMOTE': '1', 21:34:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:34:52 INFO - 'NO_EM_RESTART': '1', 21:34:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:34:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:34:52 INFO - 'PWD': '/builds/slave/test', 21:34:52 INFO - 'SHELL': '/bin/bash', 21:34:52 INFO - 'SHLVL': '1', 21:34:52 INFO - 'TERM': 'linux', 21:34:52 INFO - 'TMOUT': '86400', 21:34:52 INFO - 'USER': 'cltbld', 21:34:52 INFO - 'XDG_SESSION_COOKIE': '20e20d796406ba5117c4a6f1000001ba-1503376454.619861-1506835213', 21:34:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:34:52 INFO - '_': '/tools/buildbot/bin/python'} 21:34:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:34:52 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 21:34:52 INFO - Cleaning up... 21:34:52 INFO - Return code: 0 21:34:52 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 21:34: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')]} 21:34:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:34: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')]} 21:34:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:34:52 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 0x11bb1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1387a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x152aeb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1528050>, '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 0x1528f50>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15293e0>, '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': '20e20d796406ba5117c4a6f1000001ba-1503376454.619861-1506835213', '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 21:34:52 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 21:34:52 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 21:34:52 INFO - Using env: {'DISPLAY': ':0', 21:34:52 INFO - 'HOME': '/home/cltbld', 21:34:52 INFO - 'LANG': 'en_US.UTF-8', 21:34:52 INFO - 'LANGUAGE': 'en_US:en', 21:34:52 INFO - 'LOGNAME': 'cltbld', 21:34:52 INFO - 'MAIL': '/var/mail/cltbld', 21:34:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:34:52 INFO - 'MOZ_NO_REMOTE': '1', 21:34:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:34:52 INFO - 'NO_EM_RESTART': '1', 21:34:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:34:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:34:52 INFO - 'PWD': '/builds/slave/test', 21:34:52 INFO - 'SHELL': '/bin/bash', 21:34:52 INFO - 'SHLVL': '1', 21:34:52 INFO - 'TERM': 'linux', 21:34:52 INFO - 'TMOUT': '86400', 21:34:52 INFO - 'USER': 'cltbld', 21:34:52 INFO - 'XDG_SESSION_COOKIE': '20e20d796406ba5117c4a6f1000001ba-1503376454.619861-1506835213', 21:34:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:34:52 INFO - '_': '/tools/buildbot/bin/python'} 21:34:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:34:52 INFO - Downloading/unpacking psutil>=3.1.1 21:34:52 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 21:34:52 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 21:34:52 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 21:34:52 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 21:34:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 21:34:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:34:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:34:54 INFO - Installing collected packages: psutil 21:34:54 INFO - Running setup.py install for psutil 21:34:54 INFO - building 'psutil._psutil_linux' extension 21:34:54 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 21:34:55 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 21:34:55 INFO - building 'psutil._psutil_posix' extension 21:34:55 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 21:34:55 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 21:34:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:34:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:34:55 INFO - Successfully installed psutil 21:34:55 INFO - Cleaning up... 21:34:55 INFO - Return code: 0 21:34:55 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 21:34: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')]} 21:34:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:34: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')]} 21:34:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:34:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11bb1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1387a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x152aeb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1528050>, '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 0x1528f50>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15293e0>, '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': '20e20d796406ba5117c4a6f1000001ba-1503376454.619861-1506835213', '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 21:34:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 21:34:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 21:34:55 INFO - Using env: {'DISPLAY': ':0', 21:34:55 INFO - 'HOME': '/home/cltbld', 21:34:55 INFO - 'LANG': 'en_US.UTF-8', 21:34:55 INFO - 'LANGUAGE': 'en_US:en', 21:34:55 INFO - 'LOGNAME': 'cltbld', 21:34:55 INFO - 'MAIL': '/var/mail/cltbld', 21:34:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:34:55 INFO - 'MOZ_NO_REMOTE': '1', 21:34:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:34:55 INFO - 'NO_EM_RESTART': '1', 21:34:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:34:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:34:55 INFO - 'PWD': '/builds/slave/test', 21:34:55 INFO - 'SHELL': '/bin/bash', 21:34:55 INFO - 'SHLVL': '1', 21:34:55 INFO - 'TERM': 'linux', 21:34:55 INFO - 'TMOUT': '86400', 21:34:55 INFO - 'USER': 'cltbld', 21:34:55 INFO - 'XDG_SESSION_COOKIE': '20e20d796406ba5117c4a6f1000001ba-1503376454.619861-1506835213', 21:34:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:34:55 INFO - '_': '/tools/buildbot/bin/python'} 21:34:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:34:55 INFO - Downloading/unpacking mozsystemmonitor==0.3 21:34:55 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 21:34:55 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 21:34:55 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 21:34:55 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 21:34:56 INFO - Downloading mozsystemmonitor-0.3.tar.gz 21:34:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 21:34:56 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 21:34:56 INFO - Installing collected packages: mozsystemmonitor 21:34:56 INFO - Running setup.py install for mozsystemmonitor 21:34:56 INFO - Successfully installed mozsystemmonitor 21:34:56 INFO - Cleaning up... 21:34:56 INFO - Return code: 0 21:34:56 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 21:34:56 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')]} 21:34:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:34:56 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')]} 21:34:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:34:56 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 0x11bb1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1387a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x152aeb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1528050>, '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 0x1528f50>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15293e0>, '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': '20e20d796406ba5117c4a6f1000001ba-1503376454.619861-1506835213', '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 21:34:56 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 21:34:56 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 21:34:56 INFO - Using env: {'DISPLAY': ':0', 21:34:56 INFO - 'HOME': '/home/cltbld', 21:34:56 INFO - 'LANG': 'en_US.UTF-8', 21:34:56 INFO - 'LANGUAGE': 'en_US:en', 21:34:56 INFO - 'LOGNAME': 'cltbld', 21:34:56 INFO - 'MAIL': '/var/mail/cltbld', 21:34:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:34:56 INFO - 'MOZ_NO_REMOTE': '1', 21:34:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:34:56 INFO - 'NO_EM_RESTART': '1', 21:34:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:34:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:34:56 INFO - 'PWD': '/builds/slave/test', 21:34:56 INFO - 'SHELL': '/bin/bash', 21:34:56 INFO - 'SHLVL': '1', 21:34:56 INFO - 'TERM': 'linux', 21:34:56 INFO - 'TMOUT': '86400', 21:34:56 INFO - 'USER': 'cltbld', 21:34:56 INFO - 'XDG_SESSION_COOKIE': '20e20d796406ba5117c4a6f1000001ba-1503376454.619861-1506835213', 21:34:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:34:56 INFO - '_': '/tools/buildbot/bin/python'} 21:34:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:34:57 INFO - Downloading/unpacking jsonschema==2.5.1 21:34:57 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 21:34:57 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 21:34:57 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 21:34:57 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 21:34:58 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 21:34:58 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 21:34: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 21:34: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 21:34: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 21:34: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 21:34:58 INFO - Downloading functools32-3.2.3-2.tar.gz 21:34:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 21:34:58 INFO - warning: no files found matching '*.txt' 21:34:58 INFO - no previously-included directories found matching 'build' 21:34:58 INFO - no previously-included directories found matching 'dist' 21:34:58 INFO - no previously-included directories found matching '.git*' 21:34:58 INFO - Installing collected packages: jsonschema, functools32 21:34:58 INFO - Running setup.py install for functools32 21:34:58 INFO - warning: no files found matching '*.txt' 21:34:58 INFO - no previously-included directories found matching 'build' 21:34:58 INFO - no previously-included directories found matching 'dist' 21:34:58 INFO - no previously-included directories found matching '.git*' 21:34:58 INFO - Successfully installed jsonschema functools32 21:34:58 INFO - Cleaning up... 21:34:58 INFO - Return code: 0 21:34:58 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 21:34: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')]} 21:34:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:34: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')]} 21:34:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:34:58 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 0x11bb1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1387a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x152aeb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1528050>, '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 0x1528f50>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15293e0>, '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': '20e20d796406ba5117c4a6f1000001ba-1503376454.619861-1506835213', '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 21:34:58 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 21:34:58 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 21:34:58 INFO - Using env: {'DISPLAY': ':0', 21:34:58 INFO - 'HOME': '/home/cltbld', 21:34:58 INFO - 'LANG': 'en_US.UTF-8', 21:34:58 INFO - 'LANGUAGE': 'en_US:en', 21:34:58 INFO - 'LOGNAME': 'cltbld', 21:34:58 INFO - 'MAIL': '/var/mail/cltbld', 21:34:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:34:58 INFO - 'MOZ_NO_REMOTE': '1', 21:34:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:34:58 INFO - 'NO_EM_RESTART': '1', 21:34:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:34:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:34:58 INFO - 'PWD': '/builds/slave/test', 21:34:58 INFO - 'SHELL': '/bin/bash', 21:34:58 INFO - 'SHLVL': '1', 21:34:58 INFO - 'TERM': 'linux', 21:34:58 INFO - 'TMOUT': '86400', 21:34:58 INFO - 'USER': 'cltbld', 21:34:58 INFO - 'XDG_SESSION_COOKIE': '20e20d796406ba5117c4a6f1000001ba-1503376454.619861-1506835213', 21:34:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:34:58 INFO - '_': '/tools/buildbot/bin/python'} 21:34:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:34:58 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 21:34:58 INFO - Cleaning up... 21:34:58 INFO - Return code: 0 21:34:58 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 21:34: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')]} 21:34:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:34: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')]} 21:34:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:34:58 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 0x11bb1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1387a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x152aeb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1528050>, '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 0x1528f50>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15293e0>, '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': '20e20d796406ba5117c4a6f1000001ba-1503376454.619861-1506835213', '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 21:34:58 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 21:34:58 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 21:34:58 INFO - Using env: {'DISPLAY': ':0', 21:34:58 INFO - 'HOME': '/home/cltbld', 21:34:58 INFO - 'LANG': 'en_US.UTF-8', 21:34:58 INFO - 'LANGUAGE': 'en_US:en', 21:34:58 INFO - 'LOGNAME': 'cltbld', 21:34:58 INFO - 'MAIL': '/var/mail/cltbld', 21:34:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:34:58 INFO - 'MOZ_NO_REMOTE': '1', 21:34:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:34:58 INFO - 'NO_EM_RESTART': '1', 21:34:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:34:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:34:58 INFO - 'PWD': '/builds/slave/test', 21:34:58 INFO - 'SHELL': '/bin/bash', 21:34:58 INFO - 'SHLVL': '1', 21:34:58 INFO - 'TERM': 'linux', 21:34:58 INFO - 'TMOUT': '86400', 21:34:58 INFO - 'USER': 'cltbld', 21:34:58 INFO - 'XDG_SESSION_COOKIE': '20e20d796406ba5117c4a6f1000001ba-1503376454.619861-1506835213', 21:34:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:34:58 INFO - '_': '/tools/buildbot/bin/python'} 21:34:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:34:59 INFO - Downloading/unpacking blobuploader==1.2.4 21:34:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:34:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:34:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:34:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:35:00 INFO - Downloading blobuploader-1.2.4.tar.gz 21:35:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 21:35:00 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 21:35:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:35:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:35:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:35:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:35:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 21:35:00 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 21:35:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:35:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:35:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:35:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:35:00 INFO - Downloading docopt-0.6.1.tar.gz 21:35:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 21:35:00 INFO - Installing collected packages: blobuploader, requests, docopt 21:35:00 INFO - Running setup.py install for blobuploader 21:35:00 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 21:35:00 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 21:35:00 INFO - Running setup.py install for requests 21:35:01 INFO - Running setup.py install for docopt 21:35:01 INFO - Successfully installed blobuploader requests docopt 21:35:01 INFO - Cleaning up... 21:35:01 INFO - Return code: 0 21:35:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:35:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:35:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:35:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:35:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:35:01 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 0x11bb1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1387a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x152aeb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1528050>, '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 0x1528f50>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15293e0>, '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': '20e20d796406ba5117c4a6f1000001ba-1503376454.619861-1506835213', '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 21:35:01 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 21:35:01 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 21:35:01 INFO - Using env: {'DISPLAY': ':0', 21:35:01 INFO - 'HOME': '/home/cltbld', 21:35:01 INFO - 'LANG': 'en_US.UTF-8', 21:35:01 INFO - 'LANGUAGE': 'en_US:en', 21:35:01 INFO - 'LOGNAME': 'cltbld', 21:35:01 INFO - 'MAIL': '/var/mail/cltbld', 21:35:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:35:01 INFO - 'MOZ_NO_REMOTE': '1', 21:35:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:35:01 INFO - 'NO_EM_RESTART': '1', 21:35:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:35:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:35:01 INFO - 'PWD': '/builds/slave/test', 21:35:01 INFO - 'SHELL': '/bin/bash', 21:35:01 INFO - 'SHLVL': '1', 21:35:01 INFO - 'TERM': 'linux', 21:35:01 INFO - 'TMOUT': '86400', 21:35:01 INFO - 'USER': 'cltbld', 21:35:01 INFO - 'XDG_SESSION_COOKIE': '20e20d796406ba5117c4a6f1000001ba-1503376454.619861-1506835213', 21:35:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:35:01 INFO - '_': '/tools/buildbot/bin/python'} 21:35:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:35:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:35:01 INFO - Running setup.py (path:/tmp/pip-zNRlX0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:35:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:35:01 INFO - Running setup.py (path:/tmp/pip-8NQb0D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:35:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:35:01 INFO - Running setup.py (path:/tmp/pip-vAJx_U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:35:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:35:01 INFO - Running setup.py (path:/tmp/pip-1KGaBg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:35:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:35:01 INFO - Running setup.py (path:/tmp/pip-GiU1pG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:35:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:35:01 INFO - Running setup.py (path:/tmp/pip-XVlHiC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:35:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:35:02 INFO - Running setup.py (path:/tmp/pip-sXjEqg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:35:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:35:02 INFO - Running setup.py (path:/tmp/pip-bjljIf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:35:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:35:02 INFO - Running setup.py (path:/tmp/pip-dNyKfr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:35:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:35:02 INFO - Running setup.py (path:/tmp/pip-uSmZOQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:35:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:35:02 INFO - Running setup.py (path:/tmp/pip-KDwx58-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:35:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:35:02 INFO - Running setup.py (path:/tmp/pip-JRO084-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:35:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:35:02 INFO - Running setup.py (path:/tmp/pip-UiM9k7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:35:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:35:02 INFO - Running setup.py (path:/tmp/pip-YAI7Y7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:35:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:35:02 INFO - Running setup.py (path:/tmp/pip-opbJ6E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:35:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:35:02 INFO - Running setup.py (path:/tmp/pip-L9k_q1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:35:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:35:02 INFO - Running setup.py (path:/tmp/pip-Ux5WOK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:35:02 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 21:35:02 INFO - Running setup.py install for manifestparser 21:35:02 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 21:35:02 INFO - Running setup.py install for mozcrash 21:35:03 INFO - Running setup.py install for mozdebug 21:35:03 INFO - Running setup.py install for mozdevice 21:35:03 INFO - Installing dm script to /builds/slave/test/build/venv/bin 21:35:03 INFO - Running setup.py install for mozfile 21:35:03 INFO - Running setup.py install for mozhttpd 21:35:03 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 21:35:03 INFO - Running setup.py install for mozinfo 21:35:03 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 21:35:03 INFO - Running setup.py install for mozInstall 21:35:03 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 21:35:03 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 21:35:03 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 21:35:03 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 21:35:03 INFO - Running setup.py install for mozleak 21:35:03 INFO - Running setup.py install for mozlog 21:35:03 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 21:35:03 INFO - Running setup.py install for moznetwork 21:35:03 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 21:35:03 INFO - Running setup.py install for mozprocess 21:35:04 INFO - Running setup.py install for mozprofile 21:35:04 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 21:35:04 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 21:35:04 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 21:35:04 INFO - Running setup.py install for mozrunner 21:35:04 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 21:35:04 INFO - Running setup.py install for mozscreenshot 21:35:04 INFO - Running setup.py install for moztest 21:35:04 INFO - Running setup.py install for mozversion 21:35:04 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 21:35:04 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 21:35:04 INFO - Cleaning up... 21:35:04 INFO - Return code: 0 21:35:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:35:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:35:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:35:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:35:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:35:04 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 0x11bb1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1387a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x152aeb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1528050>, '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 0x1528f50>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15293e0>, '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': '20e20d796406ba5117c4a6f1000001ba-1503376454.619861-1506835213', '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 21:35:04 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 21:35:04 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 21:35:04 INFO - Using env: {'DISPLAY': ':0', 21:35:04 INFO - 'HOME': '/home/cltbld', 21:35:04 INFO - 'LANG': 'en_US.UTF-8', 21:35:04 INFO - 'LANGUAGE': 'en_US:en', 21:35:04 INFO - 'LOGNAME': 'cltbld', 21:35:04 INFO - 'MAIL': '/var/mail/cltbld', 21:35:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:35:04 INFO - 'MOZ_NO_REMOTE': '1', 21:35:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:35:04 INFO - 'NO_EM_RESTART': '1', 21:35:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:35:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:35:04 INFO - 'PWD': '/builds/slave/test', 21:35:04 INFO - 'SHELL': '/bin/bash', 21:35:04 INFO - 'SHLVL': '1', 21:35:04 INFO - 'TERM': 'linux', 21:35:04 INFO - 'TMOUT': '86400', 21:35:04 INFO - 'USER': 'cltbld', 21:35:04 INFO - 'XDG_SESSION_COOKIE': '20e20d796406ba5117c4a6f1000001ba-1503376454.619861-1506835213', 21:35:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:35:04 INFO - '_': '/tools/buildbot/bin/python'} 21:35:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:35:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:35:04 INFO - Running setup.py (path:/tmp/pip-XN5glP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:35:04 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)) 21:35:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:35:04 INFO - Running setup.py (path:/tmp/pip-Q0wszP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:35:04 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)) 21:35:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:35:04 INFO - Running setup.py (path:/tmp/pip-Rmhgrz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:35:05 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)) 21:35:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:35:05 INFO - Running setup.py (path:/tmp/pip-MvnPgF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:35:05 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)) 21:35:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:35:05 INFO - Running setup.py (path:/tmp/pip-9jqyqI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:35:05 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)) 21:35:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:35:05 INFO - Running setup.py (path:/tmp/pip-sazuK8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:35:05 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)) 21:35:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:35:05 INFO - Running setup.py (path:/tmp/pip-n5Zabp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:35:05 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)) 21:35:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:35:05 INFO - Running setup.py (path:/tmp/pip-vtz3GX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:35:05 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)) 21:35:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:35:05 INFO - Running setup.py (path:/tmp/pip-KahYhX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:35:05 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)) 21:35:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:35:05 INFO - Running setup.py (path:/tmp/pip-VwVU5l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:35:05 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)) 21:35:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:35:05 INFO - Running setup.py (path:/tmp/pip-7bBeVQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:35:05 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)) 21:35:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:35:05 INFO - Running setup.py (path:/tmp/pip-7qoKc6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:35:05 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)) 21:35:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:35:05 INFO - Running setup.py (path:/tmp/pip-ghnXzb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:35:05 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)) 21:35:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:35:05 INFO - Running setup.py (path:/tmp/pip-Uajbgl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:35:05 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)) 21:35:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:35:05 INFO - Running setup.py (path:/tmp/pip-DxQ06g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:35:05 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)) 21:35:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:35:05 INFO - Running setup.py (path:/tmp/pip-NltMwR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:35:05 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)) 21:35:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:35:05 INFO - Running setup.py (path:/tmp/pip-kRz0a5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:35:06 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)) 21:35:06 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)) 21:35:06 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)) 21:35:06 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)) 21:35:06 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)) 21:35:06 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)) 21:35:06 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)) 21:35:06 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 21:35:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:35:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:35:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:35:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:35:07 INFO - Downloading blessings-1.6.tar.gz 21:35:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 21:35:07 INFO - Installing collected packages: blessings 21:35:07 INFO - Running setup.py install for blessings 21:35:07 INFO - Successfully installed blessings 21:35:07 INFO - Cleaning up... 21:35:07 INFO - Return code: 0 21:35:07 INFO - Done creating virtualenv /builds/slave/test/build/venv. 21:35:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:35:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:35:07 INFO - Reading from file tmpfile_stdout 21:35:07 INFO - Current package versions: 21:35:07 INFO - argparse == 1.2.1 21:35:07 INFO - blessings == 1.6 21:35:07 INFO - blobuploader == 1.2.4 21:35:07 INFO - docopt == 0.6.1 21:35:07 INFO - functools32 == 3.2.3-2 21:35:07 INFO - jsonschema == 2.5.1 21:35:07 INFO - manifestparser == 1.1 21:35:07 INFO - mozInstall == 1.13 21:35:07 INFO - mozcrash == 1.0 21:35:07 INFO - mozdebug == 0.1 21:35:07 INFO - mozdevice == 0.51 21:35:07 INFO - mozfile == 1.2 21:35:07 INFO - mozhttpd == 0.7 21:35:07 INFO - mozinfo == 0.10 21:35:07 INFO - mozleak == 0.1 21:35:07 INFO - mozlog == 3.5 21:35:07 INFO - moznetwork == 0.27 21:35:07 INFO - mozprocess == 0.25 21:35:07 INFO - mozprofile == 0.28 21:35:07 INFO - mozrunner == 6.13 21:35:07 INFO - mozscreenshot == 0.1 21:35:07 INFO - mozsystemmonitor == 0.3 21:35:07 INFO - moztest == 0.8 21:35:07 INFO - mozversion == 1.4 21:35:07 INFO - psutil == 3.1.1 21:35:07 INFO - requests == 1.2.3 21:35:07 INFO - wsgiref == 0.1.2 21:35:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:35:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:35:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:35:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:35:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:35:07 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 0x11bb1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1387a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x152aeb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1528050>, '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 0x1528f50>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15293e0>, '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': '20e20d796406ba5117c4a6f1000001ba-1503376454.619861-1506835213', '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 21:35:07 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 21:35:07 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 21:35:07 INFO - Using env: {'DISPLAY': ':0', 21:35:07 INFO - 'HOME': '/home/cltbld', 21:35:07 INFO - 'LANG': 'en_US.UTF-8', 21:35:07 INFO - 'LANGUAGE': 'en_US:en', 21:35:07 INFO - 'LOGNAME': 'cltbld', 21:35:07 INFO - 'MAIL': '/var/mail/cltbld', 21:35:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:35:07 INFO - 'MOZ_NO_REMOTE': '1', 21:35:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:35:07 INFO - 'NO_EM_RESTART': '1', 21:35:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:35:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:35:07 INFO - 'PWD': '/builds/slave/test', 21:35:07 INFO - 'SHELL': '/bin/bash', 21:35:07 INFO - 'SHLVL': '1', 21:35:07 INFO - 'TERM': 'linux', 21:35:07 INFO - 'TMOUT': '86400', 21:35:07 INFO - 'USER': 'cltbld', 21:35:07 INFO - 'XDG_SESSION_COOKIE': '20e20d796406ba5117c4a6f1000001ba-1503376454.619861-1506835213', 21:35:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:35:07 INFO - '_': '/tools/buildbot/bin/python'} 21:35:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:35:07 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)) 21:35:07 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)) 21:35:07 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)) 21:35:07 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)) 21:35:07 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)) 21:35:07 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)) 21:35:07 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)) 21:35:07 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)) 21:35:07 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)) 21:35:07 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 21:35:07 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 21:35:07 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 21:35:07 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 21:35:07 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 21:35:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 21:35:09 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)) 21:35:09 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)) 21:35:09 INFO - Installing collected packages: simplejson 21:35:09 INFO - Running setup.py install for simplejson 21:35:09 INFO - building 'simplejson._speedups' extension 21:35:09 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 21:35:09 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 21:35:10 INFO - Successfully installed simplejson 21:35:10 INFO - Cleaning up... 21:35:10 INFO - Return code: 0 21:35:10 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 21:35:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:35:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:35:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:35:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:35:10 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11bb1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1387a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x152aeb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1528050>, '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 0x1528f50>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15293e0>, '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': '20e20d796406ba5117c4a6f1000001ba-1503376454.619861-1506835213', '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 21:35:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 21:35:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 21:35:10 INFO - Using env: {'DISPLAY': ':0', 21:35:10 INFO - 'HOME': '/home/cltbld', 21:35:10 INFO - 'LANG': 'en_US.UTF-8', 21:35:10 INFO - 'LANGUAGE': 'en_US:en', 21:35:10 INFO - 'LOGNAME': 'cltbld', 21:35:10 INFO - 'MAIL': '/var/mail/cltbld', 21:35:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:35:10 INFO - 'MOZ_NO_REMOTE': '1', 21:35:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:35:10 INFO - 'NO_EM_RESTART': '1', 21:35:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:35:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:35:10 INFO - 'PWD': '/builds/slave/test', 21:35:10 INFO - 'SHELL': '/bin/bash', 21:35:10 INFO - 'SHLVL': '1', 21:35:10 INFO - 'TERM': 'linux', 21:35:10 INFO - 'TMOUT': '86400', 21:35:10 INFO - 'USER': 'cltbld', 21:35:10 INFO - 'XDG_SESSION_COOKIE': '20e20d796406ba5117c4a6f1000001ba-1503376454.619861-1506835213', 21:35:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:35:10 INFO - '_': '/tools/buildbot/bin/python'} 21:35:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:35:10 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 21:35:10 INFO - Cleaning up... 21:35:10 INFO - Return code: 0 21:35:10 INFO - Running post-action listener: _resource_record_post_action 21:35:10 INFO - Running post-action listener: _start_resource_monitoring 21:35:10 INFO - Starting resource monitoring. 21:35:10 INFO - [mozharness: 2017-08-22 04:35:10.358454Z] Finished create-virtualenv step (success) 21:35:10 INFO - [mozharness: 2017-08-22 04:35:10.359466Z] Running install step. 21:35:10 INFO - Running pre-action listener: _resource_record_pre_action 21:35:10 INFO - Running main action method: install 21:35:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:35:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:35:10 INFO - Reading from file tmpfile_stdout 21:35:10 INFO - Detecting whether we're running mozinstall >=1.0... 21:35:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 21:35:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 21:35:10 INFO - Reading from file tmpfile_stdout 21:35:10 INFO - Output received: 21:35:10 INFO - Usage: mozinstall [options] installer 21:35:10 INFO - Options: 21:35:10 INFO - -h, --help show this help message and exit 21:35:10 INFO - -d DEST, --destination=DEST 21:35:10 INFO - Directory to install application into. [default: 21:35:10 INFO - "/builds/slave/test"] 21:35:10 INFO - --app=APP Application being installed. [default: firefox] 21:35:10 INFO - mkdir: /builds/slave/test/build/application 21:35:10 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'] 21:35:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 21:35:25 INFO - Reading from file tmpfile_stdout 21:35:25 INFO - Output received: 21:35:25 INFO - /builds/slave/test/build/application/firefox/firefox 21:35:25 INFO - Running post-action listener: _resource_record_post_action 21:35:25 INFO - [mozharness: 2017-08-22 04:35:25.484589Z] Finished install step (success) 21:35:25 INFO - [mozharness: 2017-08-22 04:35:25.484737Z] Running setup-mitmproxy step. 21:35:25 INFO - Running pre-action listener: _resource_record_pre_action 21:35:25 INFO - Running main action method: setup_mitmproxy 21:35:25 INFO - Skipping: mitmproxy is not required 21:35:25 INFO - Running post-action listener: _resource_record_post_action 21:35:25 INFO - [mozharness: 2017-08-22 04:35:25.485262Z] Finished setup-mitmproxy step (success) 21:35:25 INFO - [mozharness: 2017-08-22 04:35:25.485363Z] Running run-tests step. 21:35:25 INFO - Running pre-action listener: _resource_record_pre_action 21:35:25 INFO - Running pre-action listener: _set_gcov_prefix 21:35:25 INFO - Running main action method: run_tests 21:35:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 21:35:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 21:35:25 INFO - Python 2.7.3 21:35:25 INFO - Return code: 0 21:35:25 INFO - grabbing minidump binary from tooltool 21:35:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:35:25 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 0x1528050>, '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 0x1528f50>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15293e0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 21:35:25 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 21:35:25 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 21:35:25 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 21:35:25 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 21:35:25 INFO - Return code: 0 21:35:25 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 21:35:25 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 21:35:25 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 21:35:25 INFO - ENV: RUST_BACKTRACE is now 1 21:35:25 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 21:35:25 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 21:35:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/E6haCD-NS52AoxaYgNb96Q/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-077', '--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 21:35:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta-Non-PGO --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/E6haCD-NS52AoxaYgNb96Q/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-077 --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 21:35:25 INFO - Using env: {'DISPLAY': ':0', 21:35:25 INFO - 'HOME': '/home/cltbld', 21:35:25 INFO - 'LANG': 'en_US.UTF-8', 21:35:25 INFO - 'LANGUAGE': 'en_US:en', 21:35:25 INFO - 'LOGNAME': 'cltbld', 21:35:25 INFO - 'MAIL': '/var/mail/cltbld', 21:35:25 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 21:35:25 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 21:35:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:35:25 INFO - 'MOZ_NO_REMOTE': '1', 21:35:25 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 21:35:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:35:25 INFO - 'NO_EM_RESTART': '1', 21:35:25 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:35:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:35:25 INFO - 'PWD': '/builds/slave/test', 21:35:25 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 21:35:25 INFO - 'RUST_BACKTRACE': '1', 21:35:25 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 21:35:25 INFO - 'SHELL': '/bin/bash', 21:35:25 INFO - 'SHLVL': '1', 21:35:25 INFO - 'TERM': 'linux', 21:35:25 INFO - 'TMOUT': '86400', 21:35:25 INFO - 'USER': 'cltbld', 21:35:25 INFO - 'XDG_SESSION_COOKIE': '20e20d796406ba5117c4a6f1000001ba-1503376454.619861-1506835213', 21:35:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:35:25 INFO - '_': '/tools/buildbot/bin/python'} 21:35:25 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/E6haCD-NS52AoxaYgNb96Q/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-077', '--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 21:35:25 INFO - mozversion application_buildid: 20170822032012 21:35:25 INFO - mozversion application_changeset: c8da3a874d4ad8245711e7c618bb0d5a561f86d8 21:35:25 INFO - mozversion application_display_name: Firefox 21:35:25 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 21:35:25 INFO - mozversion application_name: Firefox 21:35:25 INFO - mozversion application_remotingname: firefox 21:35:25 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 21:35:25 INFO - mozversion application_vendor: Mozilla 21:35:25 INFO - mozversion application_version: 56.0 21:35:25 INFO - mozversion platform_buildid: 20170822032012 21:35:25 INFO - mozversion platform_changeset: c8da3a874d4ad8245711e7c618bb0d5a561f86d8 21:35:25 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 21:35:25 INFO - mozversion platform_version: 56.0 21:35:25 INFO - using testdate: 1503376525 21:35:25 INFO - actual date: 1503376525 21:35:25 INFO - starting webserver on 'localhost:36552' 21:35:25 INFO - SUITE-START | Running 2 tests 21:35:25 INFO - TEST-START | dromaeo_css 21:35:25 INFO - Initialising browser for dromaeo_css test... 21:35:25 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:36552/getInfo.html -profile /tmp/tmpm_0cHu/profile 21:35:25 INFO - TEST-INFO | started process 3339 (/builds/slave/test/build/application/firefox/firefox http://localhost:36552/getInfo.html) 21:35:32 INFO - TEST-INFO | 3339: exit 0 21:35:32 INFO - Browser initialized. 21:35:32 INFO - Running cycle 1/1 for dromaeo_css test... 21:35:32 INFO - TEST-INFO | started process 3501 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpm_0cHu/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 21:35:32 INFO - PID 3501 | 1503376532724 addons.xpi WARN Exception running bootstrap method startup on clicktoplay-rollout@mozilla.org: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getStringPref]" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/clicktoplay-rollout@mozilla.org.xpi!/bootstrap.js :: defineCohort :: line 49" data: no] Stack trace: defineCohort()@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/clicktoplay-rollout@mozilla.org.xpi!/bootstrap.js:49 < startup()@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/clicktoplay-rollout@mozilla.org.xpi!/bootstrap.js:40 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4395 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2229 < callProvider()@resource://gre/modules/AddonManager.jsm:269 < _startProvider()@resource://gre/modules/AddonManager.jsm:739 < startup()@resource://gre/modules/AddonManager.jsm:906 < startup()@resource://gre/modules/AddonManager.jsm:3090 < observe()@jar:file:///builds/slave/test/build/application/firefox/omni.ja!/components/addonManager.js:65 21:35:43 INFO - PID 3501 | RSS: Main: 150794240 21:35:43 INFO - PID 3501 | 21:38:41 INFO - PID 3501 | Cycle 1(1): loaded http://localhost:36552/tests/dromaeo/cssquery-dojo.html (next: http://localhost:36552/tests/dromaeo/cssquery-ext.html) 21:38:42 INFO - PID 3501 | RSS: Main: 161071104 21:38:42 INFO - PID 3501 | 21:41:43 INFO - PID 3501 | Cycle 1(1): loaded http://localhost:36552/tests/dromaeo/cssquery-ext.html (next: http://localhost:36552/tests/dromaeo/cssquery-jquery.html) 21:41:44 INFO - PID 3501 | RSS: Main: 156930048 21:41:44 INFO - PID 3501 | 21:44:45 INFO - PID 3501 | Cycle 1(1): loaded http://localhost:36552/tests/dromaeo/cssquery-jquery.html (next: http://localhost:36552/tests/dromaeo/cssquery-mootools.html) 21:44:46 INFO - PID 3501 | RSS: Main: 159383552 21:44:46 INFO - PID 3501 | 21:47:48 INFO - PID 3501 | Cycle 1(1): loaded http://localhost:36552/tests/dromaeo/cssquery-mootools.html (next: http://localhost:36552/tests/dromaeo/cssquery-prototype.html) 21:47:48 INFO - PID 3501 | RSS: Main: 159260672 21:47:48 INFO - PID 3501 | 21:50:51 INFO - PID 3501 | Cycle 1(1): loaded http://localhost:36552/tests/dromaeo/cssquery-prototype.html (next: http://localhost:36552/tests/dromaeo/cssquery-yui.html) 21:50:52 INFO - PID 3501 | RSS: Main: 160391168 21:50:52 INFO - PID 3501 | 21:53:53 INFO - PID 3501 | Cycle 1(1): loaded http://localhost:36552/tests/dromaeo/cssquery-yui.html (next: http://localhost:36552/tests/dromaeo/cssquery-dojo.html) 21:53:54 INFO - PID 3501 | RSS: Main: 163635200 21:53:54 INFO - PID 3501 | 21:53:54 INFO - PID 3501 | __start_tp_report 21:53:54 INFO - PID 3501 | _x_x_mozilla_page_load 21:53:54 INFO - PID 3501 | _x_x_mozilla_page_load_details 21:53:54 INFO - PID 3501 | |i|pagename|runs| 21:53:54 INFO - PID 3501 | |0;dojo.html;3538.3807833629107;3546.680798728112;3591.515145455363;3594.8022858742765;3617.457381392791;6344.270408902978;6349.841253968647;6417.679116044191;6418.486521078315;6436.324185960477;6311.147995020671;6340.3976622220935;6449.709763060662;6452.741890324393;6460;5495.313085864267;5531.806386776464;5547.805826796063;5558.388577256498;5642.746076426571;5617.550595952334;5644.971775141119;5668;5688.857778555528;5693.259876216086;5604.8038318658855;5615.073512870413;5652.123920792264;5672.829815105553;5689.345725241588;5538.0585300499015;5548.667079975174;5607.523360514361;5630.239917611111;5631.127175287796;5506.366767821712;5559.027170245191;5564.582656300753;5572.5263352615075;5607.803726869581;6199.597026193328;6217.968910155466;6263.937360626381;6294.496440284766;6323.30443651198;6667.133272674521;6744.966275168593;6779;6822.692978815916;6901.723931042802;6895.724171033153;6900.7929762107215;6904;6985.301469853025;6999.82500437488;6409.583377080475;6411.519136064767;6455.7740479083;6463.48292136628;6500.707468163897;6888.380045795802;6902.689378977958;7024.859502810017;7034.015237866703;7106.467014973898;6755.560888542229;6790.287019862942;6823.215330236987;6833.384995350442;6854.794356169323;6860.348266914635;6896.965515172493;6932.861342773116;6933.0986971693355;6971.930280697229;6844.897326540006;6860.1081859358965;6871.896921546181;6879.552829066195;6889.689963951634;6815.488838337144;6881.036654868322;6884.621345825927;6886.035954966309;6890.414314783299;5038.445770965191;5051.6716413433005;5059.241113832954;5103.132467480537;5111.233315002779;4434.623057040188;4477.70894891831;4529.116822219701;4575.519570445122;4581.312803079498;6578.27638959714;6585.1439312889015;6593.274739778621;6603.438707709803;6603.669816509156;6870.381665650116;6871.793846184622;6915.965420172867;6961.26906674802;6975.720971161199;6396.9360306397275;6525.184351956005;6555.80332590023;6629.171353580802;6666.366695163952;5814.30228372598;5866.120081987736;5870.941290587125;5871.4128587141795;5884.029135192731;7157.141143062761;7158.427325813923;7237.963810181021;7239.9276007240305;7246.782596522113;6884.242733299233;6927.826804329831;6937.618430986142;6943.51395402317;7018.087648605649;6992.3357281058215;7062.470314726519;7090.822729431806;7097.538659987084;7203.783886483414;6803.489738269748;6806.82982925411;6810.28492008332;6820.727170913108;6883.827904302432;6796.864062718817;6930.410915072275;6933.1333583302085;6999.685014174476;7025.789226323219;6720.596764194164;6734.528582999339;6769.458443324621;6772.52592318533;6772.898406524005;6679.198496180489;6683.933160668331;6757.932420675927;6763.526553141233;6768.593884366954;6580.374864387875;6662.966685166543;6672.266050734513;6695.765648202289;6732.8653426930205;6741.528093033637;6780.525363224527;6842.213145488333;6849.212340580698;6860.897086543606;6556.18047744032;6566.573172743756;6584.473242140713;6676.89984650221;6685.431738302299 21:53:54 INFO - PID 3501 | |1;ext.html;884.1158841158843;934.434667026449;948.7865230323176;977.4819345746756;986.3687240166299;35618.10954726133;44055.89860253495;44215.55784442154;44452.777736111355;44541.331880021826;44783;45164;45199.548004519944;45230.09539809202;45259;7661.502002369856;7773.766786996399;7787.7274295399675;7792.337651299618;7808.219178082203;7631.3894888408795;7716.729914452995;7717.228277172294;7732.342750866183;7746.38028957682;7432.479726419126;7724.42066844986;7789.961050194769;7941.126476087626;7944.245296696833;7851.2933835954755;7898.447108702393;7901.920980790204;7910.050793904711;7914.960425197894;7724.382049436088;7747.8450430991625;7771.844563108706;7774.067111946602;7777.650005749755;42031.579684203076;42087.789561052305;42215.78892105551;42495.787521062506;42533.36199956972;3485.163560745428;3530.223350862806;3534.0458076319505;3541.3271478419274;3553.147244661263;30064.098077057723;30270;30364.392712145855;30382.392352153056;30397.544036839467;5104.795808167669;5104.948950510485;5145.511176438233;5173.224016397533;5175.948240517584;215256;216318;216842;217045;217696;53527.46472535225;53566.196507051645;53605.73197133989;53779.19331210035;53903;55497.72251138799;55791;56025.71987140038;56037.7198114015;56055.07614466948;15769.211539422982;15898.76151857723;15997;16130.274137663833;16173.272202750904;12627;12654.87345126537;12669.493220271086;12749.745005099847;13273.601791946257;15680.137592432526;15775.763363549557;15795.842041579437;15892.443764468188;15905;5426.457354264621;6143.354947730513;6222.782202622977;6264.436200741955;6484.5460817742305;1099.3349023840622;1107.1696227829127;1107.2858006585707;1120.1151090638468;1121.5121422181378;24956;25330.11344602967;25497.61753573698;25547.48905021889;25978;1200.885915837994;1210.4189988805422;1212.7877621416217;1217.945192466341;1230.366361323919;13340.332983350794;13348.93325533366;13491.257980810953;13509.662258443617;13758;278152;278705;278756;279364;280813;57407;57735.42264577385;57915.13127303094;57974.42025579775;58211;1517.529565834595;1520.1335238913714;1550.0699580251985;1560.0639616230171;1566.7336552786053;25034.62448063317;25119.497610047332;25147;25167.496650067264;25428.364290892136;41381.37927931143;41418;41554;41622.37566436567;41668;226105;227266;228216;228217;228564;29551.85224073866;29818;29895.402091958473;29970.400591988473;29978.85010574933;38008.61991380051;38053.42919856149;54285;55687;55761.442385575625;38916.2216755669;39073.8046309778;39864.202715944935;39955.40066899057;40459;39219.80390098031;39284.80357598194;39398;39827.20345593014;39984.00039998907;45125.77437112793;46223.76888115538;46576.301355479016;46934;47035.529644703114;37908.241835163695;38235.23529529338;38350.23299533938;38494.615053850226;39089.80455097706;28001.579976300778;28325.291867703472;28403.72770178422;28672.01115371226;28679.587374167484 21:53:54 INFO - PID 3501 | |2;jquery.html;599.8080614203456;600.7116584039661;603.7735849056604;616.8889599872022;626.4518546272012;23885.641715374284;24313.63529547056;25230.621540676857;25242.368940776465;25250.242492725225;25465.61801572978;26229.73770262297;26261.686915654238;26266.737332626668;26266.868665656693;6575.736970521185;6731.427828634572;6743;6751;6751.122354093961;6370.936290637103;6631.668416579176;6651.567648102858;6651.567648102882;6660.733570657168;6630.73477060917;6635.236947751022;6635.933640663604;6638;6640.335966403345;6578.177727784027;6611.9338806611795;6619.933800662004;6633.071370008202;6635.270120286765;6170.876582468325;6175.536834737412;6177.25872895251;6185.752569897199;6193.194884664965;29936;30729.231769205948;31724.524132138038;31821.52267715986;31859;2289.267434420986;2314.5833749925005;2339.508703172336;2339.9883000584887;2340.403197184724;19429.417117486497;19849.900750496156;19888.70166947497;19895.801041989685;19928.103235354443;2453.472503411775;2458.7172475165225;2474.2206205045354;2536.9746302536923;2546.4397832476825;159549.20225398915;163535;163871.1806440972;164435.1778241101;164800;16404.589885252724;16453.670926581643;16558.6688266234;16654.916725416297;16884.57788555296;26043.348916277246;26064.34839129037;26238.606420903703;26255.606165907528;26303;10225.335353202017;10339.121174700234;10411.53148108337;10520.789584208273;10701.250912436055;8457;8828.337874659297;9505.66730164441;9526.951705383375;9608.66369677058;9581.856272155923;10000.399976001463;10038.64864729731;10089.646862359783;10191.184705223564;2774.3631257558136;2834.5543772676083;2886.336142687194;2922.6638936522145;3007.1129016940054;1063.1388575253914;1066.2376400873413;1075.2150929821119;1083.7832433513329;1094.0809719835377;21497.67753483699;21651;21760.455988600093;26323.73676263251;27546.389698373398;1129.1418521923233;1152.8962393384636;1156.5142640091183;1161.2103769436694;1168.5559487394735;11906.987906028085;12049.397530123459;12184.81722774159;12186.573469928688;12239.877601223874;577338;578592;580744;582174;603960;131441.3427932854;135459;136533;138808;140151;1341.7987301904598;1364.911280766747;1387.833459984788;1393.0805668259104;1395.8045873577505;19654.312099076462;19666;19741.21035158635;19922.30271940475;20102;65815;66840.33159668536;66925;67358;68892;84963;85141.57429212815;85428.14571854201;85806.57096714477;85922;13278.53525126616;13897.861021390061;15617.297221624827;15922.442714504938;16046.598835029217;23897.76102239025;24104.517909642058;24183;24481.775911204724;24621.38446538851;19802.90098549498;19846.206151754344;20156.697649534974;20364.490887727927;20399.286024989055;17344.47966561005;17793.288268469118;17850.821491785435;17874.106294684952;18581.721274181167;21589;21623.2431864884;21663.566728665653;22033.228836990627;22070.779292206873;15968.28142733603;16126.596835079217;16271.837281627033;16273.186340682681;16320;20128;20857.478563035438;21183.411244157058;21273.255436059662;21413 21:53:54 INFO - PID 3501 | |3;mootools.html;1466.3035058347284;1491.5450787509812;1507.2313120308638;1525.1535397854193;1526.7480865657164;15458.227088645564;16050.83949160508;16067.758983615255;16077.437289694863;16492.42276520322;16630.66738665226;16898.83101168988;16921;16949;16950.745738813926;2944;2980.1655536449775;3033.453978283908;3035.711607397294;3037.4228896509703;2841.957370639442;2967.421352836198;2981.38881529286;2986.865591048408;2992.2369795702066;2860.728230818069;2889.7110288971144;2891.103757835078;2904;2920.3575213453005;2817.4083442477104;2845.075350511092;2847.3451106245575;2848.985755071222;2851.985740071307;2762.5165596020615;2788.8047836651444;2795.566687163493;2796.0353677981066;2800.4399120175844;15682.372705091782;16088.919555402264;16124.919375403048;16142.677146457005;16168.595785105348;8193.09875913649;8490.66037358505;8508.276796472308;8516.78708032298;8523.232909038215;5588.30146231721;5718.456746609066;5949.851253718648;5969.492593129589;5991.460768530853;1981.7126516655148;2002.8297594704468;2009.7487814023248;2068.5449201175716;2073.5438203595186;27965.720342796514;28259;28260;28388.006419775415;28411.147665570064;7051.682674279669;7163.7492687755675;7224.277572242734;7378.778636640909;7441;19169.32907348236;19217.8078219216;19322.033898305308;19353.8064619352;19355.8064419354;8061.798455038554;8129.634166462523;8177.2640462357485;8182.918170818216;8197.221263979913;7454.105507339045;7507.024086868672;7562.016937798161;7780.533168009937;7840.215978402228;5737.225474560881;5832.358440571534;5855.121731740273;5915.467607915309;5949.375315591887;1609.929396951038;1657.1714142928536;1715.5882588178747;1739.1565090930887;1771.1055916761952;846.3821410370464;858.5363903492045;860.3418384935529;864.7881269089113;928.1414691410419;8121.918780812234;8995.23540499065;9193.632254709737;9205;9224.123708247706;1017.7150397888603;1027.3887037212846;1043.598214687351;1050.669039252633;1082.5886163257912;5103.387593488805;5193.662411943212;5385.434529374359;5430.185472179126;5490.56075513958;22858.314250572508;23006.079756809875;23529.529409411716;23612.527749444915;23860.284191474282;23384;23577.52844943126;23582.29253122409;23752.168674096323;23777.643335349985;11016.394098324668;11864.11019173548;12145.846144616247;12150.756984860252;12197.817032744517;8554.828903421772;8685.478871267744;8882.289416846766;8946.597403116744;9007.594658240527;20151.79848201558;20668.379948601567;20677.793222067587;20860.37418877436;21007.68488472705;20677.483062923548;20961.37115886526;21006.159753609685;21039.8948005259;21232.150713971267;12230.449629766505;12375.257484550955;12469.127161098999;12481.812772808596;12483.25100493937;14620.780688289471;14816.111033338035;15161.241937903282;15177.317020733874;15205.771913421086;10638.255322127377;12035.939820300842;12109;12111.333876636869;12409.131360805019;11212.607558735404;11222.214444989098;11250.156238281998;11441.542338306375;11677.416129193336;10109.848352274868;10208.081272685495;10239.692809215736;10250.948745256226;10304.845427318745;7646.311831935152;7673.731419400294;7736.994190755391;7739.41954353437;7786.104597971079;7634.732784352521;7802.180771018961;7828.725994590162;7837.568933708701;7853 21:53:54 INFO - PID 3501 | |4;prototype.html;538.7898719499399;557.4509108528101;558.868665863522;559.1835919557446;565.0676383966455;6251.937480625193;6290.905636415458;6325.557210995232;6358.14165087713;6382.234131904178;6156.846078848031;6198.628082315065;6254.374562543743;6290.748370065204;6368.522360822945;5024.598032157437;5047.697138171703;5047.949520504784;5056.266841308008;5072.289879416885;4770.4275486941615;4868.440129385113;4973.950260497385;4994.450610432849;4995.800167993294;4826.927596086061;4849.151398505265;4870;4874.317595536628;4888.804447822083;4843.15244832153;4872.3909511311085;4905.583025442842;4914.729689867056;4934.25986102084;4444.466664000341;4462.910741785178;4467.218236808578;4470.2177119004045;4494.662900282459;6144.692765361714;6180.196574445338;6192.009278515416;6240.751849630092;6275.24697036354;5847.970760146214;5865.736041878083;5900.43945825146;5924.496417804492;5934;6687.565308254996;6697.162854643169;6703.3296670332575;6751.42612877906;6802.455803535705;5035.3171550458055;5098.923516147261;5118.360204974379;5120.5903527718065;5126.179811230222;6744.393004629558;6834.521583489158;6898.344657257603;7024.332688394595;7081.052710504601;5099.59203263738;5222.206119725928;5233.743901463621;5237.895242095136;5254.5549973757525;5297.655652382583;5298.231756395301;5310.176922577007;5312.92030619541;5319.521243088063;5846.181534585162;5853.356130825606;5861.882762344814;5872.884152011105;5891.617044892068;6708.188789026935;6722.588256466197;6730.754810360001;6735.932640673628;6746.595204287661;1843.7787465504225;1865.2538984406347;1865.7108148237046;1866.8599855010928;1867.5424520992171;1265.373640048182;1297.7274772297924;1310.6068179546098;1321.4714114354335;1323.781576039962;6389.488840892717;6411.070394792729;6420.6147631142285;6459.450946669585;6479.967600161969;6723.462123030145;6818.874885643813;6829.180498340231;6845.828854278583;6848.863022739517;6424.421802037839;6435.324290949477;6462.806115816533;6497;6507.8698426031215;3578.946315805213;3681.4293784463453;3694.408894576855;3695.427208782643;3699.3341198583976;7065.92934070663;7093.751718689924;7165.713371465083;7199.784006479814;7207.45944054198;6982.895256571054;6987.720491180397;7058.188308344504;7092.865141577323;7121.786346409616;7010.684519196649;7104.21853596104;7119.288071192943;7158.713651453883;7170.533915295489;6820.897686534805;6864.622445765331;6894;6928.1339832520935;6931.584104953719;6668.233153187445;6760.729570817112;6819.079424277659;6823.735165521549;6842.7605033823575;6931.930680693128;6959.338862808025;6985.755498557526;6986.895196572157;7056.506044577036;5259.237410575369;5281.973590132025;5291.788328466818;5299.867503312448;5316.680999140064;6424.421802037839;6595.4393876521035;6746.898796518154;6841.213260475109;6852.6231057292325;6581.638009909501;6609.438197753053;6631.20425548918;6660.868522073084;6712.664366781739;6678.83302917431;6692.531522793358;6695.263521012587;6697.162854643169;6757.594544327356;6604.603723776589;6644.667766611554;6677.131972843499;6715.160604924385;6730.899036514554;6655.201375834931;6689.866202676017;6721.596704197764;6723.327667233238;6757.189137303555;6543.1821022372205;6543.509236807164;6583.670816459254;6608.669566521751;6623.238327592196 21:53:54 INFO - PID 3501 | |5;yui.html;951.9000504946985;959.4387283439188;959.8704174936381;980.5097451274363;995.0199053931881;19128.234870605178;19566.60866782667;19577.216911323547;19593.31423400183;21436.785632143674;21357.466063348424;21502;21750.56498870022;21778.782212177914;21807.890960545214;1047.9528421221025;1083.9512221949992;1086.7772106718144;1087.1140020882995;1087.798757229911;1029.2383636109262;1068.8663917010374;1069.107295408335;1073.8228192348254;1074;1122.960696375627;1126.7070561653989;1127.3179726265614;1129.6272230164068;1129.8926601972842;1114.6210288501907;1115.2360632966445;1115.966521004371;1116.8045592021404;1121.198343184627;822.9629666665014;824.1552408780988;824.6000689665508;829.6266679993967;831.6839600951661;18909;19348;19439.80560194408;19451.610967780565;20042.799572004384;21358.359249222547;21387.144514219413;21781;21796.564068718537;22019.22932697364;16695.165241737985;16986;17024.65950680992;17069.658606827794;17072;3683.4843121962945;3706.7405281630295;3709.0727318170457;3709.925801483982;3710.7588006779474;143775;146834;147355.26322368428;148541;149903;15039.398424063134;15041.699166016619;15268.16025118607;15298.158601277037;15314.310856011507;31730.778845481058;32047.51928721071;32115.839420803215;32267.838660807014;32300.35399292048;8876.245519130816;8910.910890891138;9038.231750301298;9114;9114;4551.40831691878;4574.039451715111;4620.907581848344;4646.558576935254;4648.581627653527;5777.422257774273;5820.912686309709;5832.708364581754;5877.030290002187;5880.441358070976;1038.2368958815264;1042.5308611125024;1068.7702144038913;1069.25152393325;1073.1682945717132;708.6315116139682;721.4733244731375;721.72935149319;729.1869565434608;745.05006117201;12011.579594714318;12382.442790074469;12446.315452650191;12535.623931282076;13080.215187088805;721.4553012475652;730.9049823523014;736.0357931110262;736.9299916507923;737.9114506259283;6018.759249629966;6052.303985041776;6195.349488303663;6276.748930042747;6316.936830631635;129365;129742;130697;131339;133241;117137;117523;117892;120744.39627801804;122891;102720;102902.48548757208;103107;103392;105284;11475.598354057567;11567.826482602599;11598.130140239347;11969.940150299193;12043.879561204276;49985.25022124598;50034;50728.49271507385;51009.23486147637;51305.7434712824;61191;61248.69375653272;61384.386156139655;61493;64936.675316623114;16243.431479898147;16488.0931548766;16532.504024879272;16671.749923751395;16677.91661041687;17950.102494874944;18024.639507210046;18046;18196;18202.635947280716;16426.671466570362;16572.088535130682;16652.417165399154;16682.833171668615;16739;17068.74396884023;17112;17117.315307387566;17162.39931602388;17279.82720172782;15882.523524294289;16031;16065.116418596623;16177.59556011109;16363.18184090767;12599.496020159091;12741.362931853555;12882.291473969022;12985.545505907248;13120.60638180856;11935.52257909674;12004.939975300067;12285.508579657071;12406.069544784357;12411.937940310241 21:53:54 INFO - PID 3501 | __end_tp_report 21:53:54 INFO - PID 3501 | __start_cc_report 21:53:54 INFO - PID 3501 | _x_x_mozilla_cycle_collect,284 21:53:54 INFO - PID 3501 | __end_cc_report 21:53:54 INFO - PID 3501 | __startTimestamp1503377634208__endTimestamp 21:53:54 INFO - PID 3501 | 21:53:54 INFO - PID 3501 | ------- Summary: start ------- 21:53:54 INFO - PID 3501 | Number of tests: 6 21:53:54 INFO - PID 3501 | 21:53:54 INFO - PID 3501 | [#0] dojo.html Cycles:165 Average:6345.06 Median:6676.90 stddev:802.91 (12.0%) stddev-sans-first:774.59 21:53:54 INFO - PID 3501 | Values: 3538.4 3546.7 3591.5 3594.8 3617.5 6344.3 6349.8 6417.7 6418.5 6436.3 6311.1 6340.4 6449.7 6452.7 6460.0 5495.3 5531.8 5547.8 5558.4 5642.7 5617.6 5645.0 5668.0 5688.9 5693.3 5604.8 5615.1 5652.1 5672.8 5689.3 5538.1 5548.7 5607.5 5630.2 5631.1 5506.4 5559.0 5564.6 5572.5 5607.8 6199.6 6218.0 6263.9 6294.5 6323.3 6667.1 6745.0 6779.0 6822.7 6901.7 6895.7 6900.8 6904.0 6985.3 6999.8 6409.6 6411.5 6455.8 6463.5 6500.7 6888.4 6902.7 7024.9 7034.0 7106.5 6755.6 6790.3 6823.2 6833.4 6854.8 6860.3 6897.0 6932.9 6933.1 6971.9 6844.9 6860.1 6871.9 6879.6 6889.7 6815.5 6881.0 6884.6 6886.0 6890.4 5038.4 5051.7 5059.2 5103.1 5111.2 4434.6 4477.7 4529.1 4575.5 4581.3 6578.3 6585.1 6593.3 6603.4 6603.7 6870.4 6871.8 6916.0 6961.3 6975.7 6396.9 6525.2 6555.8 6629.2 6666.4 5814.3 5866.1 5870.9 5871.4 5884.0 7157.1 7158.4 7238.0 7239.9 7246.8 6884.2 6927.8 6937.6 6943.5 7018.1 6992.3 7062.5 7090.8 7097.5 7203.8 6803.5 6806.8 6810.3 6820.7 6883.8 6796.9 6930.4 6933.1 6999.7 7025.8 6720.6 6734.5 6769.5 6772.5 6772.9 6679.2 6683.9 6757.9 6763.5 6768.6 6580.4 6663.0 6672.3 6695.8 6732.9 6741.5 6780.5 6842.2 6849.2 6860.9 6556.2 6566.6 6584.5 6676.9 6685.4 21:53:54 INFO - PID 3501 | 21:53:54 INFO - PID 3501 | [#1] ext.html Cycles:180 Average:42513.80 Median:39252.30 stddev:63126.43 (160.8%) stddev-sans-first:63226.12 21:53:54 INFO - PID 3501 | Values: 884.1 934.4 948.8 977.5 986.4 35618.1 44055.9 44215.6 44452.8 44541.3 44783.0 45164.0 45199.5 45230.1 45259.0 7661.5 7773.8 7787.7 7792.3 7808.2 7631.4 7716.7 7717.2 7732.3 7746.4 7432.5 7724.4 7790.0 7941.1 7944.2 7851.3 7898.4 7901.9 7910.1 7915.0 7724.4 7747.8 7771.8 7774.1 7777.7 42031.6 42087.8 42215.8 42495.8 42533.4 3485.2 3530.2 3534.0 3541.3 3553.1 30064.1 30270.0 30364.4 30382.4 30397.5 5104.8 5104.9 5145.5 5173.2 5175.9 215256.0 216318.0 216842.0 217045.0 217696.0 53527.5 53566.2 53605.7 53779.2 53903.0 55497.7 55791.0 56025.7 56037.7 56055.1 15769.2 15898.8 15997.0 16130.3 16173.3 12627.0 12654.9 12669.5 12749.7 13273.6 15680.1 15775.8 15795.8 15892.4 15905.0 5426.5 6143.4 6222.8 6264.4 6484.5 1099.3 1107.2 1107.3 1120.1 1121.5 24956.0 25330.1 25497.6 25547.5 25978.0 1200.9 1210.4 1212.8 1217.9 1230.4 13340.3 13348.9 13491.3 13509.7 13758.0 278152.0 278705.0 278756.0 279364.0 280813.0 57407.0 57735.4 57915.1 57974.4 58211.0 1517.5 1520.1 1550.1 1560.1 1566.7 25034.6 25119.5 25147.0 25167.5 25428.4 41381.4 41418.0 41554.0 41622.4 41668.0 226105.0 227266.0 228216.0 228217.0 228564.0 29551.9 29818.0 29895.4 29970.4 29978.9 38008.6 38053.4 54285.0 55687.0 55761.4 38916.2 39073.8 39864.2 39955.4 40459.0 39219.8 39284.8 39398.0 39827.2 39984.0 45125.8 46223.8 46576.3 46934.0 47035.5 37908.2 38235.2 38350.2 38494.6 39089.8 28001.6 28325.3 28403.7 28672.0 28679.6 21:53:54 INFO - PID 3501 | 21:53:54 INFO - PID 3501 | [#2] jquery.html Cycles:180 Average:40213.44 Median:2340.20 stddev:98689.06 (4217.1%) stddev-sans-first:98921.09 21:53:54 INFO - PID 3501 | Values: 599.8 600.7 603.8 616.9 626.5 23885.6 24313.6 25230.6 25242.4 25250.2 25465.6 26229.7 26261.7 26266.7 26266.9 6575.7 6731.4 6743.0 6751.0 6751.1 6370.9 6631.7 6651.6 6651.6 6660.7 6630.7 6635.2 6635.9 6638.0 6640.3 6578.2 6611.9 6619.9 6633.1 6635.3 6170.9 6175.5 6177.3 6185.8 6193.2 29936.0 30729.2 31724.5 31821.5 31859.0 2289.3 2314.6 2339.5 2340.0 2340.4 19429.4 19849.9 19888.7 19895.8 19928.1 2453.5 2458.7 2474.2 2537.0 2546.4 159549.2 163535.0 163871.2 164435.2 164800.0 16404.6 16453.7 16558.7 16654.9 16884.6 26043.3 26064.3 26238.6 26255.6 26303.0 10225.3 10339.1 10411.5 10520.8 10701.3 8457.0 8828.3 9505.7 9527.0 9608.7 9581.9 10000.4 10038.6 10089.6 10191.2 2774.4 2834.6 2886.3 2922.7 3007.1 1063.1 1066.2 1075.2 1083.8 1094.1 21497.7 21651.0 21760.5 26323.7 27546.4 1129.1 1152.9 1156.5 1161.2 1168.6 11907.0 12049.4 12184.8 12186.6 12239.9 577338.0 578592.0 580744.0 582174.0 603960.0 131441.3 135459.0 136533.0 138808.0 140151.0 1341.8 1364.9 1387.8 1393.1 1395.8 19654.3 19666.0 19741.2 19922.3 20102.0 65815.0 66840.3 66925.0 67358.0 68892.0 84963.0 85141.6 85428.1 85806.6 85922.0 13278.5 13897.9 15617.3 15922.4 16046.6 23897.8 24104.5 24183.0 24481.8 24621.4 19802.9 19846.2 20156.7 20364.5 20399.3 17344.5 17793.3 17850.8 17874.1 18581.7 21589.0 21623.2 21663.6 22033.2 22070.8 15968.3 16126.6 16271.8 16273.2 16320.0 20128.0 20857.5 21183.4 21273.3 21413.0 21:53:54 INFO - PID 3501 | 21:53:54 INFO - PID 3501 | [#3] mootools.html Cycles:180 Average:10034.69 Median:2775.66 stddev:7261.77 (261.6%) stddev-sans-first:7253.61 21:53:54 INFO - PID 3501 | Values: 1466.3 1491.5 1507.2 1525.2 1526.7 15458.2 16050.8 16067.8 16077.4 16492.4 16630.7 16898.8 16921.0 16949.0 16950.7 2944.0 2980.2 3033.5 3035.7 3037.4 2842.0 2967.4 2981.4 2986.9 2992.2 2860.7 2889.7 2891.1 2904.0 2920.4 2817.4 2845.1 2847.3 2849.0 2852.0 2762.5 2788.8 2795.6 2796.0 2800.4 15682.4 16088.9 16124.9 16142.7 16168.6 8193.1 8490.7 8508.3 8516.8 8523.2 5588.3 5718.5 5949.9 5969.5 5991.5 1981.7 2002.8 2009.7 2068.5 2073.5 27965.7 28259.0 28260.0 28388.0 28411.1 7051.7 7163.7 7224.3 7378.8 7441.0 19169.3 19217.8 19322.0 19353.8 19355.8 8061.8 8129.6 8177.3 8182.9 8197.2 7454.1 7507.0 7562.0 7780.5 7840.2 5737.2 5832.4 5855.1 5915.5 5949.4 1609.9 1657.2 1715.6 1739.2 1771.1 846.4 858.5 860.3 864.8 928.1 8121.9 8995.2 9193.6 9205.0 9224.1 1017.7 1027.4 1043.6 1050.7 1082.6 5103.4 5193.7 5385.4 5430.2 5490.6 22858.3 23006.1 23529.5 23612.5 23860.3 23384.0 23577.5 23582.3 23752.2 23777.6 11016.4 11864.1 12145.8 12150.8 12197.8 8554.8 8685.5 8882.3 8946.6 9007.6 20151.8 20668.4 20677.8 20860.4 21007.7 20677.5 20961.4 21006.2 21039.9 21232.2 12230.4 12375.3 12469.1 12481.8 12483.3 14620.8 14816.1 15161.2 15177.3 15205.8 10638.3 12035.9 12109.0 12111.3 12409.1 11212.6 11222.2 11250.2 11441.5 11677.4 10109.8 10208.1 10239.7 10250.9 10304.8 7646.3 7673.7 7737.0 7739.4 7786.1 7634.7 7802.2 7828.7 7837.6 7853.0 21:53:54 INFO - PID 3501 | 21:53:54 INFO - PID 3501 | [#4] prototype.html Cycles:180 Average:5696.72 Median:6400.28 stddev:1605.64 (25.1%) stddev-sans-first:1562.77 21:53:54 INFO - PID 3501 | Values: 538.8 557.5 558.9 559.2 565.1 6251.9 6290.9 6325.6 6358.1 6382.2 6156.8 6198.6 6254.4 6290.7 6368.5 5024.6 5047.7 5047.9 5056.3 5072.3 4770.4 4868.4 4974.0 4994.5 4995.8 4826.9 4849.2 4870.0 4874.3 4888.8 4843.2 4872.4 4905.6 4914.7 4934.3 4444.5 4462.9 4467.2 4470.2 4494.7 6144.7 6180.2 6192.0 6240.8 6275.2 5848.0 5865.7 5900.4 5924.5 5934.0 6687.6 6697.2 6703.3 6751.4 6802.5 5035.3 5098.9 5118.4 5120.6 5126.2 6744.4 6834.5 6898.3 7024.3 7081.1 5099.6 5222.2 5233.7 5237.9 5254.6 5297.7 5298.2 5310.2 5312.9 5319.5 5846.2 5853.4 5861.9 5872.9 5891.6 6708.2 6722.6 6730.8 6735.9 6746.6 1843.8 1865.3 1865.7 1866.9 1867.5 1265.4 1297.7 1310.6 1321.5 1323.8 6389.5 6411.1 6420.6 6459.5 6480.0 6723.5 6818.9 6829.2 6845.8 6848.9 6424.4 6435.3 6462.8 6497.0 6507.9 3578.9 3681.4 3694.4 3695.4 3699.3 7065.9 7093.8 7165.7 7199.8 7207.5 6982.9 6987.7 7058.2 7092.9 7121.8 7010.7 7104.2 7119.3 7158.7 7170.5 6820.9 6864.6 6894.0 6928.1 6931.6 6668.2 6760.7 6819.1 6823.7 6842.8 6931.9 6959.3 6985.8 6986.9 7056.5 5259.2 5282.0 5291.8 5299.9 5316.7 6424.4 6595.4 6746.9 6841.2 6852.6 6581.6 6609.4 6631.2 6660.9 6712.7 6678.8 6692.5 6695.3 6697.2 6757.6 6604.6 6644.7 6677.1 6715.2 6730.9 6655.2 6689.9 6721.6 6723.3 6757.2 6543.2 6543.5 6583.7 6608.7 6623.2 21:53:54 INFO - PID 3501 | 21:53:54 INFO - PID 3501 | [#5] yui.html Cycles:180 Average:25894.50 Median:17092.00 stddev:37938.69 (222.0%) stddev-sans-first:37998.89 21:53:54 INFO - PID 3501 | Values: 951.9 959.4 959.9 980.5 995.0 19128.2 19566.6 19577.2 19593.3 21436.8 21357.5 21502.0 21750.6 21778.8 21807.9 1048.0 1084.0 1086.8 1087.1 1087.8 1029.2 1068.9 1069.1 1073.8 1074.0 1123.0 1126.7 1127.3 1129.6 1129.9 1114.6 1115.2 1116.0 1116.8 1121.2 823.0 824.2 824.6 829.6 831.7 18909.0 19348.0 19439.8 19451.6 20042.8 21358.4 21387.1 21781.0 21796.6 22019.2 16695.2 16986.0 17024.7 17069.7 17072.0 3683.5 3706.7 3709.1 3709.9 3710.8 143775.0 146834.0 147355.3 148541.0 149903.0 15039.4 15041.7 15268.2 15298.2 15314.3 31730.8 32047.5 32115.8 32267.8 32300.4 8876.2 8910.9 9038.2 9114.0 9114.0 4551.4 4574.0 4620.9 4646.6 4648.6 5777.4 5820.9 5832.7 5877.0 5880.4 1038.2 1042.5 1068.8 1069.3 1073.2 708.6 721.5 721.7 729.2 745.1 12011.6 12382.4 12446.3 12535.6 13080.2 721.5 730.9 736.0 736.9 737.9 6018.8 6052.3 6195.3 6276.7 6316.9 129365.0 129742.0 130697.0 131339.0 133241.0 117137.0 117523.0 117892.0 120744.4 122891.0 102720.0 102902.5 103107.0 103392.0 105284.0 11475.6 11567.8 11598.1 11969.9 12043.9 49985.3 50034.0 50728.5 51009.2 51305.7 61191.0 61248.7 61384.4 61493.0 64936.7 16243.4 16488.1 16532.5 16671.7 16677.9 17950.1 18024.6 18046.0 18196.0 18202.6 16426.7 16572.1 16652.4 16682.8 16739.0 17068.7 17112.0 17117.3 17162.4 17279.8 15882.5 16031.0 16065.1 16177.6 16363.2 12599.5 12741.4 12882.3 12985.5 13120.6 11935.5 12004.9 12285.5 12406.1 12411.9 21:53:54 INFO - PID 3501 | -------- Summary: end -------- 21:53:54 INFO - PID 3501 | 21:53:54 INFO - TEST-INFO | 3501: exit 0 21:53:54 INFO - TEST-OK | dromaeo_css | took 1108963ms 21:53:54 INFO - TEST-START | kraken 21:53:54 INFO - Initialising browser for kraken test... 21:53:54 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:36552/getInfo.html -profile /tmp/tmpHb5vy_/profile 21:53:54 INFO - TEST-INFO | started process 3855 (/builds/slave/test/build/application/firefox/firefox http://localhost:36552/getInfo.html) 21:54:00 INFO - TEST-INFO | 3855: exit 0 21:54:00 INFO - Browser initialized. 21:54:00 INFO - Running cycle 1/1 for kraken test... 21:54:00 INFO - TEST-INFO | started process 4018 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpHb5vy_/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 21:54:00 INFO - PID 4018 | 1503377640708 addons.xpi WARN Exception running bootstrap method startup on clicktoplay-rollout@mozilla.org: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getStringPref]" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/clicktoplay-rollout@mozilla.org.xpi!/bootstrap.js :: defineCohort :: line 49" data: no] Stack trace: defineCohort()@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/clicktoplay-rollout@mozilla.org.xpi!/bootstrap.js:49 < startup()@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/clicktoplay-rollout@mozilla.org.xpi!/bootstrap.js:40 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4395 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2229 < callProvider()@resource://gre/modules/AddonManager.jsm:269 < _startProvider()@resource://gre/modules/AddonManager.jsm:739 < startup()@resource://gre/modules/AddonManager.jsm:906 < startup()@resource://gre/modules/AddonManager.jsm:3090 < observe()@jar:file:///builds/slave/test/build/application/firefox/omni.ja!/components/addonManager.js:65 21:54:11 INFO - PID 4018 | RSS: Main: 150880256 21:54:11 INFO - PID 4018 | 21:54:14 INFO - PID 4018 | Cycle 1(1): loaded http://localhost:36552/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:36552/tests/kraken/driver.html?testName=audio-beat-detection) 21:54:14 INFO - PID 4018 | RSS: Main: 157585408 21:54:14 INFO - PID 4018 | 21:54:17 INFO - PID 4018 | Cycle 1(1): loaded http://localhost:36552/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:36552/tests/kraken/driver.html?testName=audio-dft) 21:54:18 INFO - PID 4018 | RSS: Main: 158134272 21:54:18 INFO - PID 4018 | 21:54:20 INFO - PID 4018 | Cycle 1(1): loaded http://localhost:36552/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:36552/tests/kraken/driver.html?testName=audio-fft) 21:54:21 INFO - PID 4018 | RSS: Main: 158035968 21:54:21 INFO - PID 4018 | 21:54:23 INFO - PID 4018 | Cycle 1(1): loaded http://localhost:36552/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:36552/tests/kraken/driver.html?testName=audio-oscillator) 21:54:24 INFO - PID 4018 | RSS: Main: 158289920 21:54:24 INFO - PID 4018 | 21:54:26 INFO - PID 4018 | Cycle 1(1): loaded http://localhost:36552/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:36552/tests/kraken/driver.html?testName=imaging-gaussian-blur) 21:54:26 INFO - PID 4018 | RSS: Main: 157868032 21:54:26 INFO - PID 4018 | 21:54:29 INFO - PID 4018 | Cycle 1(1): loaded http://localhost:36552/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:36552/tests/kraken/driver.html?testName=imaging-darkroom) 21:54:30 INFO - PID 4018 | RSS: Main: 157888512 21:54:30 INFO - PID 4018 | 21:54:33 INFO - PID 4018 | Cycle 1(1): loaded http://localhost:36552/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:36552/tests/kraken/driver.html?testName=imaging-desaturate) 21:54:34 INFO - PID 4018 | RSS: Main: 160866304 21:54:34 INFO - PID 4018 | 21:54:37 INFO - PID 4018 | Cycle 1(1): loaded http://localhost:36552/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:36552/tests/kraken/driver.html?testName=json-parse-financial) 21:54:37 INFO - PID 4018 | RSS: Main: 161968128 21:54:37 INFO - PID 4018 | 21:54:39 INFO - PID 4018 | Cycle 1(1): loaded http://localhost:36552/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:36552/tests/kraken/driver.html?testName=json-stringify-tinderbox) 21:54:40 INFO - PID 4018 | RSS: Main: 162029568 21:54:40 INFO - PID 4018 | 21:54:41 INFO - PID 4018 | Cycle 1(1): loaded http://localhost:36552/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:36552/tests/kraken/driver.html?testName=stanford-crypto-aes) 21:54:42 INFO - PID 4018 | RSS: Main: 161796096 21:54:42 INFO - PID 4018 | 21:54:44 INFO - PID 4018 | Cycle 1(1): loaded http://localhost:36552/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:36552/tests/kraken/driver.html?testName=stanford-crypto-ccm) 21:54:45 INFO - PID 4018 | RSS: Main: 161996800 21:54:45 INFO - PID 4018 | 21:54:47 INFO - PID 4018 | Cycle 1(1): loaded http://localhost:36552/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:36552/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 21:54:48 INFO - PID 4018 | RSS: Main: 162078720 21:54:48 INFO - PID 4018 | 21:54:50 INFO - PID 4018 | Cycle 1(1): loaded http://localhost:36552/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:36552/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 21:54:51 INFO - PID 4018 | RSS: Main: 161976320 21:54:51 INFO - PID 4018 | 21:54:53 INFO - PID 4018 | Cycle 1(1): loaded http://localhost:36552/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:36552/tests/kraken/driver.html?testName=ai-astar) 21:54:53 INFO - PID 4018 | RSS: Main: 163483648 21:54:53 INFO - PID 4018 | 21:54:53 INFO - PID 4018 | __start_tp_report 21:54:53 INFO - PID 4018 | _x_x_mozilla_page_load 21:54:53 INFO - PID 4018 | _x_x_mozilla_page_load_details 21:54:53 INFO - PID 4018 | |i|pagename|runs| 21:54:53 INFO - PID 4018 | |0;ai-astar;103;106;93;104;107;106;109;105;108;107 21:54:53 INFO - PID 4018 | |1;audio-beat-detection;139;114;143;174;113;139;143;112;162;112 21:54:53 INFO - PID 4018 | |2;audio-dft;162;162;163;163;161;162;162;162;163;163 21:54:53 INFO - PID 4018 | |3;audio-fft;75;75;73;175;75;74;74;74;143;76 21:54:53 INFO - PID 4018 | |4;audio-oscillator;79;77;134;78;79;77;127;80;77;125 21:54:53 INFO - PID 4018 | |5;imaging-gaussian-blur;114;114;114;114;113;113;115;114;114;114 21:54:53 INFO - PID 4018 | |6;imaging-darkroom;148;148;149;148;149;148;148;148;149;148 21:54:53 INFO - PID 4018 | |7;imaging-desaturate;94;94;95;94;94;94;93;94;94;93 21:54:53 INFO - PID 4018 | |8;json-parse-financial;45;45;45;45;46;47;45;49;46;47 21:54:53 INFO - PID 4018 | |9;json-stringify-tinderbox;54;54;55;53;57;53;53;54;54;54 21:54:53 INFO - PID 4018 | |10;stanford-crypto-aes;75;72;73;73;72;71;74;74;72;73 21:54:53 INFO - PID 4018 | |11;stanford-crypto-ccm;116;115;124;121;113;114;113;121;113;146 21:54:53 INFO - PID 4018 | |12;stanford-crypto-pbkdf2;154;156;168;156;155;155;156;153;157;163 21:54:53 INFO - PID 4018 | |13;stanford-crypto-sha256-iterative;61;61;59;58;60;60;59;57;59;57 21:54:53 INFO - PID 4018 | __end_tp_report 21:54:53 INFO - PID 4018 | __start_cc_report 21:54:53 INFO - PID 4018 | _x_x_mozilla_cycle_collect,821 21:54:53 INFO - PID 4018 | __end_cc_report 21:54:53 INFO - PID 4018 | __startTimestamp1503377693439__endTimestamp 21:54:53 INFO - PID 4018 | 21:54:53 INFO - PID 4018 | ------- Summary: start ------- 21:54:53 INFO - PID 4018 | Number of tests: 14 21:54:53 INFO - PID 4018 | 21:54:53 INFO - PID 4018 | [#0] ai-astar Cycles:10 Average:104.80 Median:107.00 stddev:4.52 (4.2%) stddev-sans-first:4.74 21:54:53 INFO - PID 4018 | Values: 103.0 106.0 93.0 104.0 107.0 106.0 109.0 105.0 108.0 107.0 21:54:53 INFO - PID 4018 | 21:54:53 INFO - PID 4018 | [#1] audio-beat-detection Cycles:10 Average:135.10 Median:141.00 stddev:22.09 (15.7%) stddev-sans-first:23.39 21:54:53 INFO - PID 4018 | Values: 139.0 114.0 143.0 174.0 113.0 139.0 143.0 112.0 162.0 112.0 21:54:53 INFO - PID 4018 | 21:54:53 INFO - PID 4018 | [#2] audio-dft Cycles:10 Average:162.30 Median:162.50 stddev:0.67 (0.4%) stddev-sans-first:0.71 21:54:53 INFO - PID 4018 | Values: 162.0 162.0 163.0 163.0 161.0 162.0 162.0 162.0 163.0 163.0 21:54:53 INFO - PID 4018 | 21:54:53 INFO - PID 4018 | [#3] audio-fft Cycles:10 Average:91.40 Median:74.50 stddev:36.43 (48.9%) stddev-sans-first:38.15 21:54:53 INFO - PID 4018 | Values: 75.0 75.0 73.0 175.0 75.0 74.0 74.0 74.0 143.0 76.0 21:54:53 INFO - PID 4018 | 21:54:53 INFO - PID 4018 | [#4] audio-oscillator Cycles:10 Average:93.30 Median:77.50 stddev:24.53 (31.6%) stddev-sans-first:25.46 21:54:53 INFO - PID 4018 | Values: 79.0 77.0 134.0 78.0 79.0 77.0 127.0 80.0 77.0 125.0 21:54:53 INFO - PID 4018 | 21:54:53 INFO - PID 4018 | [#5] imaging-gaussian-blur Cycles:10 Average:113.90 Median:114.00 stddev:0.57 (0.5%) stddev-sans-first:0.60 21:54:53 INFO - PID 4018 | Values: 114.0 114.0 114.0 114.0 113.0 113.0 115.0 114.0 114.0 114.0 21:54:53 INFO - PID 4018 | 21:54:53 INFO - PID 4018 | [#6] imaging-darkroom Cycles:10 Average:148.30 Median:148.00 stddev:0.48 (0.3%) stddev-sans-first:0.50 21:54:53 INFO - PID 4018 | Values: 148.0 148.0 149.0 148.0 149.0 148.0 148.0 148.0 149.0 148.0 21:54:53 INFO - PID 4018 | 21:54:53 INFO - PID 4018 | [#7] imaging-desaturate Cycles:10 Average:93.90 Median:94.00 stddev:0.57 (0.6%) stddev-sans-first:0.60 21:54:53 INFO - PID 4018 | Values: 94.0 94.0 95.0 94.0 94.0 94.0 93.0 94.0 94.0 93.0 21:54:53 INFO - PID 4018 | 21:54:53 INFO - PID 4018 | [#8] json-parse-financial Cycles:10 Average:46.00 Median:46.00 stddev:1.33 (2.9%) stddev-sans-first:1.36 21:54:53 INFO - PID 4018 | Values: 45.0 45.0 45.0 45.0 46.0 47.0 45.0 49.0 46.0 47.0 21:54:53 INFO - PID 4018 | 21:54:53 INFO - PID 4018 | [#9] json-stringify-tinderbox Cycles:10 Average:54.10 Median:54.00 stddev:1.20 (2.2%) stddev-sans-first:1.27 21:54:53 INFO - PID 4018 | Values: 54.0 54.0 55.0 53.0 57.0 53.0 53.0 54.0 54.0 54.0 21:54:53 INFO - PID 4018 | 21:54:53 INFO - PID 4018 | [#10] stanford-crypto-aes Cycles:10 Average:72.90 Median:73.00 stddev:1.20 (1.6%) stddev-sans-first:1.00 21:54:53 INFO - PID 4018 | Values: 75.0 72.0 73.0 73.0 72.0 71.0 74.0 74.0 72.0 73.0 21:54:53 INFO - PID 4018 | 21:54:53 INFO - PID 4018 | [#11] stanford-crypto-ccm Cycles:10 Average:119.60 Median:118.50 stddev:10.09 (8.5%) stddev-sans-first:10.62 21:54:53 INFO - PID 4018 | Values: 116.0 115.0 124.0 121.0 113.0 114.0 113.0 121.0 113.0 146.0 21:54:53 INFO - PID 4018 | 21:54:53 INFO - PID 4018 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:157.30 Median:156.00 stddev:4.62 (3.0%) stddev-sans-first:4.74 21:54:53 INFO - PID 4018 | Values: 154.0 156.0 168.0 156.0 155.0 155.0 156.0 153.0 157.0 163.0 21:54:53 INFO - PID 4018 | 21:54:53 INFO - PID 4018 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:59.10 Median:59.50 stddev:1.45 (2.4%) stddev-sans-first:1.36 21:54:53 INFO - PID 4018 | Values: 61.0 61.0 59.0 58.0 60.0 60.0 59.0 57.0 59.0 57.0 21:54:53 INFO - PID 4018 | -------- Summary: end -------- 21:54:53 INFO - PID 4018 | 21:54:53 INFO - PID 4018 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 21:54:53 INFO - TEST-INFO | 4018: exit 0 21:54:54 INFO - TEST-OK | kraken | took 59183ms 21:54:54 INFO - SUITE-END | took 1168s 21:54:54 INFO - Completed test suite (00:19:28) 21:54:54 INFO - javascript benchmark 21:54:54 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 9018.417593429587, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3538.3807833629107, 3546.680798728112, 3591.515145455363, 3594.8022858742765, 3617.457381392791, 6344.270408902978, 6349.841253968647, 6417.679116044191, 6418.486521078315, 6436.324185960477, 6311.147995020671, 6340.3976622220935, 6449.709763060662, 6452.741890324393, 6460.0, 5495.313085864267, 5531.806386776464, 5547.805826796063, 5558.388577256498, 5642.746076426571, 5617.550595952334, 5644.971775141119, 5668.0, 5688.857778555528, 5693.259876216086, 5604.8038318658855, 5615.073512870413, 5652.123920792264, 5672.829815105553, 5689.345725241588, 5538.0585300499015, 5548.667079975174, 5607.523360514361, 5630.239917611111, 5631.127175287796, 5506.366767821712, 5559.027170245191, 5564.582656300753, 5572.5263352615075, 5607.803726869581, 6199.597026193328, 6217.968910155466, 6263.937360626381, 6294.496440284766, 6323.30443651198, 6667.133272674521, 6744.966275168593, 6779.0, 6822.692978815916, 6901.723931042802, 6895.724171033153, 6900.7929762107215, 6904.0, 6985.301469853025, 6999.82500437488, 6409.583377080475, 6411.519136064767, 6455.7740479083, 6463.48292136628, 6500.707468163897, 6888.380045795802, 6902.689378977958, 7024.859502810017, 7034.015237866703, 7106.467014973898, 6755.560888542229, 6790.287019862942, 6823.215330236987, 6833.384995350442, 6854.794356169323, 6860.348266914635, 6896.965515172493, 6932.861342773116, 6933.0986971693355, 6971.930280697229, 6844.897326540006, 6860.1081859358965, 6871.896921546181, 6879.552829066195, 6889.689963951634, 6815.488838337144, 6881.036654868322, 6884.621345825927, 6886.035954966309, 6890.414314783299, 5038.445770965191, 5051.6716413433005, 5059.241113832954, 5103.132467480537, 5111.233315002779, 4434.623057040188, 4477.70894891831, 4529.116822219701, 4575.519570445122, 4581.312803079498, 6578.27638959714, 6585.1439312889015, 6593.274739778621, 6603.438707709803, 6603.669816509156, 6870.381665650116, 6871.793846184622, 6915.965420172867, 6961.26906674802, 6975.720971161199, 6396.9360306397275, 6525.184351956005, 6555.80332590023, 6629.171353580802, 6666.366695163952, 5814.30228372598, 5866.120081987736, 5870.941290587125, 5871.4128587141795, 5884.029135192731, 7157.141143062761, 7158.427325813923, 7237.963810181021, 7239.9276007240305, 7246.782596522113, 6884.242733299233, 6927.826804329831, 6937.618430986142, 6943.51395402317, 7018.087648605649, 6992.3357281058215, 7062.470314726519, 7090.822729431806, 7097.538659987084, 7203.783886483414, 6803.489738269748, 6806.82982925411, 6810.28492008332, 6820.727170913108, 6883.827904302432, 6796.864062718817, 6930.410915072275, 6933.1333583302085, 6999.685014174476, 7025.789226323219, 6720.596764194164, 6734.528582999339, 6769.458443324621, 6772.52592318533, 6772.898406524005, 6679.198496180489, 6683.933160668331, 6757.932420675927, 6763.526553141233, 6768.593884366954, 6580.374864387875, 6662.966685166543, 6672.266050734513, 6695.765648202289, 6732.8653426930205, 6741.528093033637, 6780.525363224527, 6842.213145488333, 6849.212340580698, 6860.897086543606, 6556.18047744032, 6566.573172743756, 6584.473242140713, 6676.89984650221, 6685.431738302299], "value": 6284.622012652571, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [884.1158841158843, 934.434667026449, 948.7865230323176, 977.4819345746756, 986.3687240166299, 35618.10954726133, 44055.89860253495, 44215.55784442154, 44452.777736111355, 44541.331880021826, 44783.0, 45164.0, 45199.548004519944, 45230.09539809202, 45259.0, 7661.502002369856, 7773.766786996399, 7787.7274295399675, 7792.337651299618, 7808.219178082203, 7631.3894888408795, 7716.729914452995, 7717.228277172294, 7732.342750866183, 7746.38028957682, 7432.479726419126, 7724.42066844986, 7789.961050194769, 7941.126476087626, 7944.245296696833, 7851.2933835954755, 7898.447108702393, 7901.920980790204, 7910.050793904711, 7914.960425197894, 7724.382049436088, 7747.8450430991625, 7771.844563108706, 7774.067111946602, 7777.650005749755, 42031.579684203076, 42087.789561052305, 42215.78892105551, 42495.787521062506, 42533.36199956972, 3485.163560745428, 3530.223350862806, 3534.0458076319505, 3541.3271478419274, 3553.147244661263, 30064.098077057723, 30270.0, 30364.392712145855, 30382.392352153056, 30397.544036839467, 5104.795808167669, 5104.948950510485, 5145.511176438233, 5173.224016397533, 5175.948240517584, 215256.0, 216318.0, 216842.0, 217045.0, 217696.0, 53527.46472535225, 53566.196507051645, 53605.73197133989, 53779.19331210035, 53903.0, 55497.72251138799, 55791.0, 56025.71987140038, 56037.7198114015, 56055.07614466948, 15769.211539422982, 15898.76151857723, 15997.0, 16130.274137663833, 16173.272202750904, 12627.0, 12654.87345126537, 12669.493220271086, 12749.745005099847, 13273.601791946257, 15680.137592432526, 15775.763363549557, 15795.842041579437, 15892.443764468188, 15905.0, 5426.457354264621, 6143.354947730513, 6222.782202622977, 6264.436200741955, 6484.5460817742305, 1099.3349023840622, 1107.1696227829127, 1107.2858006585707, 1120.1151090638468, 1121.5121422181378, 24956.0, 25330.11344602967, 25497.61753573698, 25547.48905021889, 25978.0, 1200.885915837994, 1210.4189988805422, 1212.7877621416217, 1217.945192466341, 1230.366361323919, 13340.332983350794, 13348.93325533366, 13491.257980810953, 13509.662258443617, 13758.0, 278152.0, 278705.0, 278756.0, 279364.0, 280813.0, 57407.0, 57735.42264577385, 57915.13127303094, 57974.42025579775, 58211.0, 1517.529565834595, 1520.1335238913714, 1550.0699580251985, 1560.0639616230171, 1566.7336552786053, 25034.62448063317, 25119.497610047332, 25147.0, 25167.496650067264, 25428.364290892136, 41381.37927931143, 41418.0, 41554.0, 41622.37566436567, 41668.0, 226105.0, 227266.0, 228216.0, 228217.0, 228564.0, 29551.85224073866, 29818.0, 29895.402091958473, 29970.400591988473, 29978.85010574933, 38008.61991380051, 38053.42919856149, 54285.0, 55687.0, 55761.442385575625, 38916.2216755669, 39073.8046309778, 39864.202715944935, 39955.40066899057, 40459.0, 39219.80390098031, 39284.80357598194, 39398.0, 39827.20345593014, 39984.00039998907, 45125.77437112793, 46223.76888115538, 46576.301355479016, 46934.0, 47035.529644703114, 37908.241835163695, 38235.23529529338, 38350.23299533938, 38494.615053850226, 39089.80455097706, 28001.579976300778, 28325.291867703472, 28403.72770178422, 28672.01115371226, 28679.587374167484], "value": 18583.57712072025, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [599.8080614203456, 600.7116584039661, 603.7735849056604, 616.8889599872022, 626.4518546272012, 23885.641715374284, 24313.63529547056, 25230.621540676857, 25242.368940776465, 25250.242492725225, 25465.61801572978, 26229.73770262297, 26261.686915654238, 26266.737332626668, 26266.868665656693, 6575.736970521185, 6731.427828634572, 6743.0, 6751.0, 6751.122354093961, 6370.936290637103, 6631.668416579176, 6651.567648102858, 6651.567648102882, 6660.733570657168, 6630.73477060917, 6635.236947751022, 6635.933640663604, 6638.0, 6640.335966403345, 6578.177727784027, 6611.9338806611795, 6619.933800662004, 6633.071370008202, 6635.270120286765, 6170.876582468325, 6175.536834737412, 6177.25872895251, 6185.752569897199, 6193.194884664965, 29936.0, 30729.231769205948, 31724.524132138038, 31821.52267715986, 31859.0, 2289.267434420986, 2314.5833749925005, 2339.508703172336, 2339.9883000584887, 2340.403197184724, 19429.417117486497, 19849.900750496156, 19888.70166947497, 19895.801041989685, 19928.103235354443, 2453.472503411775, 2458.7172475165225, 2474.2206205045354, 2536.9746302536923, 2546.4397832476825, 159549.20225398915, 163535.0, 163871.1806440972, 164435.1778241101, 164800.0, 16404.589885252724, 16453.670926581643, 16558.6688266234, 16654.916725416297, 16884.57788555296, 26043.348916277246, 26064.34839129037, 26238.606420903703, 26255.606165907528, 26303.0, 10225.335353202017, 10339.121174700234, 10411.53148108337, 10520.789584208273, 10701.250912436055, 8457.0, 8828.337874659297, 9505.66730164441, 9526.951705383375, 9608.66369677058, 9581.856272155923, 10000.399976001463, 10038.64864729731, 10089.646862359783, 10191.184705223564, 2774.3631257558136, 2834.5543772676083, 2886.336142687194, 2922.6638936522145, 3007.1129016940054, 1063.1388575253914, 1066.2376400873413, 1075.2150929821119, 1083.7832433513329, 1094.0809719835377, 21497.67753483699, 21651.0, 21760.455988600093, 26323.73676263251, 27546.389698373398, 1129.1418521923233, 1152.8962393384636, 1156.5142640091183, 1161.2103769436694, 1168.5559487394735, 11906.987906028085, 12049.397530123459, 12184.81722774159, 12186.573469928688, 12239.877601223874, 577338.0, 578592.0, 580744.0, 582174.0, 603960.0, 131441.3427932854, 135459.0, 136533.0, 138808.0, 140151.0, 1341.7987301904598, 1364.911280766747, 1387.833459984788, 1393.0805668259104, 1395.8045873577505, 19654.312099076462, 19666.0, 19741.21035158635, 19922.30271940475, 20102.0, 65815.0, 66840.33159668536, 66925.0, 67358.0, 68892.0, 84963.0, 85141.57429212815, 85428.14571854201, 85806.57096714477, 85922.0, 13278.53525126616, 13897.861021390061, 15617.297221624827, 15922.442714504938, 16046.598835029217, 23897.76102239025, 24104.517909642058, 24183.0, 24481.775911204724, 24621.38446538851, 19802.90098549498, 19846.206151754344, 20156.697649534974, 20364.490887727927, 20399.286024989055, 17344.47966561005, 17793.288268469118, 17850.821491785435, 17874.106294684952, 18581.721274181167, 21589.0, 21623.2431864884, 21663.566728665653, 22033.228836990627, 22070.779292206873, 15968.28142733603, 16126.596835079217, 16271.837281627033, 16273.186340682681, 16320.0, 20128.0, 20857.478563035438, 21183.411244157058, 21273.255436059662, 21413.0], "value": 13046.527960712107, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1466.3035058347284, 1491.5450787509812, 1507.2313120308638, 1525.1535397854193, 1526.7480865657164, 15458.227088645564, 16050.83949160508, 16067.758983615255, 16077.437289694863, 16492.42276520322, 16630.66738665226, 16898.83101168988, 16921.0, 16949.0, 16950.745738813926, 2944.0, 2980.1655536449775, 3033.453978283908, 3035.711607397294, 3037.4228896509703, 2841.957370639442, 2967.421352836198, 2981.38881529286, 2986.865591048408, 2992.2369795702066, 2860.728230818069, 2889.7110288971144, 2891.103757835078, 2904.0, 2920.3575213453005, 2817.4083442477104, 2845.075350511092, 2847.3451106245575, 2848.985755071222, 2851.985740071307, 2762.5165596020615, 2788.8047836651444, 2795.566687163493, 2796.0353677981066, 2800.4399120175844, 15682.372705091782, 16088.919555402264, 16124.919375403048, 16142.677146457005, 16168.595785105348, 8193.09875913649, 8490.66037358505, 8508.276796472308, 8516.78708032298, 8523.232909038215, 5588.30146231721, 5718.456746609066, 5949.851253718648, 5969.492593129589, 5991.460768530853, 1981.7126516655148, 2002.8297594704468, 2009.7487814023248, 2068.5449201175716, 2073.5438203595186, 27965.720342796514, 28259.0, 28260.0, 28388.006419775415, 28411.147665570064, 7051.682674279669, 7163.7492687755675, 7224.277572242734, 7378.778636640909, 7441.0, 19169.32907348236, 19217.8078219216, 19322.033898305308, 19353.8064619352, 19355.8064419354, 8061.798455038554, 8129.634166462523, 8177.2640462357485, 8182.918170818216, 8197.221263979913, 7454.105507339045, 7507.024086868672, 7562.016937798161, 7780.533168009937, 7840.215978402228, 5737.225474560881, 5832.358440571534, 5855.121731740273, 5915.467607915309, 5949.375315591887, 1609.929396951038, 1657.1714142928536, 1715.5882588178747, 1739.1565090930887, 1771.1055916761952, 846.3821410370464, 858.5363903492045, 860.3418384935529, 864.7881269089113, 928.1414691410419, 8121.918780812234, 8995.23540499065, 9193.632254709737, 9205.0, 9224.123708247706, 1017.7150397888603, 1027.3887037212846, 1043.598214687351, 1050.669039252633, 1082.5886163257912, 5103.387593488805, 5193.662411943212, 5385.434529374359, 5430.185472179126, 5490.56075513958, 22858.314250572508, 23006.079756809875, 23529.529409411716, 23612.527749444915, 23860.284191474282, 23384.0, 23577.52844943126, 23582.29253122409, 23752.168674096323, 23777.643335349985, 11016.394098324668, 11864.11019173548, 12145.846144616247, 12150.756984860252, 12197.817032744517, 8554.828903421772, 8685.478871267744, 8882.289416846766, 8946.597403116744, 9007.594658240527, 20151.79848201558, 20668.379948601567, 20677.793222067587, 20860.37418877436, 21007.68488472705, 20677.483062923548, 20961.37115886526, 21006.159753609685, 21039.8948005259, 21232.150713971267, 12230.449629766505, 12375.257484550955, 12469.127161098999, 12481.812772808596, 12483.25100493937, 14620.780688289471, 14816.111033338035, 15161.241937903282, 15177.317020733874, 15205.771913421086, 10638.255322127377, 12035.939820300842, 12109.0, 12111.333876636869, 12409.131360805019, 11212.607558735404, 11222.214444989098, 11250.156238281998, 11441.542338306375, 11677.416129193336, 10109.848352274868, 10208.081272685495, 10239.692809215736, 10250.948745256226, 10304.845427318745, 7646.311831935152, 7673.731419400294, 7736.994190755391, 7739.41954353437, 7786.104597971079, 7634.732784352521, 7802.180771018961, 7828.725994590162, 7837.568933708701, 7853.0], "value": 7141.628257304058, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [538.7898719499399, 557.4509108528101, 558.868665863522, 559.1835919557446, 565.0676383966455, 6251.937480625193, 6290.905636415458, 6325.557210995232, 6358.14165087713, 6382.234131904178, 6156.846078848031, 6198.628082315065, 6254.374562543743, 6290.748370065204, 6368.522360822945, 5024.598032157437, 5047.697138171703, 5047.949520504784, 5056.266841308008, 5072.289879416885, 4770.4275486941615, 4868.440129385113, 4973.950260497385, 4994.450610432849, 4995.800167993294, 4826.927596086061, 4849.151398505265, 4870.0, 4874.317595536628, 4888.804447822083, 4843.15244832153, 4872.3909511311085, 4905.583025442842, 4914.729689867056, 4934.25986102084, 4444.466664000341, 4462.910741785178, 4467.218236808578, 4470.2177119004045, 4494.662900282459, 6144.692765361714, 6180.196574445338, 6192.009278515416, 6240.751849630092, 6275.24697036354, 5847.970760146214, 5865.736041878083, 5900.43945825146, 5924.496417804492, 5934.0, 6687.565308254996, 6697.162854643169, 6703.3296670332575, 6751.42612877906, 6802.455803535705, 5035.3171550458055, 5098.923516147261, 5118.360204974379, 5120.5903527718065, 5126.179811230222, 6744.393004629558, 6834.521583489158, 6898.344657257603, 7024.332688394595, 7081.052710504601, 5099.59203263738, 5222.206119725928, 5233.743901463621, 5237.895242095136, 5254.5549973757525, 5297.655652382583, 5298.231756395301, 5310.176922577007, 5312.92030619541, 5319.521243088063, 5846.181534585162, 5853.356130825606, 5861.882762344814, 5872.884152011105, 5891.617044892068, 6708.188789026935, 6722.588256466197, 6730.754810360001, 6735.932640673628, 6746.595204287661, 1843.7787465504225, 1865.2538984406347, 1865.7108148237046, 1866.8599855010928, 1867.5424520992171, 1265.373640048182, 1297.7274772297924, 1310.6068179546098, 1321.4714114354335, 1323.781576039962, 6389.488840892717, 6411.070394792729, 6420.6147631142285, 6459.450946669585, 6479.967600161969, 6723.462123030145, 6818.874885643813, 6829.180498340231, 6845.828854278583, 6848.863022739517, 6424.421802037839, 6435.324290949477, 6462.806115816533, 6497.0, 6507.8698426031215, 3578.946315805213, 3681.4293784463453, 3694.408894576855, 3695.427208782643, 3699.3341198583976, 7065.92934070663, 7093.751718689924, 7165.713371465083, 7199.784006479814, 7207.45944054198, 6982.895256571054, 6987.720491180397, 7058.188308344504, 7092.865141577323, 7121.786346409616, 7010.684519196649, 7104.21853596104, 7119.288071192943, 7158.713651453883, 7170.533915295489, 6820.897686534805, 6864.622445765331, 6894.0, 6928.1339832520935, 6931.584104953719, 6668.233153187445, 6760.729570817112, 6819.079424277659, 6823.735165521549, 6842.7605033823575, 6931.930680693128, 6959.338862808025, 6985.755498557526, 6986.895196572157, 7056.506044577036, 5259.237410575369, 5281.973590132025, 5291.788328466818, 5299.867503312448, 5316.680999140064, 6424.421802037839, 6595.4393876521035, 6746.898796518154, 6841.213260475109, 6852.6231057292325, 6581.638009909501, 6609.438197753053, 6631.20425548918, 6660.868522073084, 6712.664366781739, 6678.83302917431, 6692.531522793358, 6695.263521012587, 6697.162854643169, 6757.594544327356, 6604.603723776589, 6644.667766611554, 6677.131972843499, 6715.160604924385, 6730.899036514554, 6655.201375834931, 6689.866202676017, 6721.596704197764, 6723.327667233238, 6757.189137303555, 6543.1821022372205, 6543.509236807164, 6583.670816459254, 6608.669566521751, 6623.238327592196], "value": 5233.986854752449, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [951.9000504946985, 959.4387283439188, 959.8704174936381, 980.5097451274363, 995.0199053931881, 19128.234870605178, 19566.60866782667, 19577.216911323547, 19593.31423400183, 21436.785632143674, 21357.466063348424, 21502.0, 21750.56498870022, 21778.782212177914, 21807.890960545214, 1047.9528421221025, 1083.9512221949992, 1086.7772106718144, 1087.1140020882995, 1087.798757229911, 1029.2383636109262, 1068.8663917010374, 1069.107295408335, 1073.8228192348254, 1074.0, 1122.960696375627, 1126.7070561653989, 1127.3179726265614, 1129.6272230164068, 1129.8926601972842, 1114.6210288501907, 1115.2360632966445, 1115.966521004371, 1116.8045592021404, 1121.198343184627, 822.9629666665014, 824.1552408780988, 824.6000689665508, 829.6266679993967, 831.6839600951661, 18909.0, 19348.0, 19439.80560194408, 19451.610967780565, 20042.799572004384, 21358.359249222547, 21387.144514219413, 21781.0, 21796.564068718537, 22019.22932697364, 16695.165241737985, 16986.0, 17024.65950680992, 17069.658606827794, 17072.0, 3683.4843121962945, 3706.7405281630295, 3709.0727318170457, 3709.925801483982, 3710.7588006779474, 143775.0, 146834.0, 147355.26322368428, 148541.0, 149903.0, 15039.398424063134, 15041.699166016619, 15268.16025118607, 15298.158601277037, 15314.310856011507, 31730.778845481058, 32047.51928721071, 32115.839420803215, 32267.838660807014, 32300.35399292048, 8876.245519130816, 8910.910890891138, 9038.231750301298, 9114.0, 9114.0, 4551.40831691878, 4574.039451715111, 4620.907581848344, 4646.558576935254, 4648.581627653527, 5777.422257774273, 5820.912686309709, 5832.708364581754, 5877.030290002187, 5880.441358070976, 1038.2368958815264, 1042.5308611125024, 1068.7702144038913, 1069.25152393325, 1073.1682945717132, 708.6315116139682, 721.4733244731375, 721.72935149319, 729.1869565434608, 745.05006117201, 12011.579594714318, 12382.442790074469, 12446.315452650191, 12535.623931282076, 13080.215187088805, 721.4553012475652, 730.9049823523014, 736.0357931110262, 736.9299916507923, 737.9114506259283, 6018.759249629966, 6052.303985041776, 6195.349488303663, 6276.748930042747, 6316.936830631635, 129365.0, 129742.0, 130697.0, 131339.0, 133241.0, 117137.0, 117523.0, 117892.0, 120744.39627801804, 122891.0, 102720.0, 102902.48548757208, 103107.0, 103392.0, 105284.0, 11475.598354057567, 11567.826482602599, 11598.130140239347, 11969.940150299193, 12043.879561204276, 49985.25022124598, 50034.0, 50728.49271507385, 51009.23486147637, 51305.7434712824, 61191.0, 61248.69375653272, 61384.386156139655, 61493.0, 64936.675316623114, 16243.431479898147, 16488.0931548766, 16532.504024879272, 16671.749923751395, 16677.91661041687, 17950.102494874944, 18024.639507210046, 18046.0, 18196.0, 18202.635947280716, 16426.671466570362, 16572.088535130682, 16652.417165399154, 16682.833171668615, 16739.0, 17068.74396884023, 17112.0, 17117.315307387566, 17162.39931602388, 17279.82720172782, 15882.523524294289, 16031.0, 16065.116418596623, 16177.59556011109, 16363.18184090767, 12599.496020159091, 12741.362931853555, 12882.291473969022, 12985.545505907248, 13120.60638180856, 11935.52257909674, 12004.939975300067, 12285.508579657071, 12406.069544784357, 12411.937940310241], "value": 9445.439466361975, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1451.9999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 106.0, 93.0, 104.0, 107.0, 106.0, 109.0, 105.0, 108.0, 107.0], "value": 104.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 114.0, 143.0, 174.0, 113.0, 139.0, 143.0, 112.0, 162.0, 112.0], "value": 135.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 162.0, 163.0, 163.0, 161.0, 162.0, 162.0, 162.0, 163.0, 163.0], "value": 162.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 73.0, 175.0, 75.0, 74.0, 74.0, 74.0, 143.0, 76.0], "value": 91.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 77.0, 134.0, 78.0, 79.0, 77.0, 127.0, 80.0, 77.0, 125.0], "value": 93.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 114.0, 114.0, 113.0, 113.0, 115.0, 114.0, 114.0, 114.0], "value": 113.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 148.0, 149.0, 148.0, 149.0, 148.0, 148.0, 148.0, 149.0, 148.0], "value": 148.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 95.0, 94.0, 94.0, 94.0, 93.0, 94.0, 94.0, 93.0], "value": 93.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [45.0, 45.0, 45.0, 45.0, 46.0, 47.0, 45.0, 49.0, 46.0, 47.0], "value": 46.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 54.0, 55.0, 53.0, 57.0, 53.0, 53.0, 54.0, 54.0, 54.0], "value": 54.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 72.0, 73.0, 73.0, 72.0, 71.0, 74.0, 74.0, 72.0, 73.0], "value": 72.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 115.0, 124.0, 121.0, 113.0, 114.0, 113.0, 121.0, 113.0, 146.0], "value": 119.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 156.0, 168.0, 156.0, 155.0, 155.0, 156.0, 153.0, 157.0, 163.0], "value": 157.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 61.0, 59.0, 58.0, 60.0, 60.0, 59.0, 57.0, 59.0, 57.0], "value": 59.1, "unit": "score"}]}]} 21:54:54 INFO - Return code: 0 21:54:54 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 21:54:54 INFO - # TBPL SUCCESS # 21:54:54 INFO - Running post-action listener: _package_coverage_data 21:54:54 INFO - Running post-action listener: _resource_record_post_action 21:54:54 INFO - [mozharness: 2017-08-22 04:54:54.263546Z] Finished run-tests step (success) 21:54:54 INFO - Running post-run listener: _resource_record_post_run 21:54:54 INFO - Total resource usage - Wall time: 1183s; CPU: 13.0%; Read bytes: 6664192; Write bytes: 374620160; Read time: 2468; Write time: 213740 21:54:54 INFO - TinderboxPrint: CPU usage
13.0% 21:54:54 INFO - TinderboxPrint: I/O read bytes / time
6,664,192 / 2,468 21:54:54 INFO - TinderboxPrint: I/O write bytes / time
374,620,160 / 213,740 21:54:54 INFO - TinderboxPrint: CPU idle
8,201.8 (86.9%) 21:54:54 INFO - TinderboxPrint: CPU user
1,181.3 (12.5%) 21:54:54 INFO - TinderboxPrint: Swap in / out
0 / 0 21:54:54 INFO - install - Wall time: 15s; CPU: 13.0%; Read bytes: 0; Write bytes: 109191168; Read time: 0; Write time: 59060 21:54:54 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 21:54:54 INFO - run-tests - Wall time: 1169s; CPU: 13.0%; Read bytes: 4161536; Write bytes: 265428992; Read time: 1848; Write time: 154680 21:54:54 INFO - Running post-run listener: _upload_blobber_files 21:54:54 INFO - Blob upload gear active. 21:54:54 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 21:54:54 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 21:54:54 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', 'Mozilla-Beta-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 21:54:54 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 Mozilla-Beta-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 21:54:55 INFO - (blobuploader) - INFO - Open directory for files ... 21:54:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 21:54:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:54:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:54:56 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 21:54:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:54:56 INFO - (blobuploader) - INFO - Done attempting. 21:54:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 21:54:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:54:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:54:59 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 21:54:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:54:59 INFO - (blobuploader) - INFO - Done attempting. 21:54:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 21:54:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:54:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:55:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 21:55:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:55:00 INFO - (blobuploader) - INFO - Done attempting. 21:55:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 21:55:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:55:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:55:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 21:55:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:55:00 INFO - (blobuploader) - INFO - Done attempting. 21:55:00 INFO - (blobuploader) - INFO - Iteration through files over. 21:55:00 INFO - Return code: 0 21:55:00 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 21:55:00 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 21:55:00 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/95a286d1bfbd59f72ae5bd6732e4f20807db63738d76d08c98383e52831528d3ba4da55f4d95503e477ab2a40f16bcedb3a8468b832b27ff2800b73c00a8dfe1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/fe73150afffebe51bb661eb9c168aa391634d1faaf6b033b4486de3a59128dddacb9ae0fa7cbc038b69ef6c85822dec8729c28f2f7d29171a7642b7655722481", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/55a41a6756860289a16e2bd7ed2f30dbd9a94bed06c25659c1f143efa682ab1c63708751e6e97dca454f6185bbb37040302a8c78cbcb4a705374d84c446a229c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/6aa4f1ef1bc13b6193abc4d86da401362338e92b3cf6d0d2db12ea1e590db9780f7d565dc16136db990ed8b0280ac629861582abc9015711d2ca954d81022242"} 21:55:00 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 21:55:00 INFO - Writing to file /builds/slave/test/properties/blobber_files 21:55:00 INFO - Contents: 21:55:00 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/95a286d1bfbd59f72ae5bd6732e4f20807db63738d76d08c98383e52831528d3ba4da55f4d95503e477ab2a40f16bcedb3a8468b832b27ff2800b73c00a8dfe1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/fe73150afffebe51bb661eb9c168aa391634d1faaf6b033b4486de3a59128dddacb9ae0fa7cbc038b69ef6c85822dec8729c28f2f7d29171a7642b7655722481", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/55a41a6756860289a16e2bd7ed2f30dbd9a94bed06c25659c1f143efa682ab1c63708751e6e97dca454f6185bbb37040302a8c78cbcb4a705374d84c446a229c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/6aa4f1ef1bc13b6193abc4d86da401362338e92b3cf6d0d2db12ea1e590db9780f7d565dc16136db990ed8b0280ac629861582abc9015711d2ca954d81022242"} 21:55:00 INFO - Running post-run listener: copy_logs_to_upload_dir 21:55:00 INFO - Copying logs to upload dir... 21:55:00 INFO - mkdir: /builds/slave/test/build/upload/logs 21:55:00 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1243.917633 ========= master_lag: -0.14 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 43 secs) (at 2017-08-21 21:55:00.575188) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-21 21:55:00.584232) ========= 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=20e20d796406ba5117c4a6f1000001ba-1503376454.619861-1506835213 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/95a286d1bfbd59f72ae5bd6732e4f20807db63738d76d08c98383e52831528d3ba4da55f4d95503e477ab2a40f16bcedb3a8468b832b27ff2800b73c00a8dfe1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/fe73150afffebe51bb661eb9c168aa391634d1faaf6b033b4486de3a59128dddacb9ae0fa7cbc038b69ef6c85822dec8729c28f2f7d29171a7642b7655722481", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/55a41a6756860289a16e2bd7ed2f30dbd9a94bed06c25659c1f143efa682ab1c63708751e6e97dca454f6185bbb37040302a8c78cbcb4a705374d84c446a229c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/6aa4f1ef1bc13b6193abc4d86da401362338e92b3cf6d0d2db12ea1e590db9780f7d565dc16136db990ed8b0280ac629861582abc9015711d2ca954d81022242"} build_url:https://queue.taskcluster.net/v1/task/E6haCD-NS52AoxaYgNb96Q/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011133 build_url: 'https://queue.taskcluster.net/v1/task/E6haCD-NS52AoxaYgNb96Q/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/95a286d1bfbd59f72ae5bd6732e4f20807db63738d76d08c98383e52831528d3ba4da55f4d95503e477ab2a40f16bcedb3a8468b832b27ff2800b73c00a8dfe1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/fe73150afffebe51bb661eb9c168aa391634d1faaf6b033b4486de3a59128dddacb9ae0fa7cbc038b69ef6c85822dec8729c28f2f7d29171a7642b7655722481", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/55a41a6756860289a16e2bd7ed2f30dbd9a94bed06c25659c1f143efa682ab1c63708751e6e97dca454f6185bbb37040302a8c78cbcb4a705374d84c446a229c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/6aa4f1ef1bc13b6193abc4d86da401362338e92b3cf6d0d2db12ea1e590db9780f7d565dc16136db990ed8b0280ac629861582abc9015711d2ca954d81022242"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-21 21:55:00.610494) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-21 21:55:00.612877) ========= 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=20e20d796406ba5117c4a6f1000001ba-1503376454.619861-1506835213 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-21 21:55:00.695627) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-21 21:55:00.697997) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-21 21:55:00.698308) ========= ========= Total master_lag: 0.02 =========