builder: mozilla-central_ubuntu64_hw_styloseq_test-dromaeojs-e10s slave: talos-linux64-ix-051 starttime: 1500718890.39 results: success (0) revision: a599289ac64ba1d52a1552e33150342746e3e61b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-07-22 03:21:30.391040) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-07-22 03:21:30.391470) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-07-22 03:21:30.391795) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-07-22 03:21:30.505427) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-07-22 03:21:30.505749) ========= 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=5ac5dba3e01a12f1ff77ab15000001b1-1500716795.557513-437887472 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-07-22 03:21:30-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 47.2M=0s 2017-07-22 03:21:30 (47.2 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.160278 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-07-22 03:21:30.707499) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-07-22 03:21:30.707894) ========= 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=5ac5dba3e01a12f1ff77ab15000001b1-1500716795.557513-437887472 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.075415 ========= master_lag: 0.01 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-07-22 03:21:30.794233) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-07-22 03:21:30.794593) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev a599289ac64ba1d52a1552e33150342746e3e61b --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo mozilla-central --rev a599289ac64ba1d52a1552e33150342746e3e61b --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=5ac5dba3e01a12f1ff77ab15000001b1-1500716795.557513-437887472 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-07-22 03:21:30,815 truncating revision to first 12 chars 2017-07-22 03:21:30,815 Setting DEBUG logging. 2017-07-22 03:21:30,815 attempt 1/10 2017-07-22 03:21:30,815 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/a599289ac64b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-07-22 03:21:31,146 unpacking tar archive at: mozilla-central-a599289ac64b/testing/mozharness/ program finished with exit code 0 elapsedTime=0.732518 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-07-22 03:21:31.547276) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-07-22 03:21:31.547591) ========= script_repo_revision: a599289ac64ba1d52a1552e33150342746e3e61b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-07-22 03:21:31.548000) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-07-22 03:21:31.548335) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-07-22 03:21:31.572297) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 34 secs) (at 2017-07-22 03:21:31.572601) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=5ac5dba3e01a12f1ff77ab15000001b1-1500716795.557513-437887472 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 03:21:31 INFO - MultiFileLogger online at 20170722 03:21:31 in /builds/slave/test 03:21:31 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 03:21:31 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:21:31 INFO - {'append_to_log': False, 03:21:31 INFO - 'base_work_dir': '/builds/slave/test', 03:21:31 INFO - 'blob_upload_branch': 'Firefox', 03:21:31 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:21:31 INFO - 'branch': 'Firefox', 03:21:31 INFO - 'buildbot_json_path': 'buildprops.json', 03:21:31 INFO - 'code_coverage': False, 03:21:31 INFO - 'config_files': ('talos/linux_config.py',), 03:21:31 INFO - 'default_actions': ('clobber', 03:21:31 INFO - 'read-buildbot-config', 03:21:31 INFO - 'download-and-extract', 03:21:31 INFO - 'populate-webroot', 03:21:31 INFO - 'create-virtualenv', 03:21:31 INFO - 'install', 03:21:31 INFO - 'setup-mitmproxy', 03:21:31 INFO - 'run-tests'), 03:21:31 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:21:31 INFO - 'disable_ccov_upload': False, 03:21:31 INFO - 'download_minidump_stackwalk': True, 03:21:31 INFO - 'download_symbols': 'ondemand', 03:21:31 INFO - 'enable_stylo': False, 03:21:31 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:21:31 INFO - 'tooltool.py': '/tools/tooltool.py', 03:21:31 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:21:31 INFO - '/tools/misc-python/virtualenv.py')}, 03:21:31 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:21:31 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:21:31 INFO - 'gecko_profile': False, 03:21:31 INFO - 'gecko_profile_interval': 0, 03:21:31 INFO - 'installer_path': 'installer.exe', 03:21:31 INFO - 'log_level': 'info', 03:21:31 INFO - 'log_name': 'talos', 03:21:31 INFO - 'log_to_console': True, 03:21:31 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 03:21:31 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 03:21:31 INFO - 'opt_config_files': (), 03:21:31 INFO - 'pip_index': False, 03:21:31 INFO - 'suite': 'dromaeojs-e10s', 03:21:31 INFO - 'system_bits': '32', 03:21:31 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:21:31 INFO - 'title': 'talos-linux64-ix-051', 03:21:31 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:21:31 INFO - 'use_talos_json': True, 03:21:31 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:21:31 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:21:31 INFO - 'work_dir': 'build'} 03:21:31 INFO - [mozharness: 2017-07-22 10:21:31.819201Z] Running clobber step. 03:21:31 INFO - Running pre-action listener: _resource_record_pre_action 03:21:31 INFO - Running main action method: clobber 03:21:31 INFO - rmtree: /builds/slave/test/build 03:21:31 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:21:37 INFO - Running post-action listener: _resource_record_post_action 03:21:37 INFO - [mozharness: 2017-07-22 10:21:37.226797Z] Finished clobber step (success) 03:21:37 INFO - [mozharness: 2017-07-22 10:21:37.226899Z] Running read-buildbot-config step. 03:21:37 INFO - Running pre-action listener: _resource_record_pre_action 03:21:37 INFO - Running main action method: read_buildbot_config 03:21:37 INFO - Using buildbot properties: 03:21:37 INFO - { 03:21:37 INFO - "project": "", 03:21:37 INFO - "product": "firefox", 03:21:37 INFO - "who": "archaeopteryx@coole-files.de", 03:21:37 INFO - "installer_path": "public/build/target.tar.bz2", 03:21:37 INFO - "builddir": "mozilla-central_ubuntu64_hw_styloseq_test-dromaeojs-e10s", 03:21:37 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 03:21:37 INFO - "buildername": "Ubuntu HW 12.04 x64 stylo-sequential mozilla-central talos dromaeojs-e10s", 03:21:37 INFO - "stage_platform": "linux64-stylosequential", 03:21:37 INFO - "basedir": "/builds/slave/test", 03:21:37 INFO - "buildnumber": 140, 03:21:37 INFO - "platform": "ubuntu64_hw_styloseq", 03:21:37 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 03:21:37 INFO - "slavebuilddir": "test", 03:21:37 INFO - "taskId": "QczvkwJVQx2iNHlv16Tu2g", 03:21:37 INFO - "branch": "mozilla-central", 03:21:37 INFO - "script_repo_revision": "production", 03:21:37 INFO - "revision": "a599289ac64ba1d52a1552e33150342746e3e61b", 03:21:37 INFO - "slavename": "talos-linux64-ix-051", 03:21:37 INFO - "repo_path": "mozilla-central" 03:21:37 INFO - } 03:21:37 INFO - Finding installer, test and symbols from parent task. 03:21:37 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QczvkwJVQx2iNHlv16Tu2g'}, attempt #1 03:21:37 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QczvkwJVQx2iNHlv16Tu2g'}, attempt #1 03:21:38 INFO - Task dependencies: RwKFdA9lQGydGPWJvR_XgA 03:21:38 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RwKFdA9lQGydGPWJvR_XgA'}, attempt #1 03:21:38 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/RwKFdA9lQGydGPWJvR_XgA/artifacts/public/build/target.tar.bz2 03:21:38 INFO - Set test_url: https://queue.taskcluster.net/v1/task/RwKFdA9lQGydGPWJvR_XgA/artifacts/public/build/test_packages.json 03:21:38 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/RwKFdA9lQGydGPWJvR_XgA/artifacts/public/build/target.crashreporter-symbols.zip 03:21:38 INFO - Running post-action listener: _resource_record_post_action 03:21:38 INFO - [mozharness: 2017-07-22 10:21:38.563787Z] Finished read-buildbot-config step (success) 03:21:38 INFO - [mozharness: 2017-07-22 10:21:38.564007Z] Running download-and-extract step. 03:21:38 INFO - Running pre-action listener: _resource_record_pre_action 03:21:38 INFO - Running main action method: download_and_extract 03:21:38 INFO - mkdir: /builds/slave/test/build/tests 03:21:38 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')]} 03:21:38 INFO - trying https://queue.taskcluster.net/v1/task/RwKFdA9lQGydGPWJvR_XgA/artifacts/public/build/target.test_packages.json 03:21:38 INFO - Downloading https://queue.taskcluster.net/v1/task/RwKFdA9lQGydGPWJvR_XgA/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 03:21:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RwKFdA9lQGydGPWJvR_XgA/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 03:21:38 WARNING - Server returned status 500 HTTP Error 500: Internal Server Error for https://queue.taskcluster.net/v1/task/RwKFdA9lQGydGPWJvR_XgA/artifacts/public/build/target.test_packages.json 03:21:38 INFO - retry: attempt #1 caught HTTPError exception: HTTP Error 500: Internal Server Error 03:21:38 INFO - retry: Failed, sleeping 30 seconds before retrying 03:22:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RwKFdA9lQGydGPWJvR_XgA/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #2 03:22:09 INFO - Downloaded 989 bytes. 03:22:09 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 03:22:09 INFO - Using the following test package requirements: 03:22:09 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 03:22:09 INFO - u'common': [u'target.common.tests.zip'], 03:22:09 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 03:22:09 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 03:22:09 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 03:22:09 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 03:22:09 INFO - u'mozbase': [u'target.common.tests.zip'], 03:22:09 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 03:22:09 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 03:22:09 INFO - u'web-platform': [u'target.common.tests.zip', 03:22:09 INFO - u'target.web-platform.tests.tar.gz'], 03:22:09 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 03:22:09 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 03:22:09 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RwKFdA9lQGydGPWJvR_XgA/artifacts/public/build/target.common.tests.zip 03:22:09 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RwKFdA9lQGydGPWJvR_XgA/artifacts/public/build/target.common.tests.zip'}, attempt #1 03:22:09 INFO - Fetch https://queue.taskcluster.net/v1/task/RwKFdA9lQGydGPWJvR_XgA/artifacts/public/build/target.common.tests.zip into memory 03:22:10 INFO - Content-Length response header: 72760917 03:22:10 INFO - Bytes received: 72760917 03:22:21 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 03:22:21 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RwKFdA9lQGydGPWJvR_XgA/artifacts/public/build/target.common.tests.zip 03:22:21 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RwKFdA9lQGydGPWJvR_XgA/artifacts/public/build/target.common.tests.zip'}, attempt #1 03:22:21 INFO - Fetch https://queue.taskcluster.net/v1/task/RwKFdA9lQGydGPWJvR_XgA/artifacts/public/build/target.common.tests.zip into memory 03:22:22 INFO - Content-Length response header: 72760917 03:22:22 INFO - Bytes received: 72760917 03:22:35 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RwKFdA9lQGydGPWJvR_XgA/artifacts/public/build/target.talos.tests.zip 03:22:35 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RwKFdA9lQGydGPWJvR_XgA/artifacts/public/build/target.talos.tests.zip'}, attempt #1 03:22:35 INFO - Fetch https://queue.taskcluster.net/v1/task/RwKFdA9lQGydGPWJvR_XgA/artifacts/public/build/target.talos.tests.zip into memory 03:22:35 INFO - Content-Length response header: 14498826 03:22:35 INFO - Bytes received: 14498826 03:22:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:22:36 INFO - trying https://queue.taskcluster.net/v1/task/RwKFdA9lQGydGPWJvR_XgA/artifacts/public/build/target.tar.bz2 03:22:36 INFO - Downloading https://queue.taskcluster.net/v1/task/RwKFdA9lQGydGPWJvR_XgA/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 03:22:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RwKFdA9lQGydGPWJvR_XgA/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 03:22:37 INFO - Downloaded 61975052 bytes. 03:22:37 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/RwKFdA9lQGydGPWJvR_XgA/artifacts/public/build/target.tar.bz2 03:22:37 INFO - mkdir: /builds/slave/test/properties 03:22:37 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:22:37 INFO - Writing to file /builds/slave/test/properties/build_url 03:22:37 INFO - Contents: 03:22:37 INFO - build_url:https://queue.taskcluster.net/v1/task/RwKFdA9lQGydGPWJvR_XgA/artifacts/public/build/target.tar.bz2 03:22:37 INFO - Running post-action listener: _resource_record_post_action 03:22:37 INFO - Running post-action listener: set_extra_try_arguments 03:22:37 INFO - [mozharness: 2017-07-22 10:22:37.305749Z] Finished download-and-extract step (success) 03:22:37 INFO - [mozharness: 2017-07-22 10:22:37.305847Z] Running populate-webroot step. 03:22:37 INFO - Running pre-action listener: _resource_record_pre_action 03:22:37 INFO - Running main action method: populate_webroot 03:22:37 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:22:37 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:22:37 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 03:22:37 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:22:37 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 03:22:37 INFO - 'tests': ['damp', 'tps']}, 03:22:37 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:22:37 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 03:22:37 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 03:22:37 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 03:22:37 INFO - 'other-e10s': {'tests': ['a11yr', 03:22:37 INFO - 'ts_paint', 03:22:37 INFO - 'tpaint', 03:22:37 INFO - 'sessionrestore', 03:22:37 INFO - 'sessionrestore_many_windows', 03:22:37 INFO - 'sessionrestore_no_auto_restore', 03:22:37 INFO - 'tabpaint']}, 03:22:37 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 03:22:37 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 03:22:37 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 03:22:37 INFO - 'talos_options': ['--mitmproxy', 03:22:37 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 03:22:37 INFO - '--firstNonBlankPaint'], 03:22:37 INFO - 'tests': ['quantum_pageload_google', 03:22:37 INFO - 'quantum_pageload_youtube', 03:22:37 INFO - 'quantum_pageload_amazon', 03:22:37 INFO - 'quantum_pageload_facebook']}, 03:22:37 INFO - 'quantum-pageload-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 03:22:37 INFO - 'talos_options': ['--stylo', 03:22:37 INFO - '--mitmproxy', 03:22:37 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 03:22:37 INFO - '--firstNonBlankPaint'], 03:22:37 INFO - 'tests': ['quantum_pageload_google', 03:22:37 INFO - 'quantum_pageload_youtube', 03:22:37 INFO - 'quantum_pageload_amazon', 03:22:37 INFO - 'quantum_pageload_facebook']}, 03:22:37 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:22:37 INFO - 'tsvgr_opacity', 03:22:37 INFO - 'tart', 03:22:37 INFO - 'tscrollx', 03:22:37 INFO - 'cart', 03:22:37 INFO - 'tsvg_static']}, 03:22:37 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 03:22:37 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 03:22:37 INFO - 'talos_options': ['--xperf_path', 03:22:37 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:22:37 INFO - 'tests': ['tp5n']}}} 03:22:37 INFO - Running post-action listener: _resource_record_post_action 03:22:37 INFO - [mozharness: 2017-07-22 10:22:37.313142Z] Finished populate-webroot step (success) 03:22:37 INFO - [mozharness: 2017-07-22 10:22:37.313233Z] Running create-virtualenv step. 03:22:37 INFO - Running pre-action listener: _resource_record_pre_action 03:22:37 INFO - Running main action method: create_virtualenv 03:22:37 INFO - Creating virtualenv /builds/slave/test/build/venv 03:22:37 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 03:22:37 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:22:37 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 03:22:37 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:22:37 INFO - Using real prefix '/usr' 03:22:37 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:22:38 INFO - Installing distribute.............................................................................................................................................................................................done. 03:22:40 INFO - Installing pip.................done. 03:22:40 INFO - Return code: 0 03:22:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 03:22:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 03:22:40 INFO - Reading from file tmpfile_stdout 03:22:40 INFO - Output received: 03:22:40 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) 03:22:40 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:22:40 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')]} 03:22:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:22:40 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')]} 03:22:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:22:40 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 0x1a0c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bd8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d7bd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d78eb0>, '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 0x1d79db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d7a240>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1500716795.557513-437887472', '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 03:22:40 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 03:22:40 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 03:22:40 INFO - Using env: {'DISPLAY': ':0', 03:22:40 INFO - 'HOME': '/home/cltbld', 03:22:40 INFO - 'LANG': 'en_US.UTF-8', 03:22:40 INFO - 'LANGUAGE': 'en_US:en', 03:22:40 INFO - 'LOGNAME': 'cltbld', 03:22:40 INFO - 'MAIL': '/var/mail/cltbld', 03:22:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:22:40 INFO - 'MOZ_NO_REMOTE': '1', 03:22:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:22:40 INFO - 'NO_EM_RESTART': '1', 03:22:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:22:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:22:40 INFO - 'PWD': '/builds/slave/test', 03:22:40 INFO - 'SHELL': '/bin/bash', 03:22:40 INFO - 'SHLVL': '1', 03:22:40 INFO - 'TERM': 'linux', 03:22:40 INFO - 'TMOUT': '86400', 03:22:40 INFO - 'USER': 'cltbld', 03:22:40 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1500716795.557513-437887472', 03:22:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:22:40 INFO - '_': '/tools/buildbot/bin/python'} 03:22:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:22:40 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 03:22:40 INFO - Cleaning up... 03:22:40 INFO - Return code: 0 03:22:40 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:22:40 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')]} 03:22:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:22:40 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')]} 03:22:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:22:40 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 0x1a0c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bd8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d7bd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d78eb0>, '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 0x1d79db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d7a240>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1500716795.557513-437887472', '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 03:22:40 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 03:22:40 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 03:22:40 INFO - Using env: {'DISPLAY': ':0', 03:22:40 INFO - 'HOME': '/home/cltbld', 03:22:40 INFO - 'LANG': 'en_US.UTF-8', 03:22:40 INFO - 'LANGUAGE': 'en_US:en', 03:22:40 INFO - 'LOGNAME': 'cltbld', 03:22:40 INFO - 'MAIL': '/var/mail/cltbld', 03:22:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:22:40 INFO - 'MOZ_NO_REMOTE': '1', 03:22:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:22:40 INFO - 'NO_EM_RESTART': '1', 03:22:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:22:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:22:40 INFO - 'PWD': '/builds/slave/test', 03:22:40 INFO - 'SHELL': '/bin/bash', 03:22:40 INFO - 'SHLVL': '1', 03:22:40 INFO - 'TERM': 'linux', 03:22:40 INFO - 'TMOUT': '86400', 03:22:40 INFO - 'USER': 'cltbld', 03:22:40 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1500716795.557513-437887472', 03:22:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:22:40 INFO - '_': '/tools/buildbot/bin/python'} 03:22:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:22:40 INFO - Downloading/unpacking psutil>=3.1.1 03:22:40 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 03:22:40 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 03:22:40 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 03:22:40 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 03:22:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:22:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:22:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:22:41 INFO - Installing collected packages: psutil 03:22:41 INFO - Running setup.py install for psutil 03:22:42 INFO - building 'psutil._psutil_linux' extension 03:22:42 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 03:22:43 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 03:22:43 INFO - building 'psutil._psutil_posix' extension 03:22:43 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 03:22:43 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 03:22:43 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:22:43 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:22:43 INFO - Successfully installed psutil 03:22:43 INFO - Cleaning up... 03:22:43 INFO - Return code: 0 03:22:43 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:22:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:22:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:22:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:22:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:22:43 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 0x1a0c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bd8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d7bd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d78eb0>, '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 0x1d79db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d7a240>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1500716795.557513-437887472', '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 03:22:43 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 03:22:43 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 03:22:43 INFO - Using env: {'DISPLAY': ':0', 03:22:43 INFO - 'HOME': '/home/cltbld', 03:22:43 INFO - 'LANG': 'en_US.UTF-8', 03:22:43 INFO - 'LANGUAGE': 'en_US:en', 03:22:43 INFO - 'LOGNAME': 'cltbld', 03:22:43 INFO - 'MAIL': '/var/mail/cltbld', 03:22:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:22:43 INFO - 'MOZ_NO_REMOTE': '1', 03:22:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:22:43 INFO - 'NO_EM_RESTART': '1', 03:22:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:22:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:22:43 INFO - 'PWD': '/builds/slave/test', 03:22:43 INFO - 'SHELL': '/bin/bash', 03:22:43 INFO - 'SHLVL': '1', 03:22:43 INFO - 'TERM': 'linux', 03:22:43 INFO - 'TMOUT': '86400', 03:22:43 INFO - 'USER': 'cltbld', 03:22:43 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1500716795.557513-437887472', 03:22:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:22:43 INFO - '_': '/tools/buildbot/bin/python'} 03:22:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:22:43 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:22:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:22:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:22:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:22:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:22:44 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:22:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:22:44 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:22:44 INFO - Installing collected packages: mozsystemmonitor 03:22:44 INFO - Running setup.py install for mozsystemmonitor 03:22:45 INFO - Successfully installed mozsystemmonitor 03:22:45 INFO - Cleaning up... 03:22:45 INFO - Return code: 0 03:22:45 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:22:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:22:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:22:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:22:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:22:45 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 0x1a0c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bd8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d7bd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d78eb0>, '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 0x1d79db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d7a240>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1500716795.557513-437887472', '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 03:22:45 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 03:22:45 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 03:22:45 INFO - Using env: {'DISPLAY': ':0', 03:22:45 INFO - 'HOME': '/home/cltbld', 03:22:45 INFO - 'LANG': 'en_US.UTF-8', 03:22:45 INFO - 'LANGUAGE': 'en_US:en', 03:22:45 INFO - 'LOGNAME': 'cltbld', 03:22:45 INFO - 'MAIL': '/var/mail/cltbld', 03:22:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:22:45 INFO - 'MOZ_NO_REMOTE': '1', 03:22:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:22:45 INFO - 'NO_EM_RESTART': '1', 03:22:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:22:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:22:45 INFO - 'PWD': '/builds/slave/test', 03:22:45 INFO - 'SHELL': '/bin/bash', 03:22:45 INFO - 'SHLVL': '1', 03:22:45 INFO - 'TERM': 'linux', 03:22:45 INFO - 'TMOUT': '86400', 03:22:45 INFO - 'USER': 'cltbld', 03:22:45 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1500716795.557513-437887472', 03:22:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:22:45 INFO - '_': '/tools/buildbot/bin/python'} 03:22:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:22:45 INFO - Downloading/unpacking jsonschema==2.5.1 03:22:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:22:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:22:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:22:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:22:46 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:22:46 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:22:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:22:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:22:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:22:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:22:46 INFO - Downloading functools32-3.2.3-2.tar.gz 03:22:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:22:46 INFO - warning: no files found matching '*.txt' 03:22:46 INFO - no previously-included directories found matching 'build' 03:22:46 INFO - no previously-included directories found matching 'dist' 03:22:46 INFO - no previously-included directories found matching '.git*' 03:22:46 INFO - Installing collected packages: jsonschema, functools32 03:22:46 INFO - Running setup.py install for functools32 03:22:46 INFO - warning: no files found matching '*.txt' 03:22:46 INFO - no previously-included directories found matching 'build' 03:22:46 INFO - no previously-included directories found matching 'dist' 03:22:46 INFO - no previously-included directories found matching '.git*' 03:22:46 INFO - Successfully installed jsonschema functools32 03:22:46 INFO - Cleaning up... 03:22:46 INFO - Return code: 0 03:22:46 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 03:22:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:22:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:22:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:22:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:22:46 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1a0c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bd8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d7bd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d78eb0>, '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 0x1d79db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d7a240>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1500716795.557513-437887472', '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 03:22:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 03:22:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 03:22:46 INFO - Using env: {'DISPLAY': ':0', 03:22:46 INFO - 'HOME': '/home/cltbld', 03:22:46 INFO - 'LANG': 'en_US.UTF-8', 03:22:46 INFO - 'LANGUAGE': 'en_US:en', 03:22:46 INFO - 'LOGNAME': 'cltbld', 03:22:46 INFO - 'MAIL': '/var/mail/cltbld', 03:22:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:22:46 INFO - 'MOZ_NO_REMOTE': '1', 03:22:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:22:46 INFO - 'NO_EM_RESTART': '1', 03:22:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:22:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:22:46 INFO - 'PWD': '/builds/slave/test', 03:22:46 INFO - 'SHELL': '/bin/bash', 03:22:46 INFO - 'SHLVL': '1', 03:22:46 INFO - 'TERM': 'linux', 03:22:46 INFO - 'TMOUT': '86400', 03:22:46 INFO - 'USER': 'cltbld', 03:22:46 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1500716795.557513-437887472', 03:22:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:22:46 INFO - '_': '/tools/buildbot/bin/python'} 03:22:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:22:46 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 03:22:46 INFO - Cleaning up... 03:22:46 INFO - Return code: 0 03:22:46 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:22:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:22:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:22:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:22:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:22:46 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1a0c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bd8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d7bd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d78eb0>, '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 0x1d79db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d7a240>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1500716795.557513-437887472', '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 03:22:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 03:22:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 03:22:46 INFO - Using env: {'DISPLAY': ':0', 03:22:46 INFO - 'HOME': '/home/cltbld', 03:22:46 INFO - 'LANG': 'en_US.UTF-8', 03:22:46 INFO - 'LANGUAGE': 'en_US:en', 03:22:46 INFO - 'LOGNAME': 'cltbld', 03:22:46 INFO - 'MAIL': '/var/mail/cltbld', 03:22:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:22:46 INFO - 'MOZ_NO_REMOTE': '1', 03:22:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:22:46 INFO - 'NO_EM_RESTART': '1', 03:22:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:22:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:22:46 INFO - 'PWD': '/builds/slave/test', 03:22:46 INFO - 'SHELL': '/bin/bash', 03:22:46 INFO - 'SHLVL': '1', 03:22:46 INFO - 'TERM': 'linux', 03:22:46 INFO - 'TMOUT': '86400', 03:22:46 INFO - 'USER': 'cltbld', 03:22:46 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1500716795.557513-437887472', 03:22:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:22:46 INFO - '_': '/tools/buildbot/bin/python'} 03:22:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:22:47 INFO - Downloading/unpacking blobuploader==1.2.4 03:22:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:22:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:22:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:22:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:22:48 INFO - Downloading blobuploader-1.2.4.tar.gz 03:22:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:22:48 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:22:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:22:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:22:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:22:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:22:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:22:48 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:22:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:22:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:22:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:22:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:22:48 INFO - Downloading docopt-0.6.1.tar.gz 03:22:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:22:48 INFO - Installing collected packages: blobuploader, requests, docopt 03:22:48 INFO - Running setup.py install for blobuploader 03:22:48 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:22:48 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:22:48 INFO - Running setup.py install for requests 03:22:49 INFO - Running setup.py install for docopt 03:22:49 INFO - Successfully installed blobuploader requests docopt 03:22:49 INFO - Cleaning up... 03:22:49 INFO - Return code: 0 03:22:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:22:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:22:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:22:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:22:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:22:49 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 0x1a0c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bd8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d7bd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d78eb0>, '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 0x1d79db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d7a240>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1500716795.557513-437887472', '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 03:22:49 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 03:22:49 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 03:22:49 INFO - Using env: {'DISPLAY': ':0', 03:22:49 INFO - 'HOME': '/home/cltbld', 03:22:49 INFO - 'LANG': 'en_US.UTF-8', 03:22:49 INFO - 'LANGUAGE': 'en_US:en', 03:22:49 INFO - 'LOGNAME': 'cltbld', 03:22:49 INFO - 'MAIL': '/var/mail/cltbld', 03:22:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:22:49 INFO - 'MOZ_NO_REMOTE': '1', 03:22:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:22:49 INFO - 'NO_EM_RESTART': '1', 03:22:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:22:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:22:49 INFO - 'PWD': '/builds/slave/test', 03:22:49 INFO - 'SHELL': '/bin/bash', 03:22:49 INFO - 'SHLVL': '1', 03:22:49 INFO - 'TERM': 'linux', 03:22:49 INFO - 'TMOUT': '86400', 03:22:49 INFO - 'USER': 'cltbld', 03:22:49 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1500716795.557513-437887472', 03:22:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:22:49 INFO - '_': '/tools/buildbot/bin/python'} 03:22:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:22:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:22:49 INFO - Running setup.py (path:/tmp/pip-VCRxKZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:22:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:22:49 INFO - Running setup.py (path:/tmp/pip-vPwyPt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:22:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:22:49 INFO - Running setup.py (path:/tmp/pip-S2taZm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:22:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:22:49 INFO - Running setup.py (path:/tmp/pip-onWeGK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:22:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:22:49 INFO - Running setup.py (path:/tmp/pip-Fq_Qn1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:22:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:22:50 INFO - Running setup.py (path:/tmp/pip-Y9M47K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:22:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:22:50 INFO - Running setup.py (path:/tmp/pip-iaPQ0S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:22:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:22:50 INFO - Running setup.py (path:/tmp/pip-hOp8AF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:22:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:22:50 INFO - Running setup.py (path:/tmp/pip-ZfFAFd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:22:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:22:50 INFO - Running setup.py (path:/tmp/pip-R4AS6P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:22:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:22:50 INFO - Running setup.py (path:/tmp/pip-T7rJJ1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:22:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:22:50 INFO - Running setup.py (path:/tmp/pip-if9ztJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:22:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:22:50 INFO - Running setup.py (path:/tmp/pip-V04zc3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:22:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:22:50 INFO - Running setup.py (path:/tmp/pip-W7Z6Jb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:22:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:22:50 INFO - Running setup.py (path:/tmp/pip-F8pW_U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:22:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:22:50 INFO - Running setup.py (path:/tmp/pip-VLX_40-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:22:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:22:50 INFO - Running setup.py (path:/tmp/pip-LB_sDx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:22:50 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:22:50 INFO - Running setup.py install for manifestparser 03:22:50 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:22:50 INFO - Running setup.py install for mozcrash 03:22:51 INFO - Running setup.py install for mozdebug 03:22:51 INFO - Running setup.py install for mozdevice 03:22:51 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:22:51 INFO - Running setup.py install for mozfile 03:22:51 INFO - Running setup.py install for mozhttpd 03:22:51 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:22:51 INFO - Running setup.py install for mozinfo 03:22:51 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:22:51 INFO - Running setup.py install for mozInstall 03:22:51 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:22:51 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:22:51 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:22:51 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:22:51 INFO - Running setup.py install for mozleak 03:22:51 INFO - Running setup.py install for mozlog 03:22:51 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:22:51 INFO - Running setup.py install for moznetwork 03:22:52 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:22:52 INFO - Running setup.py install for mozprocess 03:22:52 INFO - Running setup.py install for mozprofile 03:22:52 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:22:52 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:22:52 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:22:52 INFO - Running setup.py install for mozrunner 03:22:52 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:22:52 INFO - Running setup.py install for mozscreenshot 03:22:52 INFO - Running setup.py install for moztest 03:22:52 INFO - Running setup.py install for mozversion 03:22:52 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:22:52 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:22:52 INFO - Cleaning up... 03:22:52 INFO - Return code: 0 03:22:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:22: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')]} 03:22:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:22: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')]} 03:22:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:22:52 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 0x1a0c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bd8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d7bd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d78eb0>, '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 0x1d79db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d7a240>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1500716795.557513-437887472', '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 03:22:52 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 03:22:52 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 03:22:52 INFO - Using env: {'DISPLAY': ':0', 03:22:52 INFO - 'HOME': '/home/cltbld', 03:22:52 INFO - 'LANG': 'en_US.UTF-8', 03:22:52 INFO - 'LANGUAGE': 'en_US:en', 03:22:52 INFO - 'LOGNAME': 'cltbld', 03:22:52 INFO - 'MAIL': '/var/mail/cltbld', 03:22:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:22:52 INFO - 'MOZ_NO_REMOTE': '1', 03:22:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:22:52 INFO - 'NO_EM_RESTART': '1', 03:22:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:22:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:22:52 INFO - 'PWD': '/builds/slave/test', 03:22:52 INFO - 'SHELL': '/bin/bash', 03:22:52 INFO - 'SHLVL': '1', 03:22:52 INFO - 'TERM': 'linux', 03:22:52 INFO - 'TMOUT': '86400', 03:22:52 INFO - 'USER': 'cltbld', 03:22:52 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1500716795.557513-437887472', 03:22:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:22:52 INFO - '_': '/tools/buildbot/bin/python'} 03:22:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:22:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:22:52 INFO - Running setup.py (path:/tmp/pip-GhuYrY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:22:52 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)) 03:22:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:22:52 INFO - Running setup.py (path:/tmp/pip-3F1hbS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:22:53 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)) 03:22:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:22:53 INFO - Running setup.py (path:/tmp/pip-QD6HqI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:22:53 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)) 03:22:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:22:53 INFO - Running setup.py (path:/tmp/pip-R4qreW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:22:53 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)) 03:22:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:22:53 INFO - Running setup.py (path:/tmp/pip-j9ih3y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:22:53 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)) 03:22:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:22:53 INFO - Running setup.py (path:/tmp/pip-WNkQHU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:22:53 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)) 03:22:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:22:53 INFO - Running setup.py (path:/tmp/pip-RO_Uuz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:22:53 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)) 03:22:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:22:53 INFO - Running setup.py (path:/tmp/pip-Gsw0fJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:22:53 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)) 03:22:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:22:53 INFO - Running setup.py (path:/tmp/pip-Co3f_P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:22:53 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)) 03:22:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:22:53 INFO - Running setup.py (path:/tmp/pip-miNWfX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:22:53 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)) 03:22:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:22:53 INFO - Running setup.py (path:/tmp/pip-EKs9ur-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:22:53 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)) 03:22:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:22:53 INFO - Running setup.py (path:/tmp/pip-87HswJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:22:53 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)) 03:22:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:22:53 INFO - Running setup.py (path:/tmp/pip-cbjPut-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:22:53 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)) 03:22:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:22:53 INFO - Running setup.py (path:/tmp/pip-GaX0XS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:22:53 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)) 03:22:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:22:53 INFO - Running setup.py (path:/tmp/pip-jD_03Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:22:53 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)) 03:22:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:22:53 INFO - Running setup.py (path:/tmp/pip-PF6asu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:22:54 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)) 03:22:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:22:54 INFO - Running setup.py (path:/tmp/pip-kpS3rv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:22:54 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)) 03:22:54 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)) 03:22:54 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)) 03:22:54 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)) 03:22:54 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)) 03:22:54 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)) 03:22:54 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)) 03:22:54 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:22:54 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 03:22:54 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 03:22:54 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 03:22:54 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 03:22:55 INFO - Downloading blessings-1.6.tar.gz 03:22:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:22:55 INFO - Installing collected packages: blessings 03:22:55 INFO - Running setup.py install for blessings 03:22:55 INFO - Successfully installed blessings 03:22:55 INFO - Cleaning up... 03:22:55 INFO - Return code: 0 03:22:55 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:22:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:22:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:22:55 INFO - Reading from file tmpfile_stdout 03:22:55 INFO - Current package versions: 03:22:55 INFO - argparse == 1.2.1 03:22:55 INFO - blessings == 1.6 03:22:55 INFO - blobuploader == 1.2.4 03:22:55 INFO - docopt == 0.6.1 03:22:55 INFO - functools32 == 3.2.3-2 03:22:55 INFO - jsonschema == 2.5.1 03:22:55 INFO - manifestparser == 1.1 03:22:55 INFO - mozInstall == 1.13 03:22:55 INFO - mozcrash == 1.0 03:22:55 INFO - mozdebug == 0.1 03:22:55 INFO - mozdevice == 0.51 03:22:55 INFO - mozfile == 1.2 03:22:55 INFO - mozhttpd == 0.7 03:22:55 INFO - mozinfo == 0.10 03:22:55 INFO - mozleak == 0.1 03:22:55 INFO - mozlog == 3.5 03:22:55 INFO - moznetwork == 0.27 03:22:55 INFO - mozprocess == 0.25 03:22:55 INFO - mozprofile == 0.28 03:22:55 INFO - mozrunner == 6.13 03:22:55 INFO - mozscreenshot == 0.1 03:22:55 INFO - mozsystemmonitor == 0.3 03:22:55 INFO - moztest == 0.8 03:22:55 INFO - mozversion == 1.4 03:22:55 INFO - psutil == 3.1.1 03:22:55 INFO - requests == 1.2.3 03:22:55 INFO - wsgiref == 0.1.2 03:22:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:22: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')]} 03:22:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:22: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')]} 03:22:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:22:55 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 0x1a0c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bd8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d7bd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d78eb0>, '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 0x1d79db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d7a240>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1500716795.557513-437887472', '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 03:22:55 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 03:22:55 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 03:22:55 INFO - Using env: {'DISPLAY': ':0', 03:22:55 INFO - 'HOME': '/home/cltbld', 03:22:55 INFO - 'LANG': 'en_US.UTF-8', 03:22:55 INFO - 'LANGUAGE': 'en_US:en', 03:22:55 INFO - 'LOGNAME': 'cltbld', 03:22:55 INFO - 'MAIL': '/var/mail/cltbld', 03:22:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:22:55 INFO - 'MOZ_NO_REMOTE': '1', 03:22:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:22:55 INFO - 'NO_EM_RESTART': '1', 03:22:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:22:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:22:55 INFO - 'PWD': '/builds/slave/test', 03:22:55 INFO - 'SHELL': '/bin/bash', 03:22:55 INFO - 'SHLVL': '1', 03:22:55 INFO - 'TERM': 'linux', 03:22:55 INFO - 'TMOUT': '86400', 03:22:55 INFO - 'USER': 'cltbld', 03:22:55 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1500716795.557513-437887472', 03:22:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:22:55 INFO - '_': '/tools/buildbot/bin/python'} 03:22:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:22:55 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)) 03:22:55 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)) 03:22:55 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)) 03:22:55 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)) 03:22:55 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)) 03:22:55 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)) 03:22:55 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)) 03:22:55 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)) 03:22:55 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)) 03:22:55 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 03:22: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 03:22: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 03:22: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 03:22: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 03:22:56 INFO - Downloading marionette_driver-1.4.0.tar.gz 03:22:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 03:22:57 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 11)) 03:22: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 03:22: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 03:22: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 03:22: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 03:22:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 03:22:57 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)) 03:22:57 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)) 03:22:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner>=6.9 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 03:22:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice>=0.37 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozrunner>=6.9->marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 03:22:57 INFO - Installing collected packages: marionette-driver, simplejson 03:22:57 INFO - Running setup.py install for marionette-driver 03:22:57 INFO - Running setup.py install for simplejson 03:22:57 INFO - building 'simplejson._speedups' extension 03:22:57 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 03:22:58 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 03:22:58 INFO - Successfully installed marionette-driver simplejson 03:22:58 INFO - Cleaning up... 03:22:58 INFO - Return code: 0 03:22:58 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:22: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')]} 03:22:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:22: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')]} 03:22:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:22: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a0c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bd8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d7bd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d78eb0>, '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 0x1d79db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d7a240>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1500716795.557513-437887472', '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 03:22: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', 'jsonschema'] in /builds/slave/test/build 03:22: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 jsonschema 03:22:58 INFO - Using env: {'DISPLAY': ':0', 03:22:58 INFO - 'HOME': '/home/cltbld', 03:22:58 INFO - 'LANG': 'en_US.UTF-8', 03:22:58 INFO - 'LANGUAGE': 'en_US:en', 03:22:58 INFO - 'LOGNAME': 'cltbld', 03:22:58 INFO - 'MAIL': '/var/mail/cltbld', 03:22:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:22:58 INFO - 'MOZ_NO_REMOTE': '1', 03:22:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:22:58 INFO - 'NO_EM_RESTART': '1', 03:22:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:22:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:22:58 INFO - 'PWD': '/builds/slave/test', 03:22:58 INFO - 'SHELL': '/bin/bash', 03:22:58 INFO - 'SHLVL': '1', 03:22:58 INFO - 'TERM': 'linux', 03:22:58 INFO - 'TMOUT': '86400', 03:22:58 INFO - 'USER': 'cltbld', 03:22:58 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1500716795.557513-437887472', 03:22:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:22:58 INFO - '_': '/tools/buildbot/bin/python'} 03:22:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:22:58 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:22:58 INFO - Cleaning up... 03:22:58 INFO - Return code: 0 03:22:58 INFO - Running post-action listener: _resource_record_post_action 03:22:58 INFO - Running post-action listener: _start_resource_monitoring 03:22:58 INFO - Starting resource monitoring. 03:22:58 INFO - [mozharness: 2017-07-22 10:22:58.737239Z] Finished create-virtualenv step (success) 03:22:58 INFO - [mozharness: 2017-07-22 10:22:58.738239Z] Running install step. 03:22:58 INFO - Running pre-action listener: _resource_record_pre_action 03:22:58 INFO - Running main action method: install 03:22:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:22:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:22:58 INFO - Reading from file tmpfile_stdout 03:22:58 INFO - Detecting whether we're running mozinstall >=1.0... 03:22:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:22:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:22:59 INFO - Reading from file tmpfile_stdout 03:22:59 INFO - Output received: 03:22:59 INFO - Usage: mozinstall [options] installer 03:22:59 INFO - Options: 03:22:59 INFO - -h, --help show this help message and exit 03:22:59 INFO - -d DEST, --destination=DEST 03:22:59 INFO - Directory to install application into. [default: 03:22:59 INFO - "/builds/slave/test"] 03:22:59 INFO - --app=APP Application being installed. [default: firefox] 03:22:59 INFO - mkdir: /builds/slave/test/build/application 03:22:59 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'] 03:22:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 03:23:17 INFO - Reading from file tmpfile_stdout 03:23:17 INFO - Output received: 03:23:17 INFO - /builds/slave/test/build/application/firefox/firefox 03:23:17 INFO - Running post-action listener: _resource_record_post_action 03:23:17 INFO - [mozharness: 2017-07-22 10:23:17.053800Z] Finished install step (success) 03:23:17 INFO - [mozharness: 2017-07-22 10:23:17.054050Z] Running setup-mitmproxy step. 03:23:17 INFO - Running pre-action listener: _resource_record_pre_action 03:23:17 INFO - Running main action method: setup_mitmproxy 03:23:17 INFO - Skipping: mitmproxy is not required 03:23:17 INFO - Running post-action listener: _resource_record_post_action 03:23:17 INFO - [mozharness: 2017-07-22 10:23:17.055181Z] Finished setup-mitmproxy step (success) 03:23:17 INFO - [mozharness: 2017-07-22 10:23:17.055403Z] Running run-tests step. 03:23:17 INFO - Running pre-action listener: _resource_record_pre_action 03:23:17 INFO - Running pre-action listener: _set_gcov_prefix 03:23:17 INFO - Running main action method: run_tests 03:23:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:23:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:23:17 INFO - Python 2.7.3 03:23:17 INFO - Return code: 0 03:23:17 INFO - grabbing minidump binary from tooltool 03:23:17 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')]} 03:23:17 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 0x1d78eb0>, '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 0x1d79db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d7a240>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:23:17 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 03:23:17 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 03:23:17 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 03:23:17 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:23:17 INFO - Return code: 0 03:23:17 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 03:23:17 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:23:17 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:23:17 INFO - ENV: RUST_BACKTRACE is now 1 03:23:17 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 03:23:17 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:23:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/RwKFdA9lQGydGPWJvR_XgA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-051', '--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 03:23:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/RwKFdA9lQGydGPWJvR_XgA/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-051 --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 03:23:17 INFO - Using env: {'DISPLAY': ':0', 03:23:17 INFO - 'HOME': '/home/cltbld', 03:23:17 INFO - 'LANG': 'en_US.UTF-8', 03:23:17 INFO - 'LANGUAGE': 'en_US:en', 03:23:17 INFO - 'LOGNAME': 'cltbld', 03:23:17 INFO - 'MAIL': '/var/mail/cltbld', 03:23:17 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:23:17 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:23:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:23:17 INFO - 'MOZ_NO_REMOTE': '1', 03:23:17 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:23:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:23:17 INFO - 'NO_EM_RESTART': '1', 03:23:17 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:23:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:23:17 INFO - 'PWD': '/builds/slave/test', 03:23:17 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:23:17 INFO - 'RUST_BACKTRACE': '1', 03:23:17 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 03:23:17 INFO - 'SHELL': '/bin/bash', 03:23:17 INFO - 'SHLVL': '1', 03:23:17 INFO - 'STYLO_FORCE_ENABLED': '1', 03:23:17 INFO - 'TERM': 'linux', 03:23:17 INFO - 'TMOUT': '86400', 03:23:17 INFO - 'USER': 'cltbld', 03:23:17 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1500716795.557513-437887472', 03:23:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:23:17 INFO - '_': '/tools/buildbot/bin/python'} 03:23:17 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/RwKFdA9lQGydGPWJvR_XgA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-051', '--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 03:23:17 INFO - mozversion application_buildid: 20170722093904 03:23:17 INFO - mozversion application_changeset: a599289ac64ba1d52a1552e33150342746e3e61b 03:23:17 INFO - mozversion application_display_name: Nightly 03:23:17 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:23:17 INFO - mozversion application_name: Firefox 03:23:17 INFO - mozversion application_remotingname: firefox 03:23:17 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 03:23:17 INFO - mozversion application_vendor: Mozilla 03:23:17 INFO - mozversion application_version: 56.0a1 03:23:17 INFO - mozversion platform_buildid: 20170722093904 03:23:17 INFO - mozversion platform_changeset: a599289ac64ba1d52a1552e33150342746e3e61b 03:23:17 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 03:23:17 INFO - mozversion platform_version: 56.0a1 03:23:17 INFO - using testdate: 1500718997 03:23:17 INFO - actual date: 1500718997 03:23:17 INFO - starting webserver on 'localhost:43300' 03:23:17 INFO - SUITE-START | Running 2 tests 03:23:17 INFO - TEST-START | dromaeo_css 03:23:17 INFO - Initialising browser for dromaeo_css test... 03:23:17 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:43300/getInfo.html -profile /tmp/tmpEQjsR2/profile 03:23:17 INFO - TEST-INFO | started process 3395 (/builds/slave/test/build/application/firefox/firefox http://localhost:43300/getInfo.html) 03:23:26 INFO - TEST-INFO | 3395: exit 0 03:23:26 INFO - Browser initialized. 03:23:26 INFO - Running cycle 1/1 for dromaeo_css test... 03:23:26 INFO - TEST-INFO | started process 3630 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpEQjsR2/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 03:23:38 INFO - PID 3630 | RSS: Main: 177074176 03:23:38 INFO - PID 3630 | 03:26:37 INFO - PID 3630 | Cycle 1(1): loaded http://localhost:43300/tests/dromaeo/cssquery-dojo.html (next: http://localhost:43300/tests/dromaeo/cssquery-ext.html) 03:26:39 INFO - PID 3630 | RSS: Main: 182042624 03:26:39 INFO - PID 3630 | 03:29:40 INFO - PID 3630 | Cycle 1(1): loaded http://localhost:43300/tests/dromaeo/cssquery-ext.html (next: http://localhost:43300/tests/dromaeo/cssquery-jquery.html) 03:29:41 INFO - PID 3630 | RSS: Main: 179793920 03:29:41 INFO - PID 3630 | 03:32:43 INFO - PID 3630 | Cycle 1(1): loaded http://localhost:43300/tests/dromaeo/cssquery-jquery.html (next: http://localhost:43300/tests/dromaeo/cssquery-mootools.html) 03:32:44 INFO - PID 3630 | RSS: Main: 181047296 03:32:44 INFO - PID 3630 | 03:35:46 INFO - PID 3630 | Cycle 1(1): loaded http://localhost:43300/tests/dromaeo/cssquery-mootools.html (next: http://localhost:43300/tests/dromaeo/cssquery-prototype.html) 03:35:46 INFO - PID 3630 | RSS: Main: 180498432 03:35:46 INFO - PID 3630 | 03:38:50 INFO - PID 3630 | Cycle 1(1): loaded http://localhost:43300/tests/dromaeo/cssquery-prototype.html (next: http://localhost:43300/tests/dromaeo/cssquery-yui.html) 03:38:51 INFO - PID 3630 | RSS: Main: 181649408 03:38:51 INFO - PID 3630 | 03:41:53 INFO - PID 3630 | Cycle 1(1): loaded http://localhost:43300/tests/dromaeo/cssquery-yui.html (next: http://localhost:43300/tests/dromaeo/cssquery-dojo.html) 03:41:53 INFO - PID 3630 | RSS: Main: 184655872 03:41:53 INFO - PID 3630 | 03:41:53 INFO - PID 3630 | __start_tp_report 03:41:53 INFO - PID 3630 | _x_x_mozilla_page_load 03:41:53 INFO - PID 3630 | _x_x_mozilla_page_load_details 03:41:53 INFO - PID 3630 | |i|pagename|runs| 03:41:53 INFO - PID 3630 | |0;dojo.html;3127.452695778238;3150.952735708964;3156.8263745493987;3165.8575364108606;3178.253110519028;6025.337212906587;6027.668478233695;6074.088886667002;6120.908186377203;6209.689515524223;6094.969525152368;6126.724297406617;6137.263528376601;6140.078988151779;6168.506519478442;5591.077472217079;5619.409961953977;5635.098384258509;5643.294588176478;5739.053056245735;5633.070543360361;5652.830415087554;5675.2622159119255;5700.828975130753;5722.914156287659;5445.346558413015;5587;5647.689377084259;5673.744681489343;5709.229254050712;5612.07400778871;5643.774249030034;5672.40439753824;5682.971585142089;5684.118961560924;5555.638883472602;5557.055300598868;5632.718364081779;5652.378238393773;5659.207710920475;5809.419058094199;5966.075258334965;5968.522518198533;6020.759169633209;6086.1783659205885;6591.769288074895;6616.106825578533;6620.801375958729;6650.4679625630315;6677.766278180289;6616.139901812781;6616.669166541654;6693.0964319816685;6726.42825359845;6739.5282330236905;6054.364291749391;6150;6170.7223174957235;6238.750449981995;6272.027835685476;6655.434288085567;6725.596464212163;6761.526693131434;6815.2843951385385;6831.726730930806;6357.777477788348;6452.645104519296;6520.967395162994;6543.345665433419;6547.770828020996;6429.935700643027;6496.155499785093;6560.311167327457;6565.934340656628;6581.473482121419;6409.935900640934;6501.642409667514;6561.015847622799;6584.868302634016;6672.199336079701;6546.738130474823;6597.934020659732;6629.138212032405;6637.4026337628875;6640.634765087966;5218.765155568009;5255.500727430888;5255.553277971415;5296.894062118736;5338.8665283367445;4526.456825180933;4537.77311134442;4542.772861356919;4549.7497637629585;4646.628269738413;5721.885562288814;5750.453706897838;5772.220750198753;5778.248827652378;5786.88426231473;6611.801645950629;6613.371729685672;6685.933140668629;6697.028930805006;6702.095217145623;5642;5672.631278966936;5715.371309155989;5727.656340619576;5794.681292528867;5813.796517121965;5850.297964244318;5854.677992710356;5863.70681465925;5876.471117599436;6273.8745225095245;6368;6449.645269510128;6468.708908099147;6477.319881412383;6506.3818937200895;6589.868202636017;6593.011048342787;6612.801615951529;6671.933280667131;6417.454516366161;6438;6484.416402523795;6503.512236582181;6512.34876512345;6480.57876238043;6480.902786458301;6568.047633093079;6570.5729127606555;6576.76981305652;6540.574862633914;6621.238557565749;6642.070110184674;6643.833904152435;6682.498812588978;6344.555881088279;6475.514336424692;6485.837854053498;6488.221413430419;6492.058651495601;6423.261324947691;6478.6760661967655;6507.316731743099;6517.055027021151;6527.771527996498;6354.682265886595;6359.554831161774;6378.617282963037;6423.655365791359;6457.61254324742;6313.684315784284;6437.227532696106;6468.967655161694;6518.185226846645;6531.11829902976;6266.154069200599;6285.654289014148;6293.590916590407;6304.6847657617855;6336.081268216175 03:41:53 INFO - PID 3630 | |1;ext.html;826.6238861318101;857.352698712472;879.881216035835;888.8000199955011;1141.6632093532407;30176.245593860138;41382.793086034566;42378.364324535156;42523.36214956778;42566.36150457738;42281.57718422815;42812.35781463273;42882.35676464848;42929.35605965913;42931.14137717244;7101;7511.511751736147;7674.846503069935;7775.416843736714;7779.8055048623955;7558.962205188966;7667.8083047923965;7676.4242681798805;7693.846123077535;7694.7306844260465;7642.503237289586;7665.348445382121;7685.077790665128;7707.730229441971;7745.7676269712;7642.159362470124;7703.807404814925;7708.152103268636;7709.151993280707;7710.498817576839;7524.736634217831;7540.509866858692;7589.962050189713;7591.164971853092;7591.620418979084;39176.608233917585;39471.60528394737;39498;39589.20821583581;39666;3538.2569660371355;3581.230035542371;3587.4798154267746;3590.838412271454;3592.5868525119686;30830.229244269074;30915.690843091506;31447;31490.842545787353;31563;4746.857594272177;4895.265710143472;4922.335484709554;5003.624728145403;5069.695818250917;187386;191471;191577;192084;192477.03761481243;47756;47946.280805787945;48238.27642585364;48249.863758855565;50027.499725003014;52859.73570132202;53023.46976530262;53113.7344313276;53168.734156328974;53292.73353633207;16294.103824289778;16353.672926541403;16445.424410145588;16521.91739041321;16557;13224.537141200204;13383.197008179346;13387.665308367174;13424.463021479227;13455.125416847874;14921.3285402155;14945.103293802407;14950.028248163835;15169.014014089049;15196.088234705952;5787.450192231731;5926.288845338587;6019.231153669361;6135.509159267248;6209.9068513972325;949.093615597103;985.5909797433984;993.1607791416242;1028.1158203944601;1031.83490641497;23112.768872311397;23613.645795313085;23648.290551283488;23810.88094559516;24131.51736965251;1085.234909388879;1086.6577028236081;1093.2456604942565;1108.5898217659517;1128.5203788389902;11797.646070617895;12704.936475317565;12725;12783.744325113446;12926.41831117602;246496;247838;250029;250897;252154;53951.460485394644;54028;54265.72867135718;54453.72773136109;54528;1431.6356562079031;1466.281522054207;1472.955811325662;1480.5780352599456;1485.9405623775144;22279.220227291968;22620.077293429586;22678;22782.772172278066;22831.771682282964;37794.81102594469;37949.241015180094;37963.43054854124;38011;38071.61928380681;206697;228156;231139;232492;233299;38710.612893870704;38828.61171388363;38959.80520097382;39605.60394396019;39651.80174099226;48023.27965080596;49002.75498622484;49152.26271605857;49525.25712114249;49617;32506.512402314456;35405.114872128404;35559.288814224084;35664.64335356717;36300.455493167094;33733.32533349368;34385.484217737256;34708.305833883685;34835.12912177216;35532.46701299431;38510.61489385182;38612.227755444175;38816.80591597024;38858.6114138855;38936.61063389443;33321.16697082592;34000.82999584986;34221;34407.827960860035;34473.310533789685;23143;23962.88018559896;24561.263162105166;24604.630930536416;24614.753852461246 03:41:53 INFO - PID 3630 | |2;jquery.html;562.600553606939;563.4590792838875;570.2273419516557;578.4186892173365;601.2964831147557;22426;23158.536829263405;23161.18935837246;23213.883930580323;23315.533689326225;24965.50068998619;27766;27789;27890.442191156166;27981;6496.545241833073;6669.43309818666;6699.464042876582;6701.530892837503;6703.832404189886;5736.196932429463;6346.460550853183;6585.407313341799;6595.0766892635065;6596.109525214107;6283.120363149163;6325.778597749079;6337.588056776318;6342.270638876542;6349.682515874211;6319.462845658125;6326.968365158191;6342.841428964267;6345.682715864188;6351.936480635204;5925.911111333334;5936.940630593682;5950.0479923212515;5959.851003724898;5960.225170727821;28699.713002869914;29595;30086.849565752033;30135.698643013508;30474.54288185679;1902.9524261893425;1950.1029526417865;1950.502621831438;1953.2772874036555;1954.912028958686;18824.5293867653;19197.61604767911;19222.711659325123;19238.807611923843;19247.326343578046;2065.132644289402;2180.2478145039936;2188.912443502258;2226.5101677631055;2232.441889527618;133409;135750.6424935748;135906;135979;136101;14069.437222510986;15147.54557363281;15198.696026079417;15379.462388705739;15411;23639;23644.645330320058;23664.526709465714;23667.171648992204;23672.64491032636;9344.486053267136;10418.583256669799;10451.790964180826;10502.789944201226;10504.947475262728;9678.903210967941;9689.176420004238;9736.123748862636;9740.220782337396;9868.703938881845;8913.24237439812;9379.577918993662;9470.337076404585;9488.667896623585;9540.236781057638;2588.9482210355686;2601.3236558494928;2702.8918843246443;2762.4751297253456;2804.7615952644255;954.190956357344;954.3844220477753;967.8790151231096;996.1582462818907;1003.7841863999275;18939;18980.525486862938;19055.142518586697;19453.902730486257;19730.90134549318;971.3831716859845;1031.7936412717486;1034.8706411698538;1044.4307852220559;1046.853440518328;10470;10785.245032847783;10934.4532773361;10977.506012229469;11035.668929932115;484932;497261;497371;499882;500333;114260;117862.41068794602;118062.40968795274;118635;120372;1244.4524409260077;1294.4045738960183;1321.1214542329435;1329.6210579984659;1331.793571996342;16587.91706041462;16954;17334.566635834206;17617.383391581232;18122.546936326697;51345;54233.72883135559;54722.45277547333;55151.44848551624;56602;71822.64088679732;74676;76148;77209;77708.2229177701;12496.43766030549;12876.806847897476;13172.868271317164;14750.778738319148;15140.621484462976;17451.214695338487;21402.464938376666;21415.250466233607;21519.677204841628;24347;18820.15309311057;19122.235110595422;19290.4212873614;19491.902540487208;19622.018899054703;16159.676806463569;16719.24763385675;17043;17366.82633173652;18054.368097116538;17189.226484807965;17589.736153957958;17753.73369399486;17775.644487110443;18847.434576962714;13880.791788122984;14155.221462819643;14159.292035398395;14182.574522764333;14187.858121418654;17122.315107395563;17538.386156484463;17565.82434175693;17605;17618.82381176223 03:41:53 INFO - PID 3630 | |3;mootools.html;1359.7484465373905;1427.264958546349;1436.7270218658455;1441.243347242698;1451.8765904898082;13534.796978045324;13619.591412257623;14085.436582536711;14093.92953035236;14120.082194657365;14018.228997405138;14394.712105757879;14395.06432081914;14426.134431934091;14428.783568246485;2608.6739157605252;2651.6950550686724;2652.3634327761392;2653.8275012124245;2654.4425670609194;2580.354911272182;2657.9069732559365;2673.8128331016837;2721.7958653101095;2730.781537477007;2674.1442738323744;2705.9864700676467;2712.5388683923684;2712.8643567821628;2714.76924461421;2619.7118316985116;2628.672520377216;2656.6546348974703;2673.692525359589;2674.812763106583;2572.1383336582267;2599.3111825366104;2607.6218948252586;2632.8946842126484;2657.7740892024203;13846.30768461573;13852.238126903016;13918.860811391858;13920.37358318878;13983.65040873976;7335.339819416225;7631.771046868603;7680.347170490515;7703.961480192619;7705.653245603961;4796.18464860971;4952.380952380952;5078.289039534468;5080.110980578421;5121.692698438068;1825.2242796811302;1832.6517961587256;1846.0954132475124;1846.9696145304156;1882;22754;23204.41988950273;23268.06927722889;23297.301080967598;23299.76700232993;6453.161089058486;6746.123004009545;6774.966125169342;6820.6930688119155;6823.488238382141;14881.627959301104;14967.925160374129;15012.324445399983;15039.699206015819;15066.09603423798;7060.399866011447;7195;7332.156801967842;7372.152202496674;7373.778786636302;6523.543351965317;6585.506087043395;6593.307702691244;6651.467882569383;6671.366220209073;5054.09026375256;5180.740962951837;5195.467337135554;5220.791168353223;5247.790088396498;1342.6710455938357;1357.959261222165;1361.8025386318927;1524.6569521857493;1532.486616983323;832.8417600655856;852.2925971443686;865.3336930563431;883.2580632268925;895.9552022398854;7770.92229077702;8037.8392432152195;8068.0721717002125;8126.349892008625;8141.796455088552;930.9255259579218;963.2919803944095;966.8163049020663;974.7660561465198;991.504247876062;4263.339182426729;4408.030233348658;4894.926576101361;5114.053900028507;5161.406438259573;18950.52623684419;19770;19785.307514236934;19965.60068798616;19985.800141998396;18451.90774046121;19400.59551419218;19509.219631214877;19660.410187694106;19714.112864921113;10474.947625261824;10716.89283107159;10730.78538429227;10764.946175269073;10814.94592527048;7221.53060051095;7815.374770018271;8132.2680958714;8254.257116859511;8435.650295952864;15713.371465141267;16179.676406472041;16297.918510407371;16640.417585384454;16640.750388743938;16521.586960325607;16801.243944022794;16922.49232523026;16954.406595769087;17032.063236521615;11090.168237382068;11139.610113645984;11227;11271.154663400443;11353.943230283796;12703.174293670882;12793.42429590652;12963.287019214034;12967.092303538662;12989.545365912149;8213.671453141807;10369.74075648115;10552.261341706007;10645.148388129086;10846.078083362687;9870.703878883645;10513;10592.947035264775;10620.415877126832;10807.783844323227;8973.730788076367;9216.585253663736;9222.815543688954;9223.76940576464;9233.630654773733;6271.78048768291;6297.937020629735;6641.667916604054;6760.594364338155;6850.794476165723;6683.298253683293;6926.134233220848;6943.722251109899;7021.403180729695;7030.121234845645 03:41:53 INFO - PID 3630 | |4;prototype.html;493.76774628230413;494.9902199512995;518.914379127444;520.8046982381607;526.689253340529;6026.246719160105;6090.147379366892;6163.445289923906;6184.350643182469;6186.381361863811;5754.424557544244;6069.150318955339;6069.239920423083;6085.817425477243;6087.20866287382;4847.684900481458;4914.852554423364;4958.652894297401;4976.800927962877;4986.800527978877;4819.578099549324;4904.75476226189;4952.306677065214;4963.230699241623;5004.12427825131;4870.4885986971385;4875.146849301375;4881.707097574157;4921.680090794105;4928;4829.975850120762;4835.371401717789;4888.584470320027;4894.730789806577;4953.653244272902;4380.123975204971;4430.822767089313;4439.2675208590545;4485.282354823245;4512.796924138422;6017.819465416044;6024.277086749575;6025.276966763972;6031.215941927565;6050.939490605082;5486.177073438977;5519.806806761744;5638.887222255532;5658.886822263573;5683.403242659544;6388.361163883621;6426.775062872824;6436.839079023015;6475.294399664384;6486.610803351814;4792.9281060784115;4810.470848206694;4860.246661767432;4878.317035615017;4892.168331383637;6679.666016699146;6737.292584278678;6784.694688739019;6808.931910680879;6827.146606674166;4921.557059864629;4932.704037757746;4981.007596961172;4992.703356455998;5008.724520151354;5007.023630392111;5014.640191071991;5030.8239211627415;5045.672031317952;5055.51972562606;5578.995780759502;5667.263255776723;5689.288838895138;5713.371529131792;5724.427557244326;6435.839104022343;6472.579282346633;6472.8672482315405;6534.640594767334;6536.869262614816;1663.2349119404967;1687.2323092992658;1710.8203638618015;1728.6801941640836;1743.9536278233213;1196.1865931166717;1212.8908398244023;1222.413241644016;1226.9141160118884;1227.3433712963522;5627.831165065055;5674.574406919498;5693.345265294544;5701.40135285789;5705.828825135252;6551.705173267214;6608.240052394036;6699.5261042570555;6712.160979877515;6769.323067693192;5709.457601527932;5748.9712551437815;5776.537876969833;5809.390014048548;5833.620814647119;3506.333796578642;3580.3018411409525;3591.838367273479;3605.5673319201865;3626.6192049834917;6452.419282264619;6453.128827608307;6545.672716364163;6568.211814582185;6584.38682522785;6724.462043036546;6763.188417389846;6841.692123854438;6847.6446541194455;6930.930690693029;6395.936040639534;6486.772962946275;6509.251436084911;6538.934610653928;6546.378094081055;6445.548811583144;6695.296993815774;6741.831454213684;6764.255931847394;6777.966110169417;6509.446697030806;6529.347065293623;6584.242812076673;6606.768763093269;6648.108072988266;6199.194104766532;6328.746850125944;6378.428080275061;6391.621739108726;6428.874946884183;5271.815486457956;5382.811601593926;5415.268938693226;5417.4040855506655;5433.517348187682;6351.30135685065;6624.867502649824;6637.601743895381;6654.633995130314;6688.966555167193;6316.399942005502;6320.810375688737;6376.936230637634;6431.839204019937;6455.419012288917;6427.871442571216;6433.613983161025;6435.195600549931;6441.227052753699;6460.160179176677;6185.938140618537;6330.778422755181;6347.238331400077;6364.236291645032;6368.64972426521;6422.49071468214;6439;6445.645489497935;6469.673716888121;6553.968269836366;6221.129041934042;6264.874702505833;6305.464035557029;6315.715792789428;6329.272133704683 03:41:53 INFO - PID 3630 | |5;yui.html;895.4269267668693;921.8386782313094;922.6770630279407;939.4973689076342;943.9669611563593;18501.814981850177;19249.133788979496;19453;19601.019949002548;21565.1373945042;20698.68951965722;21314;21387;21540.676889846665;21603.783962160374;1008.7074748322996;1045.4093437207991;1046.3303485769113;1046.4140081554353;1048.2976405808126;1019;1059.9629012984508;1064.8668916385452;1067.8665166854144;1070.6787963610925;1127.149002503109;1129.1813435259412;1132.0717012050122;1132.286659404574;1132.8867113288688;1119.3451830679062;1123.4888125902694;1127.661701489554;1129.8983091521761;1130.2540323386565;814.6863457568819;821.7165078048124;822.6585966823761;822.9670813167467;825.0429501777969;19682.114304856317;19708.605827883504;19961.201551937906;20065.297714580065;20425.69361459579;19909.601807963758;20207.393778186677;20473.692894606593;20917.372478825506;20921.68617470724;16241.83758162427;16321.673566528721;16394.26225819841;16510.66978660432;16526.83473165265;3564.0555252857753;3587.3363427765685;3606.5852426970973;3607.4228123500116;3639.2721455708966;125057;129494.35252823676;130426;132688;134076;15046.54860354191;15239.085654860522;15437;15697.293621787047;15910.920445397698;29954.55068173979;30417.239569010508;30465.390692186033;30644.69355306463;31024.0692779217;7360.631968401559;7729.343005844454;8052.758417247492;8452.915470845213;8875.201231889161;4098.508179018478;4215.093754842724;4237.470316210473;4245.639120674778;4258.233517966733;5080.263361812516;5105.412877519058;5117.974410127926;5167;5242.292290540729;998.2862253488645;1007.8185926533148;1013.8935411781657;1016.4054028393473;1024.4314405504867;651.2445563146726;664.6543797225415;690.1432510920839;700.2087640965676;707.5683832861951;11640.708982275512;12206.328651924052;12639.368031598384;12939.870601294055;13303.268320242287;678.6471035061741;709.1631874388271;715.2597062040763;728.0062714394813;728.3372131360436;5349.491798279158;5624.29696287964;5693.715314234271;5765.135229715576;5941.1088336749835;102253;102871;103134;108283;111548.44225778819;87238.56380718182;88934.55532722294;93793.5310323444;93970.53014734882;95418;77783.22216777873;81181.59409202916;81685.18314816775;82527.587362064;83503;11064.612738554111;11430.25703329281;11565.901239382581;11637.43842364532;12230.633081007583;34914.36558904372;36074.819625901706;36697.26605467929;37589.624103758615;38528.2294354117;66250;68502.65748671225;68958.65520672365;71091;72685;18338;18425.815741842947;18636.813631863508;18688;18887.527811804815;20984.47538811481;21000.474988125417;21007.159713611283;21109.26117585877;21118.683219752023;17287.222075006906;17768.200430980378;17939.192736327157;17977;17989;18560.257589696263;18638.72041919345;18702.81297187065;18710.625787483903;18830;17639.470815875546;17821.28714851443;17885.19516621729;17998.190081446628;18062.81937180664;13052.608421747364;13639.249841258428;13931.303434828016;14180.43278268899;14313.713725725636;12683.492660293854;13348;13404.128731632412;13554.322283885964;13675.658108546968 03:41:53 INFO - PID 3630 | __end_tp_report 03:41:53 INFO - PID 3630 | __start_cc_report 03:41:53 INFO - PID 3630 | _x_x_mozilla_cycle_collect,372 03:41:53 INFO - PID 3630 | __end_cc_report 03:41:53 INFO - PID 3630 | __startTimestamp1500720113307__endTimestamp 03:41:53 INFO - PID 3630 | 03:41:53 INFO - PID 3630 | ------- Summary: start ------- 03:41:53 INFO - PID 3630 | Number of tests: 6 03:41:53 INFO - PID 3630 | 03:41:53 INFO - PID 3630 | [#0] dojo.html Cycles:165 Average:6076.30 Median:6344.56 stddev:716.44 (11.3%) stddev-sans-first:680.27 03:41:53 INFO - PID 3630 | Values: 3127.5 3151.0 3156.8 3165.9 3178.3 6025.3 6027.7 6074.1 6120.9 6209.7 6095.0 6126.7 6137.3 6140.1 6168.5 5591.1 5619.4 5635.1 5643.3 5739.1 5633.1 5652.8 5675.3 5700.8 5722.9 5445.3 5587.0 5647.7 5673.7 5709.2 5612.1 5643.8 5672.4 5683.0 5684.1 5555.6 5557.1 5632.7 5652.4 5659.2 5809.4 5966.1 5968.5 6020.8 6086.2 6591.8 6616.1 6620.8 6650.5 6677.8 6616.1 6616.7 6693.1 6726.4 6739.5 6054.4 6150.0 6170.7 6238.8 6272.0 6655.4 6725.6 6761.5 6815.3 6831.7 6357.8 6452.6 6521.0 6543.3 6547.8 6429.9 6496.2 6560.3 6565.9 6581.5 6409.9 6501.6 6561.0 6584.9 6672.2 6546.7 6597.9 6629.1 6637.4 6640.6 5218.8 5255.5 5255.6 5296.9 5338.9 4526.5 4537.8 4542.8 4549.7 4646.6 5721.9 5750.5 5772.2 5778.2 5786.9 6611.8 6613.4 6685.9 6697.0 6702.1 5642.0 5672.6 5715.4 5727.7 5794.7 5813.8 5850.3 5854.7 5863.7 5876.5 6273.9 6368.0 6449.6 6468.7 6477.3 6506.4 6589.9 6593.0 6612.8 6671.9 6417.5 6438.0 6484.4 6503.5 6512.3 6480.6 6480.9 6568.0 6570.6 6576.8 6540.6 6621.2 6642.1 6643.8 6682.5 6344.6 6475.5 6485.8 6488.2 6492.1 6423.3 6478.7 6507.3 6517.1 6527.8 6354.7 6359.6 6378.6 6423.7 6457.6 6313.7 6437.2 6469.0 6518.2 6531.1 6266.2 6285.7 6293.6 6304.7 6336.1 03:41:53 INFO - PID 3630 | 03:41:53 INFO - PID 3630 | [#1] ext.html Cycles:180 Average:39715.26 Median:37956.34 stddev:58065.70 (153.0%) stddev-sans-first:58155.17 03:41:53 INFO - PID 3630 | Values: 826.6 857.4 879.9 888.8 1141.7 30176.2 41382.8 42378.4 42523.4 42566.4 42281.6 42812.4 42882.4 42929.4 42931.1 7101.0 7511.5 7674.8 7775.4 7779.8 7559.0 7667.8 7676.4 7693.8 7694.7 7642.5 7665.3 7685.1 7707.7 7745.8 7642.2 7703.8 7708.2 7709.2 7710.5 7524.7 7540.5 7590.0 7591.2 7591.6 39176.6 39471.6 39498.0 39589.2 39666.0 3538.3 3581.2 3587.5 3590.8 3592.6 30830.2 30915.7 31447.0 31490.8 31563.0 4746.9 4895.3 4922.3 5003.6 5069.7 187386.0 191471.0 191577.0 192084.0 192477.0 47756.0 47946.3 48238.3 48249.9 50027.5 52859.7 53023.5 53113.7 53168.7 53292.7 16294.1 16353.7 16445.4 16521.9 16557.0 13224.5 13383.2 13387.7 13424.5 13455.1 14921.3 14945.1 14950.0 15169.0 15196.1 5787.5 5926.3 6019.2 6135.5 6209.9 949.1 985.6 993.2 1028.1 1031.8 23112.8 23613.6 23648.3 23810.9 24131.5 1085.2 1086.7 1093.2 1108.6 1128.5 11797.6 12704.9 12725.0 12783.7 12926.4 246496.0 247838.0 250029.0 250897.0 252154.0 53951.5 54028.0 54265.7 54453.7 54528.0 1431.6 1466.3 1473.0 1480.6 1485.9 22279.2 22620.1 22678.0 22782.8 22831.8 37794.8 37949.2 37963.4 38011.0 38071.6 206697.0 228156.0 231139.0 232492.0 233299.0 38710.6 38828.6 38959.8 39605.6 39651.8 48023.3 49002.8 49152.3 49525.3 49617.0 32506.5 35405.1 35559.3 35664.6 36300.5 33733.3 34385.5 34708.3 34835.1 35532.5 38510.6 38612.2 38816.8 38858.6 38936.6 33321.2 34000.8 34221.0 34407.8 34473.3 23143.0 23962.9 24561.3 24604.6 24614.8 03:41:53 INFO - PID 3630 | 03:41:53 INFO - PID 3630 | [#2] jquery.html Cycles:180 Average:34913.43 Median:2207.71 stddev:83527.12 (3783.4%) stddev-sans-first:83721.61 03:41:53 INFO - PID 3630 | Values: 562.6 563.5 570.2 578.4 601.3 22426.0 23158.5 23161.2 23213.9 23315.5 24965.5 27766.0 27789.0 27890.4 27981.0 6496.5 6669.4 6699.5 6701.5 6703.8 5736.2 6346.5 6585.4 6595.1 6596.1 6283.1 6325.8 6337.6 6342.3 6349.7 6319.5 6327.0 6342.8 6345.7 6351.9 5925.9 5936.9 5950.0 5959.9 5960.2 28699.7 29595.0 30086.8 30135.7 30474.5 1903.0 1950.1 1950.5 1953.3 1954.9 18824.5 19197.6 19222.7 19238.8 19247.3 2065.1 2180.2 2188.9 2226.5 2232.4 133409.0 135750.6 135906.0 135979.0 136101.0 14069.4 15147.5 15198.7 15379.5 15411.0 23639.0 23644.6 23664.5 23667.2 23672.6 9344.5 10418.6 10451.8 10502.8 10504.9 9678.9 9689.2 9736.1 9740.2 9868.7 8913.2 9379.6 9470.3 9488.7 9540.2 2588.9 2601.3 2702.9 2762.5 2804.8 954.2 954.4 967.9 996.2 1003.8 18939.0 18980.5 19055.1 19453.9 19730.9 971.4 1031.8 1034.9 1044.4 1046.9 10470.0 10785.2 10934.5 10977.5 11035.7 484932.0 497261.0 497371.0 499882.0 500333.0 114260.0 117862.4 118062.4 118635.0 120372.0 1244.5 1294.4 1321.1 1329.6 1331.8 16587.9 16954.0 17334.6 17617.4 18122.5 51345.0 54233.7 54722.5 55151.4 56602.0 71822.6 74676.0 76148.0 77209.0 77708.2 12496.4 12876.8 13172.9 14750.8 15140.6 17451.2 21402.5 21415.3 21519.7 24347.0 18820.2 19122.2 19290.4 19491.9 19622.0 16159.7 16719.2 17043.0 17366.8 18054.4 17189.2 17589.7 17753.7 17775.6 18847.4 13880.8 14155.2 14159.3 14182.6 14187.9 17122.3 17538.4 17565.8 17605.0 17618.8 03:41:53 INFO - PID 3630 | 03:41:53 INFO - PID 3630 | [#3] mootools.html Cycles:180 Average:8597.34 Median:2642.29 stddev:5900.23 (223.3%) stddev-sans-first:5891.72 03:41:53 INFO - PID 3630 | Values: 1359.7 1427.3 1436.7 1441.2 1451.9 13534.8 13619.6 14085.4 14093.9 14120.1 14018.2 14394.7 14395.1 14426.1 14428.8 2608.7 2651.7 2652.4 2653.8 2654.4 2580.4 2657.9 2673.8 2721.8 2730.8 2674.1 2706.0 2712.5 2712.9 2714.8 2619.7 2628.7 2656.7 2673.7 2674.8 2572.1 2599.3 2607.6 2632.9 2657.8 13846.3 13852.2 13918.9 13920.4 13983.7 7335.3 7631.8 7680.3 7704.0 7705.7 4796.2 4952.4 5078.3 5080.1 5121.7 1825.2 1832.7 1846.1 1847.0 1882.0 22754.0 23204.4 23268.1 23297.3 23299.8 6453.2 6746.1 6775.0 6820.7 6823.5 14881.6 14967.9 15012.3 15039.7 15066.1 7060.4 7195.0 7332.2 7372.2 7373.8 6523.5 6585.5 6593.3 6651.5 6671.4 5054.1 5180.7 5195.5 5220.8 5247.8 1342.7 1358.0 1361.8 1524.7 1532.5 832.8 852.3 865.3 883.3 896.0 7770.9 8037.8 8068.1 8126.3 8141.8 930.9 963.3 966.8 974.8 991.5 4263.3 4408.0 4894.9 5114.1 5161.4 18950.5 19770.0 19785.3 19965.6 19985.8 18451.9 19400.6 19509.2 19660.4 19714.1 10474.9 10716.9 10730.8 10764.9 10814.9 7221.5 7815.4 8132.3 8254.3 8435.7 15713.4 16179.7 16297.9 16640.4 16640.8 16521.6 16801.2 16922.5 16954.4 17032.1 11090.2 11139.6 11227.0 11271.2 11353.9 12703.2 12793.4 12963.3 12967.1 12989.5 8213.7 10369.7 10552.3 10645.1 10846.1 9870.7 10513.0 10592.9 10620.4 10807.8 8973.7 9216.6 9222.8 9223.8 9233.6 6271.8 6297.9 6641.7 6760.6 6850.8 6683.3 6926.1 6943.7 7021.4 7030.1 03:41:53 INFO - PID 3630 | 03:41:53 INFO - PID 3630 | [#4] prototype.html Cycles:180 Average:5459.33 Median:6041.08 stddev:1521.41 (25.2%) stddev-sans-first:1479.33 03:41:53 INFO - PID 3630 | Values: 493.8 495.0 518.9 520.8 526.7 6026.2 6090.1 6163.4 6184.4 6186.4 5754.4 6069.2 6069.2 6085.8 6087.2 4847.7 4914.9 4958.7 4976.8 4986.8 4819.6 4904.8 4952.3 4963.2 5004.1 4870.5 4875.1 4881.7 4921.7 4928.0 4830.0 4835.4 4888.6 4894.7 4953.7 4380.1 4430.8 4439.3 4485.3 4512.8 6017.8 6024.3 6025.3 6031.2 6050.9 5486.2 5519.8 5638.9 5658.9 5683.4 6388.4 6426.8 6436.8 6475.3 6486.6 4792.9 4810.5 4860.2 4878.3 4892.2 6679.7 6737.3 6784.7 6808.9 6827.1 4921.6 4932.7 4981.0 4992.7 5008.7 5007.0 5014.6 5030.8 5045.7 5055.5 5579.0 5667.3 5689.3 5713.4 5724.4 6435.8 6472.6 6472.9 6534.6 6536.9 1663.2 1687.2 1710.8 1728.7 1744.0 1196.2 1212.9 1222.4 1226.9 1227.3 5627.8 5674.6 5693.3 5701.4 5705.8 6551.7 6608.2 6699.5 6712.2 6769.3 5709.5 5749.0 5776.5 5809.4 5833.6 3506.3 3580.3 3591.8 3605.6 3626.6 6452.4 6453.1 6545.7 6568.2 6584.4 6724.5 6763.2 6841.7 6847.6 6930.9 6395.9 6486.8 6509.3 6538.9 6546.4 6445.5 6695.3 6741.8 6764.3 6778.0 6509.4 6529.3 6584.2 6606.8 6648.1 6199.2 6328.7 6378.4 6391.6 6428.9 5271.8 5382.8 5415.3 5417.4 5433.5 6351.3 6624.9 6637.6 6654.6 6689.0 6316.4 6320.8 6376.9 6431.8 6455.4 6427.9 6433.6 6435.2 6441.2 6460.2 6185.9 6330.8 6347.2 6364.2 6368.6 6422.5 6439.0 6445.6 6469.7 6554.0 6221.1 6264.9 6305.5 6315.7 6329.3 03:41:53 INFO - PID 3630 | 03:41:53 INFO - PID 3630 | [#5] yui.html Cycles:180 Average:23326.49 Median:19935.40 stddev:31506.21 (158.0%) stddev-sans-first:31549.57 03:41:53 INFO - PID 3630 | Values: 895.4 921.8 922.7 939.5 944.0 18501.8 19249.1 19453.0 19601.0 21565.1 20698.7 21314.0 21387.0 21540.7 21603.8 1008.7 1045.4 1046.3 1046.4 1048.3 1019.0 1060.0 1064.9 1067.9 1070.7 1127.1 1129.2 1132.1 1132.3 1132.9 1119.3 1123.5 1127.7 1129.9 1130.3 814.7 821.7 822.7 823.0 825.0 19682.1 19708.6 19961.2 20065.3 20425.7 19909.6 20207.4 20473.7 20917.4 20921.7 16241.8 16321.7 16394.3 16510.7 16526.8 3564.1 3587.3 3606.6 3607.4 3639.3 125057.0 129494.4 130426.0 132688.0 134076.0 15046.5 15239.1 15437.0 15697.3 15910.9 29954.6 30417.2 30465.4 30644.7 31024.1 7360.6 7729.3 8052.8 8452.9 8875.2 4098.5 4215.1 4237.5 4245.6 4258.2 5080.3 5105.4 5118.0 5167.0 5242.3 998.3 1007.8 1013.9 1016.4 1024.4 651.2 664.7 690.1 700.2 707.6 11640.7 12206.3 12639.4 12939.9 13303.3 678.6 709.2 715.3 728.0 728.3 5349.5 5624.3 5693.7 5765.1 5941.1 102253.0 102871.0 103134.0 108283.0 111548.4 87238.6 88934.6 93793.5 93970.5 95418.0 77783.2 81181.6 81685.2 82527.6 83503.0 11064.6 11430.3 11565.9 11637.4 12230.6 34914.4 36074.8 36697.3 37589.6 38528.2 66250.0 68502.7 68958.7 71091.0 72685.0 18338.0 18425.8 18636.8 18688.0 18887.5 20984.5 21000.5 21007.2 21109.3 21118.7 17287.2 17768.2 17939.2 17977.0 17989.0 18560.3 18638.7 18702.8 18710.6 18830.0 17639.5 17821.3 17885.2 17998.2 18062.8 13052.6 13639.2 13931.3 14180.4 14313.7 12683.5 13348.0 13404.1 13554.3 13675.7 03:41:53 INFO - PID 3630 | -------- Summary: end -------- 03:41:53 INFO - PID 3630 | 03:41:53 INFO - TEST-INFO | 3630: exit 0 03:41:53 INFO - TEST-OK | dromaeo_css | took 1116442ms 03:41:53 INFO - TEST-START | kraken 03:41:53 INFO - Initialising browser for kraken test... 03:41:53 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:43300/getInfo.html -profile /tmp/tmp9j_wGk/profile 03:41:53 INFO - TEST-INFO | started process 4005 (/builds/slave/test/build/application/firefox/firefox http://localhost:43300/getInfo.html) 03:42:00 INFO - TEST-INFO | 4005: exit 0 03:42:00 INFO - Browser initialized. 03:42:00 INFO - Running cycle 1/1 for kraken test... 03:42:00 INFO - TEST-INFO | started process 4239 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp9j_wGk/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 03:42:11 INFO - PID 4239 | RSS: Main: 177385472 03:42:11 INFO - PID 4239 | 03:42:14 INFO - PID 4239 | Cycle 1(1): loaded http://localhost:43300/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:43300/tests/kraken/driver.html?testName=audio-beat-detection) 03:42:15 INFO - PID 4239 | RSS: Main: 182001664 03:42:15 INFO - PID 4239 | 03:42:18 INFO - PID 4239 | Cycle 1(1): loaded http://localhost:43300/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:43300/tests/kraken/driver.html?testName=audio-dft) 03:42:18 INFO - PID 4239 | RSS: Main: 182321152 03:42:18 INFO - PID 4239 | 03:42:21 INFO - PID 4239 | Cycle 1(1): loaded http://localhost:43300/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:43300/tests/kraken/driver.html?testName=audio-fft) 03:42:22 INFO - PID 4239 | RSS: Main: 176689152 03:42:22 INFO - PID 4239 | 03:42:24 INFO - PID 4239 | Cycle 1(1): loaded http://localhost:43300/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:43300/tests/kraken/driver.html?testName=audio-oscillator) 03:42:25 INFO - PID 4239 | RSS: Main: 177143808 03:42:25 INFO - PID 4239 | 03:42:27 INFO - PID 4239 | Cycle 1(1): loaded http://localhost:43300/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:43300/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:42:28 INFO - PID 4239 | RSS: Main: 177315840 03:42:28 INFO - PID 4239 | 03:42:31 INFO - PID 4239 | Cycle 1(1): loaded http://localhost:43300/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:43300/tests/kraken/driver.html?testName=imaging-darkroom) 03:42:31 INFO - PID 4239 | RSS: Main: 179810304 03:42:31 INFO - PID 4239 | 03:42:35 INFO - PID 4239 | Cycle 1(1): loaded http://localhost:43300/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:43300/tests/kraken/driver.html?testName=imaging-desaturate) 03:42:35 INFO - PID 4239 | RSS: Main: 180019200 03:42:35 INFO - PID 4239 | 03:42:38 INFO - PID 4239 | Cycle 1(1): loaded http://localhost:43300/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:43300/tests/kraken/driver.html?testName=json-parse-financial) 03:42:39 INFO - PID 4239 | RSS: Main: 180215808 03:42:39 INFO - PID 4239 | 03:42:41 INFO - PID 4239 | Cycle 1(1): loaded http://localhost:43300/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:43300/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:42:41 INFO - PID 4239 | RSS: Main: 180310016 03:42:41 INFO - PID 4239 | 03:42:43 INFO - PID 4239 | Cycle 1(1): loaded http://localhost:43300/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:43300/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:42:44 INFO - PID 4239 | RSS: Main: 180359168 03:42:44 INFO - PID 4239 | 03:42:46 INFO - PID 4239 | Cycle 1(1): loaded http://localhost:43300/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:43300/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:42:46 INFO - PID 4239 | RSS: Main: 180715520 03:42:46 INFO - PID 4239 | 03:42:49 INFO - PID 4239 | Cycle 1(1): loaded http://localhost:43300/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:43300/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:42:50 INFO - PID 4239 | RSS: Main: 181141504 03:42:50 INFO - PID 4239 | 03:42:53 INFO - PID 4239 | Cycle 1(1): loaded http://localhost:43300/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:43300/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:42:54 INFO - PID 4239 | RSS: Main: 181039104 03:42:54 INFO - PID 4239 | 03:42:55 INFO - PID 4239 | Cycle 1(1): loaded http://localhost:43300/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:43300/tests/kraken/driver.html?testName=ai-astar) 03:42:56 INFO - PID 4239 | RSS: Main: 182726656 03:42:56 INFO - PID 4239 | 03:42:56 INFO - PID 4239 | __start_tp_report 03:42:56 INFO - PID 4239 | _x_x_mozilla_page_load 03:42:56 INFO - PID 4239 | _x_x_mozilla_page_load_details 03:42:56 INFO - PID 4239 | |i|pagename|runs| 03:42:56 INFO - PID 4239 | |0;ai-astar;102;110;102;109;116;111;110;101;109;109 03:42:56 INFO - PID 4239 | |1;audio-beat-detection;159;118;144;185;119;152;150;122;165;119 03:42:56 INFO - PID 4239 | |2;audio-dft;163;164;164;164;163;163;164;166;163;164 03:42:56 INFO - PID 4239 | |3;audio-fft;76;75;76;166;77;77;82;73;157;75 03:42:56 INFO - PID 4239 | |4;audio-oscillator;82;81;136;81;81;81;124;81;82;126 03:42:56 INFO - PID 4239 | |5;imaging-gaussian-blur;114;114;118;114;113;114;118;115;113;114 03:42:56 INFO - PID 4239 | |6;imaging-darkroom;151;151;150;151;150;151;149;150;150;151 03:42:56 INFO - PID 4239 | |7;imaging-desaturate;99;99;99;99;94;94;95;94;94;94 03:42:56 INFO - PID 4239 | |8;json-parse-financial;54;58;53;55;55;54;54;53;54;54 03:42:56 INFO - PID 4239 | |9;json-stringify-tinderbox;54;55;55;55;54;55;54;54;54;55 03:42:56 INFO - PID 4239 | |10;stanford-crypto-aes;81;77;80;77;77;78;79;76;78;78 03:42:56 INFO - PID 4239 | |11;stanford-crypto-ccm;127;128;126;147;128;130;124;133;129;167 03:42:56 INFO - PID 4239 | |12;stanford-crypto-pbkdf2;190;179;193;178;184;177;178;180;179;198 03:42:56 INFO - PID 4239 | |13;stanford-crypto-sha256-iterative;69;71;69;72;71;68;69;67;69;69 03:42:56 INFO - PID 4239 | __end_tp_report 03:42:56 INFO - PID 4239 | __start_cc_report 03:42:56 INFO - PID 4239 | _x_x_mozilla_cycle_collect,1053 03:42:56 INFO - PID 4239 | __end_cc_report 03:42:56 INFO - PID 4239 | __startTimestamp1500720176230__endTimestamp 03:42:56 INFO - PID 4239 | 03:42:56 INFO - PID 4239 | ------- Summary: start ------- 03:42:56 INFO - PID 4239 | Number of tests: 14 03:42:56 INFO - PID 4239 | 03:42:56 INFO - PID 4239 | [#0] ai-astar Cycles:10 Average:107.90 Median:109.50 stddev:4.77 (4.4%) stddev-sans-first:4.56 03:42:56 INFO - PID 4239 | Values: 102.0 110.0 102.0 109.0 116.0 111.0 110.0 101.0 109.0 109.0 03:42:56 INFO - PID 4239 | 03:42:56 INFO - PID 4239 | [#1] audio-beat-detection Cycles:10 Average:143.30 Median:151.00 stddev:23.22 (15.4%) stddev-sans-first:23.92 03:42:56 INFO - PID 4239 | Values: 159.0 118.0 144.0 185.0 119.0 152.0 150.0 122.0 165.0 119.0 03:42:56 INFO - PID 4239 | 03:42:56 INFO - PID 4239 | [#2] audio-dft Cycles:10 Average:163.80 Median:164.00 stddev:0.92 (0.6%) stddev-sans-first:0.93 03:42:56 INFO - PID 4239 | Values: 163.0 164.0 164.0 164.0 163.0 163.0 164.0 166.0 163.0 164.0 03:42:56 INFO - PID 4239 | 03:42:56 INFO - PID 4239 | [#3] audio-fft Cycles:10 Average:93.40 Median:76.00 stddev:36.03 (47.4%) stddev-sans-first:37.66 03:42:56 INFO - PID 4239 | Values: 76.0 75.0 76.0 166.0 77.0 77.0 82.0 73.0 157.0 75.0 03:42:56 INFO - PID 4239 | 03:42:56 INFO - PID 4239 | [#4] audio-oscillator Cycles:10 Average:95.50 Median:81.00 stddev:23.09 (28.5%) stddev-sans-first:23.97 03:42:56 INFO - PID 4239 | Values: 82.0 81.0 136.0 81.0 81.0 81.0 124.0 81.0 82.0 126.0 03:42:56 INFO - PID 4239 | 03:42:56 INFO - PID 4239 | [#5] imaging-gaussian-blur Cycles:10 Average:114.70 Median:114.00 stddev:1.83 (1.6%) stddev-sans-first:1.92 03:42:56 INFO - PID 4239 | Values: 114.0 114.0 118.0 114.0 113.0 114.0 118.0 115.0 113.0 114.0 03:42:56 INFO - PID 4239 | 03:42:56 INFO - PID 4239 | [#6] imaging-darkroom Cycles:10 Average:150.40 Median:151.00 stddev:0.70 (0.5%) stddev-sans-first:0.71 03:42:56 INFO - PID 4239 | Values: 151.0 151.0 150.0 151.0 150.0 151.0 149.0 150.0 150.0 151.0 03:42:56 INFO - PID 4239 | 03:42:56 INFO - PID 4239 | [#7] imaging-desaturate Cycles:10 Average:96.10 Median:97.00 stddev:2.51 (2.6%) stddev-sans-first:2.44 03:42:56 INFO - PID 4239 | Values: 99.0 99.0 99.0 99.0 94.0 94.0 95.0 94.0 94.0 94.0 03:42:56 INFO - PID 4239 | 03:42:56 INFO - PID 4239 | [#8] json-parse-financial Cycles:10 Average:54.40 Median:54.00 stddev:1.43 (2.6%) stddev-sans-first:1.51 03:42:56 INFO - PID 4239 | Values: 54.0 58.0 53.0 55.0 55.0 54.0 54.0 53.0 54.0 54.0 03:42:56 INFO - PID 4239 | 03:42:56 INFO - PID 4239 | [#9] json-stringify-tinderbox Cycles:10 Average:54.50 Median:55.00 stddev:0.53 (1.0%) stddev-sans-first:0.53 03:42:56 INFO - PID 4239 | Values: 54.0 55.0 55.0 55.0 54.0 55.0 54.0 54.0 54.0 55.0 03:42:56 INFO - PID 4239 | 03:42:56 INFO - PID 4239 | [#10] stanford-crypto-aes Cycles:10 Average:78.10 Median:78.00 stddev:1.52 (2.0%) stddev-sans-first:1.20 03:42:56 INFO - PID 4239 | Values: 81.0 77.0 80.0 77.0 77.0 78.0 79.0 76.0 78.0 78.0 03:42:56 INFO - PID 4239 | 03:42:56 INFO - PID 4239 | [#11] stanford-crypto-ccm Cycles:10 Average:133.90 Median:129.50 stddev:13.27 (10.2%) stddev-sans-first:13.84 03:42:56 INFO - PID 4239 | Values: 127.0 128.0 126.0 147.0 128.0 130.0 124.0 133.0 129.0 167.0 03:42:56 INFO - PID 4239 | 03:42:56 INFO - PID 4239 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:183.60 Median:182.00 stddev:7.44 (4.1%) stddev-sans-first:7.52 03:42:56 INFO - PID 4239 | Values: 190.0 179.0 193.0 178.0 184.0 177.0 178.0 180.0 179.0 198.0 03:42:56 INFO - PID 4239 | 03:42:56 INFO - PID 4239 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.40 Median:69.00 stddev:1.51 (2.2%) stddev-sans-first:1.59 03:42:56 INFO - PID 4239 | Values: 69.0 71.0 69.0 72.0 71.0 68.0 69.0 67.0 69.0 69.0 03:42:56 INFO - PID 4239 | -------- Summary: end -------- 03:42:56 INFO - PID 4239 | 03:42:56 INFO - PID 4239 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 03:42:56 INFO - TEST-INFO | 4239: exit 0 03:42:56 INFO - TEST-OK | kraken | took 62966ms 03:42:56 INFO - SUITE-END | took 1179s 03:42:57 INFO - Completed test suite (00:19:39) 03:42:57 INFO - javascript benchmark 03:42:57 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8454.355417923736, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3127.452695778238, 3150.952735708964, 3156.8263745493987, 3165.8575364108606, 3178.253110519028, 6025.337212906587, 6027.668478233695, 6074.088886667002, 6120.908186377203, 6209.689515524223, 6094.969525152368, 6126.724297406617, 6137.263528376601, 6140.078988151779, 6168.506519478442, 5591.077472217079, 5619.409961953977, 5635.098384258509, 5643.294588176478, 5739.053056245735, 5633.070543360361, 5652.830415087554, 5675.2622159119255, 5700.828975130753, 5722.914156287659, 5445.346558413015, 5587.0, 5647.689377084259, 5673.744681489343, 5709.229254050712, 5612.07400778871, 5643.774249030034, 5672.40439753824, 5682.971585142089, 5684.118961560924, 5555.638883472602, 5557.055300598868, 5632.718364081779, 5652.378238393773, 5659.207710920475, 5809.419058094199, 5966.075258334965, 5968.522518198533, 6020.759169633209, 6086.1783659205885, 6591.769288074895, 6616.106825578533, 6620.801375958729, 6650.4679625630315, 6677.766278180289, 6616.139901812781, 6616.669166541654, 6693.0964319816685, 6726.42825359845, 6739.5282330236905, 6054.364291749391, 6150.0, 6170.7223174957235, 6238.750449981995, 6272.027835685476, 6655.434288085567, 6725.596464212163, 6761.526693131434, 6815.2843951385385, 6831.726730930806, 6357.777477788348, 6452.645104519296, 6520.967395162994, 6543.345665433419, 6547.770828020996, 6429.935700643027, 6496.155499785093, 6560.311167327457, 6565.934340656628, 6581.473482121419, 6409.935900640934, 6501.642409667514, 6561.015847622799, 6584.868302634016, 6672.199336079701, 6546.738130474823, 6597.934020659732, 6629.138212032405, 6637.4026337628875, 6640.634765087966, 5218.765155568009, 5255.500727430888, 5255.553277971415, 5296.894062118736, 5338.8665283367445, 4526.456825180933, 4537.77311134442, 4542.772861356919, 4549.7497637629585, 4646.628269738413, 5721.885562288814, 5750.453706897838, 5772.220750198753, 5778.248827652378, 5786.88426231473, 6611.801645950629, 6613.371729685672, 6685.933140668629, 6697.028930805006, 6702.095217145623, 5642.0, 5672.631278966936, 5715.371309155989, 5727.656340619576, 5794.681292528867, 5813.796517121965, 5850.297964244318, 5854.677992710356, 5863.70681465925, 5876.471117599436, 6273.8745225095245, 6368.0, 6449.645269510128, 6468.708908099147, 6477.319881412383, 6506.3818937200895, 6589.868202636017, 6593.011048342787, 6612.801615951529, 6671.933280667131, 6417.454516366161, 6438.0, 6484.416402523795, 6503.512236582181, 6512.34876512345, 6480.57876238043, 6480.902786458301, 6568.047633093079, 6570.5729127606555, 6576.76981305652, 6540.574862633914, 6621.238557565749, 6642.070110184674, 6643.833904152435, 6682.498812588978, 6344.555881088279, 6475.514336424692, 6485.837854053498, 6488.221413430419, 6492.058651495601, 6423.261324947691, 6478.6760661967655, 6507.316731743099, 6517.055027021151, 6527.771527996498, 6354.682265886595, 6359.554831161774, 6378.617282963037, 6423.655365791359, 6457.61254324742, 6313.684315784284, 6437.227532696106, 6468.967655161694, 6518.185226846645, 6531.11829902976, 6266.154069200599, 6285.654289014148, 6293.590916590407, 6304.6847657617855, 6336.081268216175], "value": 6022.989417287385, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [826.6238861318101, 857.352698712472, 879.881216035835, 888.8000199955011, 1141.6632093532407, 30176.245593860138, 41382.793086034566, 42378.364324535156, 42523.36214956778, 42566.36150457738, 42281.57718422815, 42812.35781463273, 42882.35676464848, 42929.35605965913, 42931.14137717244, 7101.0, 7511.511751736147, 7674.846503069935, 7775.416843736714, 7779.8055048623955, 7558.962205188966, 7667.8083047923965, 7676.4242681798805, 7693.846123077535, 7694.7306844260465, 7642.503237289586, 7665.348445382121, 7685.077790665128, 7707.730229441971, 7745.7676269712, 7642.159362470124, 7703.807404814925, 7708.152103268636, 7709.151993280707, 7710.498817576839, 7524.736634217831, 7540.509866858692, 7589.962050189713, 7591.164971853092, 7591.620418979084, 39176.608233917585, 39471.60528394737, 39498.0, 39589.20821583581, 39666.0, 3538.2569660371355, 3581.230035542371, 3587.4798154267746, 3590.838412271454, 3592.5868525119686, 30830.229244269074, 30915.690843091506, 31447.0, 31490.842545787353, 31563.0, 4746.857594272177, 4895.265710143472, 4922.335484709554, 5003.624728145403, 5069.695818250917, 187386.0, 191471.0, 191577.0, 192084.0, 192477.03761481243, 47756.0, 47946.280805787945, 48238.27642585364, 48249.863758855565, 50027.499725003014, 52859.73570132202, 53023.46976530262, 53113.7344313276, 53168.734156328974, 53292.73353633207, 16294.103824289778, 16353.672926541403, 16445.424410145588, 16521.91739041321, 16557.0, 13224.537141200204, 13383.197008179346, 13387.665308367174, 13424.463021479227, 13455.125416847874, 14921.3285402155, 14945.103293802407, 14950.028248163835, 15169.014014089049, 15196.088234705952, 5787.450192231731, 5926.288845338587, 6019.231153669361, 6135.509159267248, 6209.9068513972325, 949.093615597103, 985.5909797433984, 993.1607791416242, 1028.1158203944601, 1031.83490641497, 23112.768872311397, 23613.645795313085, 23648.290551283488, 23810.88094559516, 24131.51736965251, 1085.234909388879, 1086.6577028236081, 1093.2456604942565, 1108.5898217659517, 1128.5203788389902, 11797.646070617895, 12704.936475317565, 12725.0, 12783.744325113446, 12926.41831117602, 246496.0, 247838.0, 250029.0, 250897.0, 252154.0, 53951.460485394644, 54028.0, 54265.72867135718, 54453.72773136109, 54528.0, 1431.6356562079031, 1466.281522054207, 1472.955811325662, 1480.5780352599456, 1485.9405623775144, 22279.220227291968, 22620.077293429586, 22678.0, 22782.772172278066, 22831.771682282964, 37794.81102594469, 37949.241015180094, 37963.43054854124, 38011.0, 38071.61928380681, 206697.0, 228156.0, 231139.0, 232492.0, 233299.0, 38710.612893870704, 38828.61171388363, 38959.80520097382, 39605.60394396019, 39651.80174099226, 48023.27965080596, 49002.75498622484, 49152.26271605857, 49525.25712114249, 49617.0, 32506.512402314456, 35405.114872128404, 35559.288814224084, 35664.64335356717, 36300.455493167094, 33733.32533349368, 34385.484217737256, 34708.305833883685, 34835.12912177216, 35532.46701299431, 38510.61489385182, 38612.227755444175, 38816.80591597024, 38858.6114138855, 38936.61063389443, 33321.16697082592, 34000.82999584986, 34221.0, 34407.827960860035, 34473.310533789685, 23143.0, 23962.88018559896, 24561.263162105166, 24604.630930536416, 24614.753852461246], "value": 17682.137066112566, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [562.600553606939, 563.4590792838875, 570.2273419516557, 578.4186892173365, 601.2964831147557, 22426.0, 23158.536829263405, 23161.18935837246, 23213.883930580323, 23315.533689326225, 24965.50068998619, 27766.0, 27789.0, 27890.442191156166, 27981.0, 6496.545241833073, 6669.43309818666, 6699.464042876582, 6701.530892837503, 6703.832404189886, 5736.196932429463, 6346.460550853183, 6585.407313341799, 6595.0766892635065, 6596.109525214107, 6283.120363149163, 6325.778597749079, 6337.588056776318, 6342.270638876542, 6349.682515874211, 6319.462845658125, 6326.968365158191, 6342.841428964267, 6345.682715864188, 6351.936480635204, 5925.911111333334, 5936.940630593682, 5950.0479923212515, 5959.851003724898, 5960.225170727821, 28699.713002869914, 29595.0, 30086.849565752033, 30135.698643013508, 30474.54288185679, 1902.9524261893425, 1950.1029526417865, 1950.502621831438, 1953.2772874036555, 1954.912028958686, 18824.5293867653, 19197.61604767911, 19222.711659325123, 19238.807611923843, 19247.326343578046, 2065.132644289402, 2180.2478145039936, 2188.912443502258, 2226.5101677631055, 2232.441889527618, 133409.0, 135750.6424935748, 135906.0, 135979.0, 136101.0, 14069.437222510986, 15147.54557363281, 15198.696026079417, 15379.462388705739, 15411.0, 23639.0, 23644.645330320058, 23664.526709465714, 23667.171648992204, 23672.64491032636, 9344.486053267136, 10418.583256669799, 10451.790964180826, 10502.789944201226, 10504.947475262728, 9678.903210967941, 9689.176420004238, 9736.123748862636, 9740.220782337396, 9868.703938881845, 8913.24237439812, 9379.577918993662, 9470.337076404585, 9488.667896623585, 9540.236781057638, 2588.9482210355686, 2601.3236558494928, 2702.8918843246443, 2762.4751297253456, 2804.7615952644255, 954.190956357344, 954.3844220477753, 967.8790151231096, 996.1582462818907, 1003.7841863999275, 18939.0, 18980.525486862938, 19055.142518586697, 19453.902730486257, 19730.90134549318, 971.3831716859845, 1031.7936412717486, 1034.8706411698538, 1044.4307852220559, 1046.853440518328, 10470.0, 10785.245032847783, 10934.4532773361, 10977.506012229469, 11035.668929932115, 484932.0, 497261.0, 497371.0, 499882.0, 500333.0, 114260.0, 117862.41068794602, 118062.40968795274, 118635.0, 120372.0, 1244.4524409260077, 1294.4045738960183, 1321.1214542329435, 1329.6210579984659, 1331.793571996342, 16587.91706041462, 16954.0, 17334.566635834206, 17617.383391581232, 18122.546936326697, 51345.0, 54233.72883135559, 54722.45277547333, 55151.44848551624, 56602.0, 71822.64088679732, 74676.0, 76148.0, 77209.0, 77708.2229177701, 12496.43766030549, 12876.806847897476, 13172.868271317164, 14750.778738319148, 15140.621484462976, 17451.214695338487, 21402.464938376666, 21415.250466233607, 21519.677204841628, 24347.0, 18820.15309311057, 19122.235110595422, 19290.4212873614, 19491.902540487208, 19622.018899054703, 16159.676806463569, 16719.24763385675, 17043.0, 17366.82633173652, 18054.368097116538, 17189.226484807965, 17589.736153957958, 17753.73369399486, 17775.644487110443, 18847.434576962714, 13880.791788122984, 14155.221462819643, 14159.292035398395, 14182.574522764333, 14187.858121418654, 17122.315107395563, 17538.386156484463, 17565.82434175693, 17605.0, 17618.82381176223], "value": 11891.954039703107, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1359.7484465373905, 1427.264958546349, 1436.7270218658455, 1441.243347242698, 1451.8765904898082, 13534.796978045324, 13619.591412257623, 14085.436582536711, 14093.92953035236, 14120.082194657365, 14018.228997405138, 14394.712105757879, 14395.06432081914, 14426.134431934091, 14428.783568246485, 2608.6739157605252, 2651.6950550686724, 2652.3634327761392, 2653.8275012124245, 2654.4425670609194, 2580.354911272182, 2657.9069732559365, 2673.8128331016837, 2721.7958653101095, 2730.781537477007, 2674.1442738323744, 2705.9864700676467, 2712.5388683923684, 2712.8643567821628, 2714.76924461421, 2619.7118316985116, 2628.672520377216, 2656.6546348974703, 2673.692525359589, 2674.812763106583, 2572.1383336582267, 2599.3111825366104, 2607.6218948252586, 2632.8946842126484, 2657.7740892024203, 13846.30768461573, 13852.238126903016, 13918.860811391858, 13920.37358318878, 13983.65040873976, 7335.339819416225, 7631.771046868603, 7680.347170490515, 7703.961480192619, 7705.653245603961, 4796.18464860971, 4952.380952380952, 5078.289039534468, 5080.110980578421, 5121.692698438068, 1825.2242796811302, 1832.6517961587256, 1846.0954132475124, 1846.9696145304156, 1882.0, 22754.0, 23204.41988950273, 23268.06927722889, 23297.301080967598, 23299.76700232993, 6453.161089058486, 6746.123004009545, 6774.966125169342, 6820.6930688119155, 6823.488238382141, 14881.627959301104, 14967.925160374129, 15012.324445399983, 15039.699206015819, 15066.09603423798, 7060.399866011447, 7195.0, 7332.156801967842, 7372.152202496674, 7373.778786636302, 6523.543351965317, 6585.506087043395, 6593.307702691244, 6651.467882569383, 6671.366220209073, 5054.09026375256, 5180.740962951837, 5195.467337135554, 5220.791168353223, 5247.790088396498, 1342.6710455938357, 1357.959261222165, 1361.8025386318927, 1524.6569521857493, 1532.486616983323, 832.8417600655856, 852.2925971443686, 865.3336930563431, 883.2580632268925, 895.9552022398854, 7770.92229077702, 8037.8392432152195, 8068.0721717002125, 8126.349892008625, 8141.796455088552, 930.9255259579218, 963.2919803944095, 966.8163049020663, 974.7660561465198, 991.504247876062, 4263.339182426729, 4408.030233348658, 4894.926576101361, 5114.053900028507, 5161.406438259573, 18950.52623684419, 19770.0, 19785.307514236934, 19965.60068798616, 19985.800141998396, 18451.90774046121, 19400.59551419218, 19509.219631214877, 19660.410187694106, 19714.112864921113, 10474.947625261824, 10716.89283107159, 10730.78538429227, 10764.946175269073, 10814.94592527048, 7221.53060051095, 7815.374770018271, 8132.2680958714, 8254.257116859511, 8435.650295952864, 15713.371465141267, 16179.676406472041, 16297.918510407371, 16640.417585384454, 16640.750388743938, 16521.586960325607, 16801.243944022794, 16922.49232523026, 16954.406595769087, 17032.063236521615, 11090.168237382068, 11139.610113645984, 11227.0, 11271.154663400443, 11353.943230283796, 12703.174293670882, 12793.42429590652, 12963.287019214034, 12967.092303538662, 12989.545365912149, 8213.671453141807, 10369.74075648115, 10552.261341706007, 10645.148388129086, 10846.078083362687, 9870.703878883645, 10513.0, 10592.947035264775, 10620.415877126832, 10807.783844323227, 8973.730788076367, 9216.585253663736, 9222.815543688954, 9223.76940576464, 9233.630654773733, 6271.78048768291, 6297.937020629735, 6641.667916604054, 6760.594364338155, 6850.794476165723, 6683.298253683293, 6926.134233220848, 6943.722251109899, 7021.403180729695, 7030.121234845645], "value": 6287.319546491702, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [493.76774628230413, 494.9902199512995, 518.914379127444, 520.8046982381607, 526.689253340529, 6026.246719160105, 6090.147379366892, 6163.445289923906, 6184.350643182469, 6186.381361863811, 5754.424557544244, 6069.150318955339, 6069.239920423083, 6085.817425477243, 6087.20866287382, 4847.684900481458, 4914.852554423364, 4958.652894297401, 4976.800927962877, 4986.800527978877, 4819.578099549324, 4904.75476226189, 4952.306677065214, 4963.230699241623, 5004.12427825131, 4870.4885986971385, 4875.146849301375, 4881.707097574157, 4921.680090794105, 4928.0, 4829.975850120762, 4835.371401717789, 4888.584470320027, 4894.730789806577, 4953.653244272902, 4380.123975204971, 4430.822767089313, 4439.2675208590545, 4485.282354823245, 4512.796924138422, 6017.819465416044, 6024.277086749575, 6025.276966763972, 6031.215941927565, 6050.939490605082, 5486.177073438977, 5519.806806761744, 5638.887222255532, 5658.886822263573, 5683.403242659544, 6388.361163883621, 6426.775062872824, 6436.839079023015, 6475.294399664384, 6486.610803351814, 4792.9281060784115, 4810.470848206694, 4860.246661767432, 4878.317035615017, 4892.168331383637, 6679.666016699146, 6737.292584278678, 6784.694688739019, 6808.931910680879, 6827.146606674166, 4921.557059864629, 4932.704037757746, 4981.007596961172, 4992.703356455998, 5008.724520151354, 5007.023630392111, 5014.640191071991, 5030.8239211627415, 5045.672031317952, 5055.51972562606, 5578.995780759502, 5667.263255776723, 5689.288838895138, 5713.371529131792, 5724.427557244326, 6435.839104022343, 6472.579282346633, 6472.8672482315405, 6534.640594767334, 6536.869262614816, 1663.2349119404967, 1687.2323092992658, 1710.8203638618015, 1728.6801941640836, 1743.9536278233213, 1196.1865931166717, 1212.8908398244023, 1222.413241644016, 1226.9141160118884, 1227.3433712963522, 5627.831165065055, 5674.574406919498, 5693.345265294544, 5701.40135285789, 5705.828825135252, 6551.705173267214, 6608.240052394036, 6699.5261042570555, 6712.160979877515, 6769.323067693192, 5709.457601527932, 5748.9712551437815, 5776.537876969833, 5809.390014048548, 5833.620814647119, 3506.333796578642, 3580.3018411409525, 3591.838367273479, 3605.5673319201865, 3626.6192049834917, 6452.419282264619, 6453.128827608307, 6545.672716364163, 6568.211814582185, 6584.38682522785, 6724.462043036546, 6763.188417389846, 6841.692123854438, 6847.6446541194455, 6930.930690693029, 6395.936040639534, 6486.772962946275, 6509.251436084911, 6538.934610653928, 6546.378094081055, 6445.548811583144, 6695.296993815774, 6741.831454213684, 6764.255931847394, 6777.966110169417, 6509.446697030806, 6529.347065293623, 6584.242812076673, 6606.768763093269, 6648.108072988266, 6199.194104766532, 6328.746850125944, 6378.428080275061, 6391.621739108726, 6428.874946884183, 5271.815486457956, 5382.811601593926, 5415.268938693226, 5417.4040855506655, 5433.517348187682, 6351.30135685065, 6624.867502649824, 6637.601743895381, 6654.633995130314, 6688.966555167193, 6316.399942005502, 6320.810375688737, 6376.936230637634, 6431.839204019937, 6455.419012288917, 6427.871442571216, 6433.613983161025, 6435.195600549931, 6441.227052753699, 6460.160179176677, 6185.938140618537, 6330.778422755181, 6347.238331400077, 6364.236291645032, 6368.64972426521, 6422.49071468214, 6439.0, 6445.645489497935, 6469.673716888121, 6553.968269836366, 6221.129041934042, 6264.874702505833, 6305.464035557029, 6315.715792789428, 6329.272133704683], "value": 5011.043027059865, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [895.4269267668693, 921.8386782313094, 922.6770630279407, 939.4973689076342, 943.9669611563593, 18501.814981850177, 19249.133788979496, 19453.0, 19601.019949002548, 21565.1373945042, 20698.68951965722, 21314.0, 21387.0, 21540.676889846665, 21603.783962160374, 1008.7074748322996, 1045.4093437207991, 1046.3303485769113, 1046.4140081554353, 1048.2976405808126, 1019.0, 1059.9629012984508, 1064.8668916385452, 1067.8665166854144, 1070.6787963610925, 1127.149002503109, 1129.1813435259412, 1132.0717012050122, 1132.286659404574, 1132.8867113288688, 1119.3451830679062, 1123.4888125902694, 1127.661701489554, 1129.8983091521761, 1130.2540323386565, 814.6863457568819, 821.7165078048124, 822.6585966823761, 822.9670813167467, 825.0429501777969, 19682.114304856317, 19708.605827883504, 19961.201551937906, 20065.297714580065, 20425.69361459579, 19909.601807963758, 20207.393778186677, 20473.692894606593, 20917.372478825506, 20921.68617470724, 16241.83758162427, 16321.673566528721, 16394.26225819841, 16510.66978660432, 16526.83473165265, 3564.0555252857753, 3587.3363427765685, 3606.5852426970973, 3607.4228123500116, 3639.2721455708966, 125057.0, 129494.35252823676, 130426.0, 132688.0, 134076.0, 15046.54860354191, 15239.085654860522, 15437.0, 15697.293621787047, 15910.920445397698, 29954.55068173979, 30417.239569010508, 30465.390692186033, 30644.69355306463, 31024.0692779217, 7360.631968401559, 7729.343005844454, 8052.758417247492, 8452.915470845213, 8875.201231889161, 4098.508179018478, 4215.093754842724, 4237.470316210473, 4245.639120674778, 4258.233517966733, 5080.263361812516, 5105.412877519058, 5117.974410127926, 5167.0, 5242.292290540729, 998.2862253488645, 1007.8185926533148, 1013.8935411781657, 1016.4054028393473, 1024.4314405504867, 651.2445563146726, 664.6543797225415, 690.1432510920839, 700.2087640965676, 707.5683832861951, 11640.708982275512, 12206.328651924052, 12639.368031598384, 12939.870601294055, 13303.268320242287, 678.6471035061741, 709.1631874388271, 715.2597062040763, 728.0062714394813, 728.3372131360436, 5349.491798279158, 5624.29696287964, 5693.715314234271, 5765.135229715576, 5941.1088336749835, 102253.0, 102871.0, 103134.0, 108283.0, 111548.44225778819, 87238.56380718182, 88934.55532722294, 93793.5310323444, 93970.53014734882, 95418.0, 77783.22216777873, 81181.59409202916, 81685.18314816775, 82527.587362064, 83503.0, 11064.612738554111, 11430.25703329281, 11565.901239382581, 11637.43842364532, 12230.633081007583, 34914.36558904372, 36074.819625901706, 36697.26605467929, 37589.624103758615, 38528.2294354117, 66250.0, 68502.65748671225, 68958.65520672365, 71091.0, 72685.0, 18338.0, 18425.815741842947, 18636.813631863508, 18688.0, 18887.527811804815, 20984.47538811481, 21000.474988125417, 21007.159713611283, 21109.26117585877, 21118.683219752023, 17287.222075006906, 17768.200430980378, 17939.192736327157, 17977.0, 17989.0, 18560.257589696263, 18638.72041919345, 18702.81297187065, 18710.625787483903, 18830.0, 17639.470815875546, 17821.28714851443, 17885.19516621729, 17998.190081446628, 18062.81937180664, 13052.608421747364, 13639.249841258428, 13931.303434828016, 14180.43278268899, 14313.713725725636, 12683.492660293854, 13348.0, 13404.128731632412, 13554.322283885964, 13675.658108546968], "value": 9150.842955297909, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1539.0, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 110.0, 102.0, 109.0, 116.0, 111.0, 110.0, 101.0, 109.0, 109.0], "value": 107.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 118.0, 144.0, 185.0, 119.0, 152.0, 150.0, 122.0, 165.0, 119.0], "value": 143.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 164.0, 164.0, 164.0, 163.0, 163.0, 164.0, 166.0, 163.0, 164.0], "value": 163.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 75.0, 76.0, 166.0, 77.0, 77.0, 82.0, 73.0, 157.0, 75.0], "value": 93.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 81.0, 136.0, 81.0, 81.0, 81.0, 124.0, 81.0, 82.0, 126.0], "value": 95.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 118.0, 114.0, 113.0, 114.0, 118.0, 115.0, 113.0, 114.0], "value": 114.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 151.0, 150.0, 151.0, 150.0, 151.0, 149.0, 150.0, 150.0, 151.0], "value": 150.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 99.0, 99.0, 99.0, 94.0, 94.0, 95.0, 94.0, 94.0, 94.0], "value": 96.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 58.0, 53.0, 55.0, 55.0, 54.0, 54.0, 53.0, 54.0, 54.0], "value": 54.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 55.0, 55.0, 55.0, 54.0, 55.0, 54.0, 54.0, 54.0, 55.0], "value": 54.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 77.0, 80.0, 77.0, 77.0, 78.0, 79.0, 76.0, 78.0, 78.0], "value": 78.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [127.0, 128.0, 126.0, 147.0, 128.0, 130.0, 124.0, 133.0, 129.0, 167.0], "value": 133.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [190.0, 179.0, 193.0, 178.0, 184.0, 177.0, 178.0, 180.0, 179.0, 198.0], "value": 183.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 71.0, 69.0, 72.0, 71.0, 68.0, 69.0, 67.0, 69.0, 69.0], "value": 69.4, "unit": "score"}]}]} 03:42:57 INFO - Return code: 0 03:42:57 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:42:57 INFO - # TBPL SUCCESS # 03:42:57 INFO - Running post-action listener: _package_coverage_data 03:42:57 INFO - Running post-action listener: _resource_record_post_action 03:42:57 INFO - [mozharness: 2017-07-22 10:42:57.487418Z] Finished run-tests step (success) 03:42:57 INFO - Running post-run listener: _resource_record_post_run 03:42:57 INFO - Total resource usage - Wall time: 1198s; CPU: 13.0%; Read bytes: 4939776; Write bytes: 450232320; Read time: 1224; Write time: 364432 03:42:57 INFO - TinderboxPrint: CPU usage
13.2% 03:42:57 INFO - TinderboxPrint: I/O read bytes / time
4,939,776 / 1,224 03:42:57 INFO - TinderboxPrint: I/O write bytes / time
450,232,320 / 364,432 03:42:57 INFO - TinderboxPrint: CPU idle
8,293.7 (86.8%) 03:42:57 INFO - TinderboxPrint: CPU user
1,204.7 (12.6%) 03:42:57 INFO - TinderboxPrint: Swap in / out
0 / 0 03:42:57 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 120291328; Read time: 0; Write time: 94524 03:42:57 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 03:42:57 INFO - run-tests - Wall time: 1180s; CPU: 13.0%; Read bytes: 2691072; Write bytes: 324198400; Read time: 928; Write time: 269536 03:42:58 INFO - Running post-run listener: _upload_blobber_files 03:42:58 INFO - Blob upload gear active. 03:42:58 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:42:58 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 03:42:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 03:42:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 03:42:58 INFO - (blobuploader) - INFO - Open directory for files ... 03:42:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 03:42:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:42:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:43:00 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 03:43:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:43:00 INFO - (blobuploader) - INFO - Done attempting. 03:43:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 03:43:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:43:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:43:01 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 03:43:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:43:01 INFO - (blobuploader) - INFO - Done attempting. 03:43:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:43:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:43:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:43:04 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:43:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:43:04 INFO - (blobuploader) - INFO - Done attempting. 03:43:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 03:43:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:43:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:43:05 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 03:43:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:43:05 INFO - (blobuploader) - INFO - Done attempting. 03:43:05 INFO - (blobuploader) - INFO - Iteration through files over. 03:43:05 INFO - Return code: 0 03:43:05 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:43:05 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:43:05 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/283fed4f43bd32cbdee5d9caac3089379dfc1c75537f223cc33518ce8ad6c0c5f48efe0de0e4ea1d3211a8b1a3c5d85b3f78a11e425ecb2378be1320cb860e26", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/634664bf2c355102f411c358f2e9c2b8a4f9a2d31ff6a18b99da283effa9ed1eeb3f7a809ae5511f9dd05f94fe16da964c57fbc7b2d38ec7ef742f420d5343a5", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cff8e6593d4a72d1cd107d07dea830730b059b73cd7b7122271c702a79252859148bc791824e52f7770a15e38e5125f6bfef2ffba1f4d3352330cafd4df60edc", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/438fc9736933a8168609c22891ab66c38e516ce93280d87f67f34b7a32117050857ea4e9a4f447a369d654e1e91b295469d247052c65b0396774a1e0c2e492e5"} 03:43:05 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:43:05 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:43:05 INFO - Contents: 03:43:05 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/283fed4f43bd32cbdee5d9caac3089379dfc1c75537f223cc33518ce8ad6c0c5f48efe0de0e4ea1d3211a8b1a3c5d85b3f78a11e425ecb2378be1320cb860e26", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/634664bf2c355102f411c358f2e9c2b8a4f9a2d31ff6a18b99da283effa9ed1eeb3f7a809ae5511f9dd05f94fe16da964c57fbc7b2d38ec7ef742f420d5343a5", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cff8e6593d4a72d1cd107d07dea830730b059b73cd7b7122271c702a79252859148bc791824e52f7770a15e38e5125f6bfef2ffba1f4d3352330cafd4df60edc", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/438fc9736933a8168609c22891ab66c38e516ce93280d87f67f34b7a32117050857ea4e9a4f447a369d654e1e91b295469d247052c65b0396774a1e0c2e492e5"} 03:43:05 INFO - Running post-run listener: copy_logs_to_upload_dir 03:43:05 INFO - Copying logs to upload dir... 03:43:05 INFO - mkdir: /builds/slave/test/build/upload/logs 03:43:05 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1294.071086 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 34 secs) (at 2017-07-22 03:43:05.662955) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-07-22 03:43:05.666361) ========= 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=5ac5dba3e01a12f1ff77ab15000001b1-1500716795.557513-437887472 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/283fed4f43bd32cbdee5d9caac3089379dfc1c75537f223cc33518ce8ad6c0c5f48efe0de0e4ea1d3211a8b1a3c5d85b3f78a11e425ecb2378be1320cb860e26", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/634664bf2c355102f411c358f2e9c2b8a4f9a2d31ff6a18b99da283effa9ed1eeb3f7a809ae5511f9dd05f94fe16da964c57fbc7b2d38ec7ef742f420d5343a5", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cff8e6593d4a72d1cd107d07dea830730b059b73cd7b7122271c702a79252859148bc791824e52f7770a15e38e5125f6bfef2ffba1f4d3352330cafd4df60edc", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/438fc9736933a8168609c22891ab66c38e516ce93280d87f67f34b7a32117050857ea4e9a4f447a369d654e1e91b295469d247052c65b0396774a1e0c2e492e5"} build_url:https://queue.taskcluster.net/v1/task/RwKFdA9lQGydGPWJvR_XgA/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011090 build_url: 'https://queue.taskcluster.net/v1/task/RwKFdA9lQGydGPWJvR_XgA/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/283fed4f43bd32cbdee5d9caac3089379dfc1c75537f223cc33518ce8ad6c0c5f48efe0de0e4ea1d3211a8b1a3c5d85b3f78a11e425ecb2378be1320cb860e26", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/634664bf2c355102f411c358f2e9c2b8a4f9a2d31ff6a18b99da283effa9ed1eeb3f7a809ae5511f9dd05f94fe16da964c57fbc7b2d38ec7ef742f420d5343a5", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cff8e6593d4a72d1cd107d07dea830730b059b73cd7b7122271c702a79252859148bc791824e52f7770a15e38e5125f6bfef2ffba1f4d3352330cafd4df60edc", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/438fc9736933a8168609c22891ab66c38e516ce93280d87f67f34b7a32117050857ea4e9a4f447a369d654e1e91b295469d247052c65b0396774a1e0c2e492e5"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-07-22 03:43:05.692397) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-07-22 03:43:05.692881) ========= 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=5ac5dba3e01a12f1ff77ab15000001b1-1500716795.557513-437887472 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004344 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-07-22 03:43:05.776613) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-07-22 03:43:05.777069) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-07-22 03:43:05.777490) ========= ========= Total master_lag: 0.19 =========