builder: mozilla-central_ubuntu64_hw_stylo_test-dromaeojs-e10s slave: talos-linux64-ix-082 starttime: 1501251030.58 results: success (0) revision: 16ffc1d05422a81099ce8b9b59de66dde4c8b2f0 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-07-28 07:10:30.577149) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-07-28 07:10:30.577520) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-07-28 07:10:30.577805) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-07-28 07:10:30.597280) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-07-28 07:10:30.597549) ========= 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=51fc86400edbde9650d2781a000001d0-1501250581.574311-1448507222 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-07-28 07:10:31-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 25.4M=0s 2017-07-28 07:10:31 (25.4 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.124506 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-07-28 07:10:30.735030) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-07-28 07:10:30.735307) ========= 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=51fc86400edbde9650d2781a000001d0-1501250581.574311-1448507222 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020431 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-07-28 07:10:30.787795) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 12 secs) (at 2017-07-28 07:10:30.788082) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 16ffc1d05422a81099ce8b9b59de66dde4c8b2f0 --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 16ffc1d05422a81099ce8b9b59de66dde4c8b2f0 --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=51fc86400edbde9650d2781a000001d0-1501250581.574311-1448507222 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-07-28 07:10:31,381 truncating revision to first 12 chars 2017-07-28 07:10:31,382 Setting DEBUG logging. 2017-07-28 07:10:31,382 attempt 1/10 2017-07-28 07:10:31,382 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/16ffc1d05422?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-07-28 07:10:32,062 sleeping for 10.00s (attempt 1/10) 2017-07-28 07:10:42,072 attempt 2/10 2017-07-28 07:10:42,072 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/16ffc1d05422?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-07-28 07:10:43,310 unpacking tar archive at: mozilla-central-16ffc1d05422/testing/mozharness/ program finished with exit code 0 elapsedTime=12.213056 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 12 secs) (at 2017-07-28 07:10:43.013724) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-07-28 07:10:43.013993) ========= script_repo_revision: 16ffc1d05422a81099ce8b9b59de66dde4c8b2f0 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-07-28 07:10:43.017346) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-07-28 07:10:43.017615) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-07-28 07:10:43.025933) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 0 secs) (at 2017-07-28 07:10:43.026194) ========= /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=51fc86400edbde9650d2781a000001d0-1501250581.574311-1448507222 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 07:10:43 INFO - MultiFileLogger online at 20170728 07:10:43 in /builds/slave/test 07:10:43 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 07:10:43 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:10:43 INFO - {'append_to_log': False, 07:10:43 INFO - 'base_work_dir': '/builds/slave/test', 07:10:43 INFO - 'blob_upload_branch': 'Firefox', 07:10:43 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:10:43 INFO - 'branch': 'Firefox', 07:10:43 INFO - 'buildbot_json_path': 'buildprops.json', 07:10:43 INFO - 'code_coverage': False, 07:10:43 INFO - 'config_files': ('talos/linux_config.py',), 07:10:43 INFO - 'default_actions': ('clobber', 07:10:43 INFO - 'read-buildbot-config', 07:10:43 INFO - 'download-and-extract', 07:10:43 INFO - 'populate-webroot', 07:10:43 INFO - 'create-virtualenv', 07:10:43 INFO - 'install', 07:10:43 INFO - 'setup-mitmproxy', 07:10:43 INFO - 'run-tests'), 07:10:43 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:10:43 INFO - 'disable_ccov_upload': False, 07:10:43 INFO - 'download_minidump_stackwalk': True, 07:10:43 INFO - 'download_symbols': 'ondemand', 07:10:43 INFO - 'enable_stylo': False, 07:10:43 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:10:43 INFO - 'tooltool.py': '/tools/tooltool.py', 07:10:43 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:10:43 INFO - '/tools/misc-python/virtualenv.py')}, 07:10:43 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:10:43 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:10:43 INFO - 'gecko_profile': False, 07:10:43 INFO - 'gecko_profile_interval': 0, 07:10:43 INFO - 'installer_path': 'installer.exe', 07:10:43 INFO - 'log_level': 'info', 07:10:43 INFO - 'log_name': 'talos', 07:10:43 INFO - 'log_to_console': True, 07:10:43 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 07:10:43 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 07:10:43 INFO - 'opt_config_files': (), 07:10:43 INFO - 'pip_index': False, 07:10:43 INFO - 'suite': 'dromaeojs-e10s', 07:10:43 INFO - 'system_bits': '32', 07:10:43 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:10:43 INFO - 'title': 'talos-linux64-ix-082', 07:10:43 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:10:43 INFO - 'use_talos_json': True, 07:10:43 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:10:43 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:10:43 INFO - 'work_dir': 'build'} 07:10:43 INFO - [mozharness: 2017-07-28 14:10:43.723745Z] Running clobber step. 07:10:43 INFO - Running pre-action listener: _resource_record_pre_action 07:10:43 INFO - Running main action method: clobber 07:10:43 INFO - rmtree: /builds/slave/test/build 07:10:43 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:10:44 INFO - Running post-action listener: _resource_record_post_action 07:10:44 INFO - [mozharness: 2017-07-28 14:10:44.756380Z] Finished clobber step (success) 07:10:44 INFO - [mozharness: 2017-07-28 14:10:44.756483Z] Running read-buildbot-config step. 07:10:44 INFO - Running pre-action listener: _resource_record_pre_action 07:10:44 INFO - Running main action method: read_buildbot_config 07:10:44 INFO - Using buildbot properties: 07:10:44 INFO - { 07:10:44 INFO - "project": "", 07:10:44 INFO - "product": "firefox", 07:10:44 INFO - "who": "cbook@mozilla.com", 07:10:44 INFO - "installer_path": "public/build/target.tar.bz2", 07:10:44 INFO - "builddir": "mozilla-central_ubuntu64_hw_stylo_test-dromaeojs-e10s", 07:10:44 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 07:10:44 INFO - "buildername": "Ubuntu HW 12.04 x64 stylo mozilla-central talos dromaeojs-e10s", 07:10:44 INFO - "stage_platform": "linux64-stylo", 07:10:44 INFO - "basedir": "/builds/slave/test", 07:10:44 INFO - "buildnumber": 53, 07:10:44 INFO - "platform": "ubuntu64_hw_stylo", 07:10:44 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 07:10:44 INFO - "slavebuilddir": "test", 07:10:44 INFO - "taskId": "CHKIk1W-ROyPiBvCotIKGg", 07:10:44 INFO - "branch": "mozilla-central", 07:10:44 INFO - "script_repo_revision": "production", 07:10:44 INFO - "revision": "16ffc1d05422a81099ce8b9b59de66dde4c8b2f0", 07:10:44 INFO - "slavename": "talos-linux64-ix-082", 07:10:44 INFO - "repo_path": "mozilla-central" 07:10:44 INFO - } 07:10:44 INFO - Finding installer, test and symbols from parent task. 07:10:44 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CHKIk1W-ROyPiBvCotIKGg'}, attempt #1 07:10:45 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CHKIk1W-ROyPiBvCotIKGg'}, attempt #1 07:10:45 INFO - Task dependencies: BEg1anclTmmD7bmx1GwmKA 07:10:45 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BEg1anclTmmD7bmx1GwmKA'}, attempt #1 07:10:46 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/BEg1anclTmmD7bmx1GwmKA/artifacts/public/build/target.tar.bz2 07:10:46 INFO - Set test_url: https://queue.taskcluster.net/v1/task/BEg1anclTmmD7bmx1GwmKA/artifacts/public/build/test_packages.json 07:10:46 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/BEg1anclTmmD7bmx1GwmKA/artifacts/public/build/target.crashreporter-symbols.zip 07:10:46 INFO - Running post-action listener: _resource_record_post_action 07:10:46 INFO - [mozharness: 2017-07-28 14:10:46.113004Z] Finished read-buildbot-config step (success) 07:10:46 INFO - [mozharness: 2017-07-28 14:10:46.113246Z] Running download-and-extract step. 07:10:46 INFO - Running pre-action listener: _resource_record_pre_action 07:10:46 INFO - Running main action method: download_and_extract 07:10:46 INFO - mkdir: /builds/slave/test/build/tests 07:10: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')]} 07:10:46 INFO - trying https://queue.taskcluster.net/v1/task/BEg1anclTmmD7bmx1GwmKA/artifacts/public/build/target.test_packages.json 07:10:46 INFO - Downloading https://queue.taskcluster.net/v1/task/BEg1anclTmmD7bmx1GwmKA/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 07:10:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BEg1anclTmmD7bmx1GwmKA/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 07:10:46 INFO - Downloaded 989 bytes. 07:10:46 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 07:10:46 INFO - Using the following test package requirements: 07:10:46 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 07:10:46 INFO - u'common': [u'target.common.tests.zip'], 07:10:46 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 07:10:46 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 07:10:46 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 07:10:46 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 07:10:46 INFO - u'mozbase': [u'target.common.tests.zip'], 07:10:46 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 07:10:46 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 07:10:46 INFO - u'web-platform': [u'target.common.tests.zip', 07:10:46 INFO - u'target.web-platform.tests.tar.gz'], 07:10:46 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 07:10:46 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 07:10:46 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/BEg1anclTmmD7bmx1GwmKA/artifacts/public/build/target.common.tests.zip 07:10:46 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/BEg1anclTmmD7bmx1GwmKA/artifacts/public/build/target.common.tests.zip'}, attempt #1 07:10:46 INFO - Fetch https://queue.taskcluster.net/v1/task/BEg1anclTmmD7bmx1GwmKA/artifacts/public/build/target.common.tests.zip into memory 07:10:49 INFO - Content-Length response header: 72564944 07:10:49 INFO - Bytes received: 72564944 07:11:00 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 07:11:00 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/BEg1anclTmmD7bmx1GwmKA/artifacts/public/build/target.common.tests.zip 07:11:00 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/BEg1anclTmmD7bmx1GwmKA/artifacts/public/build/target.common.tests.zip'}, attempt #1 07:11:00 INFO - Fetch https://queue.taskcluster.net/v1/task/BEg1anclTmmD7bmx1GwmKA/artifacts/public/build/target.common.tests.zip into memory 07:11:01 INFO - Content-Length response header: 72564944 07:11:01 INFO - Bytes received: 72564944 07:11:14 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/BEg1anclTmmD7bmx1GwmKA/artifacts/public/build/target.talos.tests.zip 07:11:14 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/BEg1anclTmmD7bmx1GwmKA/artifacts/public/build/target.talos.tests.zip'}, attempt #1 07:11:14 INFO - Fetch https://queue.taskcluster.net/v1/task/BEg1anclTmmD7bmx1GwmKA/artifacts/public/build/target.talos.tests.zip into memory 07:11:15 INFO - Content-Length response header: 14496686 07:11:15 INFO - Bytes received: 14496686 07:11:15 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')]} 07:11:15 INFO - trying https://queue.taskcluster.net/v1/task/BEg1anclTmmD7bmx1GwmKA/artifacts/public/build/target.tar.bz2 07:11:15 INFO - Downloading https://queue.taskcluster.net/v1/task/BEg1anclTmmD7bmx1GwmKA/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 07:11:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BEg1anclTmmD7bmx1GwmKA/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 07:11:17 INFO - Downloaded 62182159 bytes. 07:11:17 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/BEg1anclTmmD7bmx1GwmKA/artifacts/public/build/target.tar.bz2 07:11:17 INFO - mkdir: /builds/slave/test/properties 07:11:17 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:11:17 INFO - Writing to file /builds/slave/test/properties/build_url 07:11:17 INFO - Contents: 07:11:17 INFO - build_url:https://queue.taskcluster.net/v1/task/BEg1anclTmmD7bmx1GwmKA/artifacts/public/build/target.tar.bz2 07:11:17 INFO - Running post-action listener: _resource_record_post_action 07:11:17 INFO - Running post-action listener: set_extra_try_arguments 07:11:17 INFO - [mozharness: 2017-07-28 14:11:17.588060Z] Finished download-and-extract step (success) 07:11:17 INFO - [mozharness: 2017-07-28 14:11:17.588206Z] Running populate-webroot step. 07:11:17 INFO - Running pre-action listener: _resource_record_pre_action 07:11:17 INFO - Running main action method: populate_webroot 07:11:17 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:11:17 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:11:17 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 07:11:17 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:11:17 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 07:11:17 INFO - 'tests': ['damp', 'tps']}, 07:11:17 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:11:17 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 07:11:17 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 07:11:17 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 07:11:17 INFO - 'other-e10s': {'tests': ['a11yr', 07:11:17 INFO - 'ts_paint', 07:11:17 INFO - 'tpaint', 07:11:17 INFO - 'sessionrestore', 07:11:17 INFO - 'sessionrestore_many_windows', 07:11:17 INFO - 'sessionrestore_no_auto_restore', 07:11:17 INFO - 'tabpaint']}, 07:11:17 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 07:11:17 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 07:11:17 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 07:11:17 INFO - 'talos_options': ['--mitmproxy', 07:11:17 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 07:11:17 INFO - '--firstNonBlankPaint'], 07:11:17 INFO - 'tests': ['quantum_pageload_google', 07:11:17 INFO - 'quantum_pageload_youtube', 07:11:17 INFO - 'quantum_pageload_amazon', 07:11:17 INFO - 'quantum_pageload_facebook']}, 07:11:17 INFO - 'quantum-pageload-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 07:11:17 INFO - 'talos_options': ['--stylo', 07:11:17 INFO - '--mitmproxy', 07:11:17 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 07:11:17 INFO - '--firstNonBlankPaint'], 07:11:17 INFO - 'tests': ['quantum_pageload_google', 07:11:17 INFO - 'quantum_pageload_youtube', 07:11:17 INFO - 'quantum_pageload_amazon', 07:11:17 INFO - 'quantum_pageload_facebook']}, 07:11:17 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:11:17 INFO - 'tsvgr_opacity', 07:11:17 INFO - 'tart', 07:11:17 INFO - 'tscrollx', 07:11:17 INFO - 'cart', 07:11:17 INFO - 'tsvg_static']}, 07:11:17 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 07:11:17 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 07:11:17 INFO - 'talos_options': ['--stylo', 07:11:17 INFO - '--stylo-threads=1', 07:11:17 INFO - '--mitmproxy', 07:11:17 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 07:11:17 INFO - '--firstNonBlankPaint'], 07:11:17 INFO - 'tests': ['tp6_google', 07:11:17 INFO - 'tp6_youtube', 07:11:17 INFO - 'tp6_amazon', 07:11:17 INFO - 'tp6_facebook']}, 07:11:17 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 07:11:17 INFO - 'talos_options': ['--xperf_path', 07:11:17 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:11:17 INFO - 'tests': ['tp5n']}}} 07:11:17 INFO - Running post-action listener: _resource_record_post_action 07:11:17 INFO - [mozharness: 2017-07-28 14:11:17.599280Z] Finished populate-webroot step (success) 07:11:17 INFO - [mozharness: 2017-07-28 14:11:17.599371Z] Running create-virtualenv step. 07:11:17 INFO - Running pre-action listener: _resource_record_pre_action 07:11:17 INFO - Running main action method: create_virtualenv 07:11:17 INFO - Creating virtualenv /builds/slave/test/build/venv 07:11:17 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 07:11:17 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:11:17 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 07:11:17 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:11:17 INFO - Using real prefix '/usr' 07:11:17 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:11:18 INFO - Installing distribute.............................................................................................................................................................................................done. 07:11:20 INFO - Installing pip.................done. 07:11:20 INFO - Return code: 0 07:11:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 07:11:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 07:11:20 INFO - Reading from file tmpfile_stdout 07:11:20 INFO - Output received: 07:11:20 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) 07:11:20 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:11:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:11:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:11:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:11:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:11:20 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 0xcb01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe7ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101fd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101ceb0>, '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 0x101ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101e240>, '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': '51fc86400edbde9650d2781a000001d0-1501250581.574311-1448507222', '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 07:11:20 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 07:11:20 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 07:11:20 INFO - Using env: {'DISPLAY': ':0', 07:11:20 INFO - 'HOME': '/home/cltbld', 07:11:20 INFO - 'LANG': 'en_US.UTF-8', 07:11:20 INFO - 'LANGUAGE': 'en_US:en', 07:11:20 INFO - 'LOGNAME': 'cltbld', 07:11:20 INFO - 'MAIL': '/var/mail/cltbld', 07:11:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:11:20 INFO - 'MOZ_NO_REMOTE': '1', 07:11:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:11:20 INFO - 'NO_EM_RESTART': '1', 07:11:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:11:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:11:20 INFO - 'PWD': '/builds/slave/test', 07:11:20 INFO - 'SHELL': '/bin/bash', 07:11:20 INFO - 'SHLVL': '1', 07:11:20 INFO - 'TERM': 'linux', 07:11:20 INFO - 'TMOUT': '86400', 07:11:20 INFO - 'USER': 'cltbld', 07:11:20 INFO - 'XDG_SESSION_COOKIE': '51fc86400edbde9650d2781a000001d0-1501250581.574311-1448507222', 07:11:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:11:20 INFO - '_': '/tools/buildbot/bin/python'} 07:11:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:11:20 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 07:11:20 INFO - Cleaning up... 07:11:20 INFO - Return code: 0 07:11:20 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:11:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:11:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:11:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:11:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:11:20 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 0xcb01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe7ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101fd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101ceb0>, '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 0x101ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101e240>, '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': '51fc86400edbde9650d2781a000001d0-1501250581.574311-1448507222', '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 07:11:20 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 07:11:20 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 07:11:20 INFO - Using env: {'DISPLAY': ':0', 07:11:20 INFO - 'HOME': '/home/cltbld', 07:11:20 INFO - 'LANG': 'en_US.UTF-8', 07:11:20 INFO - 'LANGUAGE': 'en_US:en', 07:11:20 INFO - 'LOGNAME': 'cltbld', 07:11:20 INFO - 'MAIL': '/var/mail/cltbld', 07:11:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:11:20 INFO - 'MOZ_NO_REMOTE': '1', 07:11:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:11:20 INFO - 'NO_EM_RESTART': '1', 07:11:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:11:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:11:20 INFO - 'PWD': '/builds/slave/test', 07:11:20 INFO - 'SHELL': '/bin/bash', 07:11:20 INFO - 'SHLVL': '1', 07:11:20 INFO - 'TERM': 'linux', 07:11:20 INFO - 'TMOUT': '86400', 07:11:20 INFO - 'USER': 'cltbld', 07:11:20 INFO - 'XDG_SESSION_COOKIE': '51fc86400edbde9650d2781a000001d0-1501250581.574311-1448507222', 07:11:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:11:20 INFO - '_': '/tools/buildbot/bin/python'} 07:11:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:11:20 INFO - Downloading/unpacking psutil>=3.1.1 07:11:20 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 07:11:20 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 07:11:20 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 07:11:20 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 07:11:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:11:22 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:11:22 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:11:22 INFO - Installing collected packages: psutil 07:11:22 INFO - Running setup.py install for psutil 07:11:22 INFO - building 'psutil._psutil_linux' extension 07:11:22 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 07:11:22 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 07:11:22 INFO - building 'psutil._psutil_posix' extension 07:11:22 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 07:11:22 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 07:11:22 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:11:22 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:11:22 INFO - Successfully installed psutil 07:11:22 INFO - Cleaning up... 07:11:22 INFO - Return code: 0 07:11:22 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:11:22 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')]} 07:11:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:11:22 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')]} 07:11:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:11:22 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 0xcb01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe7ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101fd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101ceb0>, '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 0x101ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101e240>, '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': '51fc86400edbde9650d2781a000001d0-1501250581.574311-1448507222', '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 07:11:22 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 07:11:22 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 07:11:22 INFO - Using env: {'DISPLAY': ':0', 07:11:22 INFO - 'HOME': '/home/cltbld', 07:11:22 INFO - 'LANG': 'en_US.UTF-8', 07:11:22 INFO - 'LANGUAGE': 'en_US:en', 07:11:22 INFO - 'LOGNAME': 'cltbld', 07:11:22 INFO - 'MAIL': '/var/mail/cltbld', 07:11:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:11:22 INFO - 'MOZ_NO_REMOTE': '1', 07:11:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:11:22 INFO - 'NO_EM_RESTART': '1', 07:11:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:11:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:11:22 INFO - 'PWD': '/builds/slave/test', 07:11:22 INFO - 'SHELL': '/bin/bash', 07:11:22 INFO - 'SHLVL': '1', 07:11:22 INFO - 'TERM': 'linux', 07:11:22 INFO - 'TMOUT': '86400', 07:11:22 INFO - 'USER': 'cltbld', 07:11:22 INFO - 'XDG_SESSION_COOKIE': '51fc86400edbde9650d2781a000001d0-1501250581.574311-1448507222', 07:11:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:11:22 INFO - '_': '/tools/buildbot/bin/python'} 07:11:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:11:22 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:11:22 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 07:11:22 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 07:11:22 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 07:11:22 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 07:11:23 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:11:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:11:23 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:11:23 INFO - Installing collected packages: mozsystemmonitor 07:11:23 INFO - Running setup.py install for mozsystemmonitor 07:11:24 INFO - Successfully installed mozsystemmonitor 07:11:24 INFO - Cleaning up... 07:11:24 INFO - Return code: 0 07:11:24 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 07:11:24 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')]} 07:11:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:11:24 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')]} 07:11:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:11:24 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 0xcb01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe7ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101fd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101ceb0>, '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 0x101ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101e240>, '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': '51fc86400edbde9650d2781a000001d0-1501250581.574311-1448507222', '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 07:11:24 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 07:11:24 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 07:11:24 INFO - Using env: {'DISPLAY': ':0', 07:11:24 INFO - 'HOME': '/home/cltbld', 07:11:24 INFO - 'LANG': 'en_US.UTF-8', 07:11:24 INFO - 'LANGUAGE': 'en_US:en', 07:11:24 INFO - 'LOGNAME': 'cltbld', 07:11:24 INFO - 'MAIL': '/var/mail/cltbld', 07:11:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:11:24 INFO - 'MOZ_NO_REMOTE': '1', 07:11:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:11:24 INFO - 'NO_EM_RESTART': '1', 07:11:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:11:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:11:24 INFO - 'PWD': '/builds/slave/test', 07:11:24 INFO - 'SHELL': '/bin/bash', 07:11:24 INFO - 'SHLVL': '1', 07:11:24 INFO - 'TERM': 'linux', 07:11:24 INFO - 'TMOUT': '86400', 07:11:24 INFO - 'USER': 'cltbld', 07:11:24 INFO - 'XDG_SESSION_COOKIE': '51fc86400edbde9650d2781a000001d0-1501250581.574311-1448507222', 07:11:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:11:24 INFO - '_': '/tools/buildbot/bin/python'} 07:11:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:11:24 INFO - Downloading/unpacking jsonschema==2.5.1 07:11:24 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 07:11:24 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 07:11:24 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 07:11:24 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 07:11:25 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:11:25 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 07:11:25 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 07:11:25 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 07:11:25 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 07:11:25 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 07:11:25 INFO - Downloading functools32-3.2.3-2.tar.gz 07:11:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:11:25 INFO - warning: no files found matching '*.txt' 07:11:25 INFO - no previously-included directories found matching 'build' 07:11:25 INFO - no previously-included directories found matching 'dist' 07:11:25 INFO - no previously-included directories found matching '.git*' 07:11:25 INFO - Installing collected packages: jsonschema, functools32 07:11:25 INFO - Running setup.py install for functools32 07:11:25 INFO - warning: no files found matching '*.txt' 07:11:25 INFO - no previously-included directories found matching 'build' 07:11:25 INFO - no previously-included directories found matching 'dist' 07:11:25 INFO - no previously-included directories found matching '.git*' 07:11:25 INFO - Successfully installed jsonschema functools32 07:11:25 INFO - Cleaning up... 07:11:25 INFO - Return code: 0 07:11:25 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 07:11:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:11:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:11:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:11:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:11:25 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 0xcb01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe7ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101fd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101ceb0>, '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 0x101ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101e240>, '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': '51fc86400edbde9650d2781a000001d0-1501250581.574311-1448507222', '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 07:11:25 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 07:11:25 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 07:11:25 INFO - Using env: {'DISPLAY': ':0', 07:11:25 INFO - 'HOME': '/home/cltbld', 07:11:25 INFO - 'LANG': 'en_US.UTF-8', 07:11:25 INFO - 'LANGUAGE': 'en_US:en', 07:11:25 INFO - 'LOGNAME': 'cltbld', 07:11:25 INFO - 'MAIL': '/var/mail/cltbld', 07:11:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:11:25 INFO - 'MOZ_NO_REMOTE': '1', 07:11:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:11:25 INFO - 'NO_EM_RESTART': '1', 07:11:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:11:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:11:25 INFO - 'PWD': '/builds/slave/test', 07:11:25 INFO - 'SHELL': '/bin/bash', 07:11:25 INFO - 'SHLVL': '1', 07:11:25 INFO - 'TERM': 'linux', 07:11:25 INFO - 'TMOUT': '86400', 07:11:25 INFO - 'USER': 'cltbld', 07:11:25 INFO - 'XDG_SESSION_COOKIE': '51fc86400edbde9650d2781a000001d0-1501250581.574311-1448507222', 07:11:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:11:25 INFO - '_': '/tools/buildbot/bin/python'} 07:11:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:11:25 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 07:11:25 INFO - Cleaning up... 07:11:26 INFO - Return code: 0 07:11:26 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:11:26 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')]} 07:11:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:11:26 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')]} 07:11:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:11:26 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 0xcb01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe7ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101fd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101ceb0>, '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 0x101ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101e240>, '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': '51fc86400edbde9650d2781a000001d0-1501250581.574311-1448507222', '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 07:11:26 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 07:11:26 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 07:11:26 INFO - Using env: {'DISPLAY': ':0', 07:11:26 INFO - 'HOME': '/home/cltbld', 07:11:26 INFO - 'LANG': 'en_US.UTF-8', 07:11:26 INFO - 'LANGUAGE': 'en_US:en', 07:11:26 INFO - 'LOGNAME': 'cltbld', 07:11:26 INFO - 'MAIL': '/var/mail/cltbld', 07:11:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:11:26 INFO - 'MOZ_NO_REMOTE': '1', 07:11:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:11:26 INFO - 'NO_EM_RESTART': '1', 07:11:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:11:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:11:26 INFO - 'PWD': '/builds/slave/test', 07:11:26 INFO - 'SHELL': '/bin/bash', 07:11:26 INFO - 'SHLVL': '1', 07:11:26 INFO - 'TERM': 'linux', 07:11:26 INFO - 'TMOUT': '86400', 07:11:26 INFO - 'USER': 'cltbld', 07:11:26 INFO - 'XDG_SESSION_COOKIE': '51fc86400edbde9650d2781a000001d0-1501250581.574311-1448507222', 07:11:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:11:26 INFO - '_': '/tools/buildbot/bin/python'} 07:11:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:11:26 INFO - Downloading/unpacking blobuploader==1.2.4 07:11:26 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 07:11:26 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 07:11:26 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 07:11:26 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 07:11:27 INFO - Downloading blobuploader-1.2.4.tar.gz 07:11:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:11:27 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:11:27 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 07:11:27 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 07:11:27 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 07:11:27 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 07:11:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:11:27 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:11:27 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 07:11:27 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 07:11:27 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 07:11:27 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 07:11:27 INFO - Downloading docopt-0.6.1.tar.gz 07:11:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:11:27 INFO - Installing collected packages: blobuploader, requests, docopt 07:11:27 INFO - Running setup.py install for blobuploader 07:11:27 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:11:27 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:11:27 INFO - Running setup.py install for requests 07:11:28 INFO - Running setup.py install for docopt 07:11:28 INFO - Successfully installed blobuploader requests docopt 07:11:28 INFO - Cleaning up... 07:11:28 INFO - Return code: 0 07:11:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:11:28 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')]} 07:11:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:11:28 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')]} 07:11:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:11:28 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 0xcb01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe7ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101fd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101ceb0>, '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 0x101ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101e240>, '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': '51fc86400edbde9650d2781a000001d0-1501250581.574311-1448507222', '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 07:11:28 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 07:11:28 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 07:11:28 INFO - Using env: {'DISPLAY': ':0', 07:11:28 INFO - 'HOME': '/home/cltbld', 07:11:28 INFO - 'LANG': 'en_US.UTF-8', 07:11:28 INFO - 'LANGUAGE': 'en_US:en', 07:11:28 INFO - 'LOGNAME': 'cltbld', 07:11:28 INFO - 'MAIL': '/var/mail/cltbld', 07:11:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:11:28 INFO - 'MOZ_NO_REMOTE': '1', 07:11:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:11:28 INFO - 'NO_EM_RESTART': '1', 07:11:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:11:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:11:28 INFO - 'PWD': '/builds/slave/test', 07:11:28 INFO - 'SHELL': '/bin/bash', 07:11:28 INFO - 'SHLVL': '1', 07:11:28 INFO - 'TERM': 'linux', 07:11:28 INFO - 'TMOUT': '86400', 07:11:28 INFO - 'USER': 'cltbld', 07:11:28 INFO - 'XDG_SESSION_COOKIE': '51fc86400edbde9650d2781a000001d0-1501250581.574311-1448507222', 07:11:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:11:28 INFO - '_': '/tools/buildbot/bin/python'} 07:11:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:11:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:11:28 INFO - Running setup.py (path:/tmp/pip-bChWYI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:11:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:11:28 INFO - Running setup.py (path:/tmp/pip-rHxX6x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:11:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:11:28 INFO - Running setup.py (path:/tmp/pip-XWWzDB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:11:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:11:28 INFO - Running setup.py (path:/tmp/pip-MXdog8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:11:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:11:28 INFO - Running setup.py (path:/tmp/pip-FuJvTt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:11:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:11:29 INFO - Running setup.py (path:/tmp/pip-3Nc96Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:11:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:11:29 INFO - Running setup.py (path:/tmp/pip-YkJcPK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:11:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:11:29 INFO - Running setup.py (path:/tmp/pip-8LspGE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:11:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:11:29 INFO - Running setup.py (path:/tmp/pip-8L2O12-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:11:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:11:29 INFO - Running setup.py (path:/tmp/pip-u2hQxq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:11:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:11:29 INFO - Running setup.py (path:/tmp/pip-GO1msP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:11:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:11:29 INFO - Running setup.py (path:/tmp/pip-GPh_VF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:11:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:11:29 INFO - Running setup.py (path:/tmp/pip-s7uj7x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:11:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:11:29 INFO - Running setup.py (path:/tmp/pip-QmBNtj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:11:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:11:29 INFO - Running setup.py (path:/tmp/pip-e7hvv7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:11:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:11:29 INFO - Running setup.py (path:/tmp/pip-uZOHfW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:11:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:11:29 INFO - Running setup.py (path:/tmp/pip-yPBwBW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:11:29 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:11:29 INFO - Running setup.py install for manifestparser 07:11:30 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:11:30 INFO - Running setup.py install for mozcrash 07:11:30 INFO - Running setup.py install for mozdebug 07:11:30 INFO - Running setup.py install for mozdevice 07:11:30 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:11:30 INFO - Running setup.py install for mozfile 07:11:30 INFO - Running setup.py install for mozhttpd 07:11:30 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:11:30 INFO - Running setup.py install for mozinfo 07:11:30 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:11:30 INFO - Running setup.py install for mozInstall 07:11:30 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:11:30 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:11:30 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:11:30 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:11:30 INFO - Running setup.py install for mozleak 07:11:30 INFO - Running setup.py install for mozlog 07:11:30 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:11:30 INFO - Running setup.py install for moznetwork 07:11:31 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:11:31 INFO - Running setup.py install for mozprocess 07:11:31 INFO - Running setup.py install for mozprofile 07:11:31 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:11:31 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:11:31 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:11:31 INFO - Running setup.py install for mozrunner 07:11:31 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:11:31 INFO - Running setup.py install for mozscreenshot 07:11:31 INFO - Running setup.py install for moztest 07:11:31 INFO - Running setup.py install for mozversion 07:11:31 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:11:31 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:11:31 INFO - Cleaning up... 07:11:31 INFO - Return code: 0 07:11:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:11:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:11:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:11:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:11:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:11:31 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 0xcb01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe7ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101fd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101ceb0>, '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 0x101ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101e240>, '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': '51fc86400edbde9650d2781a000001d0-1501250581.574311-1448507222', '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 07:11:31 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 07:11:31 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 07:11:31 INFO - Using env: {'DISPLAY': ':0', 07:11:31 INFO - 'HOME': '/home/cltbld', 07:11:31 INFO - 'LANG': 'en_US.UTF-8', 07:11:31 INFO - 'LANGUAGE': 'en_US:en', 07:11:31 INFO - 'LOGNAME': 'cltbld', 07:11:31 INFO - 'MAIL': '/var/mail/cltbld', 07:11:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:11:31 INFO - 'MOZ_NO_REMOTE': '1', 07:11:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:11:31 INFO - 'NO_EM_RESTART': '1', 07:11:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:11:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:11:31 INFO - 'PWD': '/builds/slave/test', 07:11:31 INFO - 'SHELL': '/bin/bash', 07:11:31 INFO - 'SHLVL': '1', 07:11:31 INFO - 'TERM': 'linux', 07:11:31 INFO - 'TMOUT': '86400', 07:11:31 INFO - 'USER': 'cltbld', 07:11:31 INFO - 'XDG_SESSION_COOKIE': '51fc86400edbde9650d2781a000001d0-1501250581.574311-1448507222', 07:11:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:11:31 INFO - '_': '/tools/buildbot/bin/python'} 07:11:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:11:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:11:31 INFO - Running setup.py (path:/tmp/pip-t42lei-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:11:32 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)) 07:11:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:11:32 INFO - Running setup.py (path:/tmp/pip-0AN6tK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:11:32 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)) 07:11:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:11:32 INFO - Running setup.py (path:/tmp/pip-egHU4W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:11:32 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)) 07:11:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:11:32 INFO - Running setup.py (path:/tmp/pip-24_40Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:11:32 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)) 07:11:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:11:32 INFO - Running setup.py (path:/tmp/pip-QzLw81-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:11:32 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)) 07:11:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:11:32 INFO - Running setup.py (path:/tmp/pip-yaOQAK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:11:32 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)) 07:11:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:11:32 INFO - Running setup.py (path:/tmp/pip-YPfSuL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:11:32 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)) 07:11:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:11:32 INFO - Running setup.py (path:/tmp/pip-X_FkSC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:11:32 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)) 07:11:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:11:32 INFO - Running setup.py (path:/tmp/pip-a_DWRp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:11:32 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)) 07:11:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:11:32 INFO - Running setup.py (path:/tmp/pip-Jtq54P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:11:32 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)) 07:11:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:11:32 INFO - Running setup.py (path:/tmp/pip-ST1JX_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:11:32 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)) 07:11:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:11:32 INFO - Running setup.py (path:/tmp/pip-5m0raM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:11:32 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)) 07:11:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:11:32 INFO - Running setup.py (path:/tmp/pip-lcubQ_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:11:32 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)) 07:11:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:11:32 INFO - Running setup.py (path:/tmp/pip-akaEc2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:11:32 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)) 07:11:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:11:32 INFO - Running setup.py (path:/tmp/pip-lo0xZB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:11:33 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)) 07:11:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:11:33 INFO - Running setup.py (path:/tmp/pip-Bk_Zo0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:11:33 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)) 07:11:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:11:33 INFO - Running setup.py (path:/tmp/pip-m9OUlW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:11:33 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)) 07:11:33 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)) 07:11:33 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)) 07:11:33 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)) 07:11:33 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)) 07:11:33 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)) 07:11:33 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)) 07:11:33 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:11:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:11:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:11:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:11:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:11:34 INFO - Downloading blessings-1.6.tar.gz 07:11:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:11:34 INFO - Installing collected packages: blessings 07:11:34 INFO - Running setup.py install for blessings 07:11:34 INFO - Successfully installed blessings 07:11:34 INFO - Cleaning up... 07:11:34 INFO - Return code: 0 07:11:34 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:11:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:11:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:11:34 INFO - Reading from file tmpfile_stdout 07:11:34 INFO - Current package versions: 07:11:34 INFO - argparse == 1.2.1 07:11:34 INFO - blessings == 1.6 07:11:34 INFO - blobuploader == 1.2.4 07:11:34 INFO - docopt == 0.6.1 07:11:34 INFO - functools32 == 3.2.3-2 07:11:34 INFO - jsonschema == 2.5.1 07:11:34 INFO - manifestparser == 1.1 07:11:34 INFO - mozInstall == 1.13 07:11:34 INFO - mozcrash == 1.0 07:11:34 INFO - mozdebug == 0.1 07:11:34 INFO - mozdevice == 0.51 07:11:34 INFO - mozfile == 1.2 07:11:34 INFO - mozhttpd == 0.7 07:11:34 INFO - mozinfo == 0.10 07:11:34 INFO - mozleak == 0.1 07:11:34 INFO - mozlog == 3.5 07:11:34 INFO - moznetwork == 0.27 07:11:34 INFO - mozprocess == 0.25 07:11:34 INFO - mozprofile == 0.28 07:11:34 INFO - mozrunner == 6.13 07:11:34 INFO - mozscreenshot == 0.1 07:11:34 INFO - mozsystemmonitor == 0.3 07:11:34 INFO - moztest == 0.8 07:11:34 INFO - mozversion == 1.4 07:11:34 INFO - psutil == 3.1.1 07:11:34 INFO - requests == 1.2.3 07:11:34 INFO - wsgiref == 0.1.2 07:11:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:11:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:11:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:11:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:11:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:11:34 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 0xcb01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe7ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101fd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101ceb0>, '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 0x101ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101e240>, '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': '51fc86400edbde9650d2781a000001d0-1501250581.574311-1448507222', '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 07:11:34 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 07:11:34 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 07:11:34 INFO - Using env: {'DISPLAY': ':0', 07:11:34 INFO - 'HOME': '/home/cltbld', 07:11:34 INFO - 'LANG': 'en_US.UTF-8', 07:11:34 INFO - 'LANGUAGE': 'en_US:en', 07:11:34 INFO - 'LOGNAME': 'cltbld', 07:11:34 INFO - 'MAIL': '/var/mail/cltbld', 07:11:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:11:34 INFO - 'MOZ_NO_REMOTE': '1', 07:11:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:11:34 INFO - 'NO_EM_RESTART': '1', 07:11:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:11:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:11:34 INFO - 'PWD': '/builds/slave/test', 07:11:34 INFO - 'SHELL': '/bin/bash', 07:11:34 INFO - 'SHLVL': '1', 07:11:34 INFO - 'TERM': 'linux', 07:11:34 INFO - 'TMOUT': '86400', 07:11:34 INFO - 'USER': 'cltbld', 07:11:34 INFO - 'XDG_SESSION_COOKIE': '51fc86400edbde9650d2781a000001d0-1501250581.574311-1448507222', 07:11:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:11:34 INFO - '_': '/tools/buildbot/bin/python'} 07:11:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:11:34 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)) 07:11:34 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)) 07:11:34 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)) 07:11:34 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)) 07:11:34 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)) 07:11:34 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)) 07:11:34 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)) 07:11:34 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)) 07:11:34 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)) 07:11:34 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 07:11:34 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 07:11:34 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 07:11:34 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 07:11:34 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 07:11:36 INFO - Downloading marionette_driver-1.4.0.tar.gz 07:11:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 07:11:36 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 11)) 07:11:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:11:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:11:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:11:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:11:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 07:11:36 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)) 07:11:36 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)) 07:11:36 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)) 07:11:36 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)) 07:11:36 INFO - Installing collected packages: marionette-driver, simplejson 07:11:36 INFO - Running setup.py install for marionette-driver 07:11:36 INFO - Running setup.py install for simplejson 07:11:36 INFO - building 'simplejson._speedups' extension 07:11:36 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 07:11:37 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 07:11:37 INFO - Successfully installed marionette-driver simplejson 07:11:37 INFO - Cleaning up... 07:11:37 INFO - Return code: 0 07:11:37 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:11:37 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')]} 07:11:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:11:37 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')]} 07:11:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:11:37 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 0xcb01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe7ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101fd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101ceb0>, '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 0x101ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101e240>, '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': '51fc86400edbde9650d2781a000001d0-1501250581.574311-1448507222', '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 07:11:37 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 07:11:37 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 07:11:37 INFO - Using env: {'DISPLAY': ':0', 07:11:37 INFO - 'HOME': '/home/cltbld', 07:11:37 INFO - 'LANG': 'en_US.UTF-8', 07:11:37 INFO - 'LANGUAGE': 'en_US:en', 07:11:37 INFO - 'LOGNAME': 'cltbld', 07:11:37 INFO - 'MAIL': '/var/mail/cltbld', 07:11:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:11:37 INFO - 'MOZ_NO_REMOTE': '1', 07:11:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:11:37 INFO - 'NO_EM_RESTART': '1', 07:11:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:11:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:11:37 INFO - 'PWD': '/builds/slave/test', 07:11:37 INFO - 'SHELL': '/bin/bash', 07:11:37 INFO - 'SHLVL': '1', 07:11:37 INFO - 'TERM': 'linux', 07:11:37 INFO - 'TMOUT': '86400', 07:11:37 INFO - 'USER': 'cltbld', 07:11:37 INFO - 'XDG_SESSION_COOKIE': '51fc86400edbde9650d2781a000001d0-1501250581.574311-1448507222', 07:11:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:11:37 INFO - '_': '/tools/buildbot/bin/python'} 07:11:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:11:37 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 07:11:37 INFO - Cleaning up... 07:11:37 INFO - Return code: 0 07:11:37 INFO - Running post-action listener: _resource_record_post_action 07:11:37 INFO - Running post-action listener: _start_resource_monitoring 07:11:37 INFO - Starting resource monitoring. 07:11:37 INFO - [mozharness: 2017-07-28 14:11:37.783118Z] Finished create-virtualenv step (success) 07:11:37 INFO - [mozharness: 2017-07-28 14:11:37.783637Z] Running install step. 07:11:37 INFO - Running pre-action listener: _resource_record_pre_action 07:11:37 INFO - Running main action method: install 07:11:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:11:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:11:37 INFO - Reading from file tmpfile_stdout 07:11:38 INFO - Detecting whether we're running mozinstall >=1.0... 07:11:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:11:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:11:38 INFO - Reading from file tmpfile_stdout 07:11:38 INFO - Output received: 07:11:38 INFO - Usage: mozinstall [options] installer 07:11:38 INFO - Options: 07:11:38 INFO - -h, --help show this help message and exit 07:11:38 INFO - -d DEST, --destination=DEST 07:11:38 INFO - Directory to install application into. [default: 07:11:38 INFO - "/builds/slave/test"] 07:11:38 INFO - --app=APP Application being installed. [default: firefox] 07:11:38 INFO - mkdir: /builds/slave/test/build/application 07:11:38 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'] 07:11:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 07:11:56 INFO - Reading from file tmpfile_stdout 07:11:56 INFO - Output received: 07:11:56 INFO - /builds/slave/test/build/application/firefox/firefox 07:11:56 INFO - Running post-action listener: _resource_record_post_action 07:11:56 INFO - [mozharness: 2017-07-28 14:11:56.374325Z] Finished install step (success) 07:11:56 INFO - [mozharness: 2017-07-28 14:11:56.374718Z] Running setup-mitmproxy step. 07:11:56 INFO - Running pre-action listener: _resource_record_pre_action 07:11:56 INFO - Running main action method: setup_mitmproxy 07:11:56 INFO - Skipping: mitmproxy is not required 07:11:56 INFO - Running post-action listener: _resource_record_post_action 07:11:56 INFO - [mozharness: 2017-07-28 14:11:56.376336Z] Finished setup-mitmproxy step (success) 07:11:56 INFO - [mozharness: 2017-07-28 14:11:56.376650Z] Running run-tests step. 07:11:56 INFO - Running pre-action listener: _resource_record_pre_action 07:11:56 INFO - Running pre-action listener: _set_gcov_prefix 07:11:56 INFO - Running main action method: run_tests 07:11:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:11:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:11:56 INFO - Python 2.7.3 07:11:56 INFO - Return code: 0 07:11:56 INFO - grabbing minidump binary from tooltool 07:11:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:11:56 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 0x101ceb0>, '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 0x101ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101e240>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:11:56 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 07:11:56 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 07:11:56 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 07:11:56 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:11:56 INFO - Return code: 0 07:11:56 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 07:11:56 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:11:56 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:11:56 INFO - ENV: RUST_BACKTRACE is now 1 07:11:56 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 07:11:56 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:11:56 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/BEg1anclTmmD7bmx1GwmKA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-082', '--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 07:11:56 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/BEg1anclTmmD7bmx1GwmKA/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-082 --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 07:11:56 INFO - Using env: {'DISPLAY': ':0', 07:11:56 INFO - 'HOME': '/home/cltbld', 07:11:56 INFO - 'LANG': 'en_US.UTF-8', 07:11:56 INFO - 'LANGUAGE': 'en_US:en', 07:11:56 INFO - 'LOGNAME': 'cltbld', 07:11:56 INFO - 'MAIL': '/var/mail/cltbld', 07:11:56 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:11:56 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 07:11:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:11:56 INFO - 'MOZ_NO_REMOTE': '1', 07:11:56 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:11:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:11:56 INFO - 'NO_EM_RESTART': '1', 07:11:56 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:11:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:11:56 INFO - 'PWD': '/builds/slave/test', 07:11:56 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:11:56 INFO - 'RUST_BACKTRACE': '1', 07:11:56 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 07:11:56 INFO - 'SHELL': '/bin/bash', 07:11:56 INFO - 'SHLVL': '1', 07:11:56 INFO - 'STYLO_FORCE_ENABLED': '1', 07:11:56 INFO - 'TERM': 'linux', 07:11:56 INFO - 'TMOUT': '86400', 07:11:56 INFO - 'USER': 'cltbld', 07:11:56 INFO - 'XDG_SESSION_COOKIE': '51fc86400edbde9650d2781a000001d0-1501250581.574311-1448507222', 07:11:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:11:56 INFO - '_': '/tools/buildbot/bin/python'} 07:11:56 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/BEg1anclTmmD7bmx1GwmKA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-082', '--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 07:11:56 INFO - mozversion application_buildid: 20170728132457 07:11:56 INFO - mozversion application_changeset: 16ffc1d05422a81099ce8b9b59de66dde4c8b2f0 07:11:56 INFO - mozversion application_display_name: Nightly 07:11:56 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:11:56 INFO - mozversion application_name: Firefox 07:11:56 INFO - mozversion application_remotingname: firefox 07:11:56 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 07:11:56 INFO - mozversion application_vendor: Mozilla 07:11:56 INFO - mozversion application_version: 56.0a1 07:11:56 INFO - mozversion platform_buildid: 20170728132457 07:11:56 INFO - mozversion platform_changeset: 16ffc1d05422a81099ce8b9b59de66dde4c8b2f0 07:11:56 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 07:11:56 INFO - mozversion platform_version: 56.0a1 07:11:56 INFO - using testdate: 1501251116 07:11:56 INFO - actual date: 1501251116 07:11:56 INFO - starting webserver on 'localhost:54488' 07:11:56 INFO - SUITE-START | Running 2 tests 07:11:56 INFO - TEST-START | dromaeo_css 07:11:56 INFO - Initialising browser for dromaeo_css test... 07:11:56 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:54488/getInfo.html -profile /tmp/tmpM2fnE3/profile 07:11:56 INFO - TEST-INFO | started process 15867 (/builds/slave/test/build/application/firefox/firefox http://localhost:54488/getInfo.html) 07:12:02 INFO - TEST-INFO | 15867: exit 0 07:12:02 INFO - Browser initialized. 07:12:02 INFO - Running cycle 1/1 for dromaeo_css test... 07:12:02 INFO - TEST-INFO | started process 16098 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpM2fnE3/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 07:12:03 INFO - PID 16098 | 07:12:03 INFO - PID 16098 | (/builds/slave/test/build/application/firefox/firefox:16152): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 07:12:03 INFO - PID 16098 | 07:12:14 INFO - PID 16098 | RSS: Main: 173498368 07:12:14 INFO - PID 16098 | 07:15:13 INFO - PID 16098 | Cycle 1(1): loaded http://localhost:54488/tests/dromaeo/cssquery-dojo.html (next: http://localhost:54488/tests/dromaeo/cssquery-ext.html) 07:15:14 INFO - PID 16098 | RSS: Main: 175767552 07:15:14 INFO - PID 16098 | 07:18:16 INFO - PID 16098 | Cycle 1(1): loaded http://localhost:54488/tests/dromaeo/cssquery-ext.html (next: http://localhost:54488/tests/dromaeo/cssquery-jquery.html) 07:18:17 INFO - PID 16098 | RSS: Main: 171524096 07:18:17 INFO - PID 16098 | 07:21:19 INFO - PID 16098 | Cycle 1(1): loaded http://localhost:54488/tests/dromaeo/cssquery-jquery.html (next: http://localhost:54488/tests/dromaeo/cssquery-mootools.html) 07:21:20 INFO - PID 16098 | RSS: Main: 173608960 07:21:20 INFO - PID 16098 | 07:24:21 INFO - PID 16098 | Cycle 1(1): loaded http://localhost:54488/tests/dromaeo/cssquery-mootools.html (next: http://localhost:54488/tests/dromaeo/cssquery-prototype.html) 07:24:22 INFO - PID 16098 | RSS: Main: 173645824 07:24:22 INFO - PID 16098 | 07:27:26 INFO - PID 16098 | Cycle 1(1): loaded http://localhost:54488/tests/dromaeo/cssquery-prototype.html (next: http://localhost:54488/tests/dromaeo/cssquery-yui.html) 07:27:26 INFO - PID 16098 | RSS: Main: 174809088 07:27:26 INFO - PID 16098 | 07:30:28 INFO - PID 16098 | Cycle 1(1): loaded http://localhost:54488/tests/dromaeo/cssquery-yui.html (next: http://localhost:54488/tests/dromaeo/cssquery-dojo.html) 07:30:29 INFO - PID 16098 | RSS: Main: 177594368 07:30:29 INFO - PID 16098 | 07:30:29 INFO - PID 16098 | __start_tp_report 07:30:29 INFO - PID 16098 | _x_x_mozilla_page_load 07:30:29 INFO - PID 16098 | _x_x_mozilla_page_load_details 07:30:29 INFO - PID 16098 | |i|pagename|runs| 07:30:29 INFO - PID 16098 | |0;dojo.html;3127.1087739994105;3148.4962406015043;3182.649908510064;3182.6976437238454;3221.806691598504;6121.969390153048;6183.381661833814;6195.101710252016;6284.7486100555925;6302.684865756705;6226.3462336454695;6281.214848143982;6285.6228626282455;6327.9367206327925;6372.681365931697;5659.943400566003;5694.088945768678;5783.6529808211435;5819.156222347757;5834.241548598698;5604.691741954191;5735.885282294352;5736.684482353469;5754.424557544254;5760.279965004374;5663.631863928831;5675.3757086720425;5680.289963754531;5683.204351390808;5800.53595712342;5658.0381335173115;5727.341355744101;5727.541796656257;5742.454466825645;5743.655380677173;5632.577556683265;5637.577181711388;5739.483446489836;5777.942220577783;5779.104238842986;6068.696565171768;6158.84602884927;6206.441420272197;6233.345498722614;6257.092721555349;6740.966295168541;6744.595324280559;6761.966190169067;6764.391204791593;6811.25076241613;6757.189137303555;6796.898046529306;6864.5881247124835;6865.347791959756;6902.344277293649;6230.314665386803;6238.656873871935;6334.619922804646;6337.429631333202;6362.5546211765195;6895.172579290517;6912.066870972455;6984.825379365455;6994.125734283215;7044.119485064367;6594.142761440982;6600.900986485207;6609.966950165314;6613.5370524062855;6616.503762217853;6642.86714265712;6697.162854643169;6726.091977583062;6729.6971636276485;6739.528233023641;6636.336366363325;6667.533272670867;6694.196696396463;6708.060871477997;6713.530052896251;6043.8186854394435;6711.395974362331;6754.729810807611;6756.797296081126;6858.931410685929;5481.534069604048;5482.068048431776;5534.169874518854;5559.833205003856;5575.665460072328;4638.536146385402;4667.789949452283;4699.600533954652;4714.198586240347;4738.597219236403;5830.154627578977;5869.589128761031;5909.349971503131;5911.142884281755;5919.112133180058;6825.283345248777;6837.418819400406;6842.726290948306;6872.656367181621;6874.828129296708;5832.358440571534;5835.013035143127;5874.265716785402;5904.1734157218025;5913.793017244376;5835.095560188178;5942.8811423771285;6007;6021.0968354746265;6023.66869822164;6456.386643268882;6543.574667646683;6580.670966451658;6588.110605068255;6591.604503729792;6762.357576030269;6820.761273355409;6843.349881761224;6844.965775171092;6848.075509806312;6190.7523699051535;6529.738810447719;6564.868702626016;6591.670416479061;6603.372679595431;6586.341365863375;6589.571677840925;6675.599464032173;6689.966550167413;6709.060731497497;6663.5002374821115;6767.526273160832;6782.559133656297;6859.039734437282;6873.690683919136;6577.177852768404;6587.63767992765;6604.7027883746305;6605.207375115017;6609.966950165219;6575.736970521126;6576.605403675795;6577.079208910852;6604.504662150454;6613.933860661332;5464.480874316933;6132.693365331805;6563.671816409256;6571.605703657796;6573.04690819838;6635.1374321339845;6640.169978752656;6657.101291325609;6666.966665166643;6744.595324280559;6438.903416448663;6443.259025212161;6471.611703297817;6517.608943463408;6545.934540654533 07:30:29 INFO - PID 16098 | |1;ext.html;711.9822004449888;746.052513308099;767.1561282589153;779.3453499060788;784.051297929505;39597.2080558389;40482.39276410856;40528.392074118914;40603.796981015126;40671.59328406715;36483.81758091206;36603.26793464129;36750.448743268804;36901.26197476049;36973.630263697356;6617.139771829668;6806.319368063178;6813.080234168398;6822.385985261326;6833;6740.258571557125;6764.627945462998;6781.491388145884;6795.422389096932;6818.454523638097;7593.354564861993;7687.308142267195;7721.112072111723;7741.303282704555;7755;7675.539467631932;7731.265529774662;7741.5355078695175;7741.690332386698;7741.883871741872;7370.26297370264;7549.433792465587;7590.3927685785;7602.961985190039;7604.239576042407;38307.80846095779;38403.61596384028;38807;38825.805870970464;38867.80566097151;3469.0460123465996;3519.7184225261917;3523.577170739502;3533.770304930197;3536.734744894143;31086.689133108604;31243.84378078095;31367.529487057713;31443.213919651964;31468.37063258722;4725.527447255281;4754.381930349033;4766.475687674353;4796.280557916306;4812.061647978646;186055;188836;190128;190445;198363;47915.281270780964;47994.28008579874;48037.279440808416;48041.03917921622;48052.5194748055;50953.490465095616;51156.744216279425;51240.74379628078;51313.48686513087;51450;16417.343306267856;16541.421050263183;16552.75170872438;16568.6686266274;16610.50168494947;13518.662033449242;13613.183209007491;13627.454901803816;13645.658858528615;13661.863381366258;14072.71854562903;14283.857161428461;14340.282985850667;14412.846972242196;14611.415543378145;5691.897915635988;5704.201411802351;5728.455796699307;5754.388771144087;5854.385289544622;977.3354119199013;986.3194395866828;998.6554638649654;1005.2962925951863;1018.8879223285433;21959.780402196095;22256.77743222547;22284.44288892758;23166.305010849363;23297.301080967598;1061.7153244573997;1092.2736380307008;1110.555777688931;1125.6397952655236;1127.233481232753;11261.718457038476;12087.758244835055;12126;12192.878071219351;15903.602409939613;230121;230552;230845;230986;231104;55470.16794748082;55714.442855570924;56284.71857640768;56330.43669563334;56573.71713141408;1459.1026518690928;1465.1648560320516;1469.5591322603177;1478.8669019788272;1479.8742106920818;25677.87161064183;26755.732442676104;26950.865245673645;27442.5883611742;27985.160445186677;50265.24602130898;51117.48882511276;51230.23154652608;51276.48723512717;51278.74360628173;206967;207716;207759;207770;208184;34854.65145348514;35177;36819;37069.81465092657;37082.44376334411;43732.34401484044;45347.773261134804;46027.53972460367;46060.76969615131;46068.07863842637;30281.24296892507;30513.694863051085;32134.67865321317;32211.51682724808;32219.677803221668;30383;31374.529382059744;31890.332611749018;31951.840240798647;32018;36851.447228292134;37626.81186594049;37665.2466950654;37772.81113594415;37780.2443951114;30672.539911900894;32190.970236098998;32345.514817278232;32454.188645284055;32835;23603.173888913807;23648.645270321304;23832.523349533258;24021.879890600434;24125.155619553232 07:30:29 INFO - PID 16098 | |2;jquery.html;558.5308340993568;563.1355868741483;578.4967078641582;591.21663795471;592.2862950145076;21459.141634334628;22114;22116.668249976265;22241.110355585777;22318.442038949033;24655.137070202545;24684.629730554057;24749.257522274314;24765.38086547837;24799.87600061997;6532.934670653304;6618.4705223582;6673.566218195801;6703.329667033307;6704.229013663418;5871.324797648261;6401.679916004205;6440.871182576346;6505.674716264191;6576.671166441659;6393.936060639404;6398.648074355909;6402.839929001766;6409.615423074631;6410.455111315544;6348.682565871711;6386.9041964370335;6396.392342727433;6399.77600783975;6400.9039864402075;5894.3810899855325;5936.079907614327;5949.167116603679;5970.432808883149;5970.761169553256;29266.122016339545;30223.244418889484;30592.2351941201;30664.386712265852;30948;1892.933747318851;1955.0908827395328;1955.2667749593902;1956.0513151121836;1956.0708663384778;18775.436736897915;19227.61544769097;19244.80755192444;19279.51801204981;19331.51671208217;2273.3407311879537;2301.838871279011;2304.0898844956314;2314.7685231476885;2399.7600239976036;133076;135394;136020.31989840086;136074;138770;13994.510192143227;14631.341589628491;14855.182964936816;15023.399064037316;15079.321430535652;23075.7692423077;23543.764562354158;23556.64665030026;23622.7637723624;23889.163879264142;10315.432651204255;10381.636642717469;10516;10545.683629491128;10667.306625069501;9882.950585247028;9993.200543956465;10002.899971000343;10051.145652619609;10105.848412273821;9157.908420915839;9193.356465047382;9324.720258392259;9532.571034303473;9563.426194428357;2582.1607977407416;2592.2741632342972;2626.908058217953;2697.1234348836706;2767.8339299642084;925.8518637018045;927.888653361601;950.5817440326234;968.2447690801187;995.9352642078242;19317.8068219316;19502.5124371889;19944;22932.885335573217;24358.39104022378;1001.9148372388283;1005.5173516712019;1011.1506334678905;1034.6999370182714;1062.6705721226444;10835.51240194193;11153.163512736577;11335.716607084889;11355.318680879174;11402.91672291131;509782;543475;544486;544831;547992;113216;118276;120849;127485;128428;1187.352892673495;1215.0765418281992;1222.0651201830606;1258.157034786695;1271.841019872516;18646.906765466087;20033.499162521053;20795.272165474136;21446.142154314275;21868.23461178851;56560.43439565552;58350.12474812966;59849.70075149596;61209.08186377297;62185.378146219766;69135.30864691289;70501.29498704948;71217.64391178219;71221.64389178022;71501.64249178929;14710.779338310149;15048.62378440504;15166.772498412312;15266.313015914533;15267.465638702592;23571.41071473227;23629.763702362758;23866;23936.64095038541;24154;16895;17177.914110429367;18576;18726.438206853818;18839.529011774815;15478.613034674223;16316.836831631532;16480.505584832474;16583.91708041452;16701.415450459175;18822.153003115167;19334.61330773405;19718.309859154862;19813.108410121866;19895.602087957872;14893.851061489246;15027.624309392353;15096.849031509544;15135.697286053997;15174.013689110172;18697;18700.251989920795;18841.811581884005;18998.620027599645;19012.429627111207 07:30:29 INFO - PID 16098 | |3;mootools.html;1353.8713822186892;1398.2519352146596;1409.4432699083861;1413.9222342771156;1420.28275720761;13608.455661773529;13705.65735856604;14124.364403601838;14131.858681413183;14139.293035348217;14450.783238251408;14760.33578488968;14778.113313201215;14783.630409239775;14792;2673.451942351813;2692.4749673813517;2708.526007948611;2711.9830063726104;2712.0778935162043;2662.6804783426014;2684.7717943974785;2685.650865387497;2686.4895669822768;2686.5835795451735;2605.3877338825455;2637.696664883534;2638.868056597172;2646.4310173312733;2672.786177105827;2620.135355332736;2638.3536033671776;2644.854533000684;2647.5763877779464;2649.3376655835946;2611.647427597269;2621.659184306028;2624.6194301826317;2668.8932442702267;2678.116221646852;13585.252811095386;13889.16665000093;13933.233672148028;13948.093373930662;13957.651058723512;7627.122880868716;7806;7856.921430785676;7889.8027549311155;7908.960455197687;4786.449558300805;5013.273075404082;5085.79656813727;5095.490450954912;5116.181410974264;1815.310182130795;1835.9265629374943;1837.9908100459545;1917.1468696430095;1919.7024461208534;22858.771412285998;23096.538069238522;23107.653385199235;23132.306030818934;23135;6224.844378890473;6264.906026409608;6271.27880293763;6441.838954026187;6509.218893732783;15611.531654050397;15611.829112816356;15631.921840390953;15672.138032408098;15672.608184795243;7548.509346892434;7711.575863327571;7729.458937874408;7736.458447908705;7740.148583655793;6831.521793474509;6932.064171336906;7134.714611415589;7136.0723105995885;7140.357367836919;5001.774920128603;5110.437851836295;5161.974190129101;5169.81905633301;5231.764570594332;1518.3926429428318;1519.4302136698739;1536.3624096000258;1542.2627824492868;1547.5744170353018;826.5454000299811;827.6275675945849;848.291676450171;860.8579584368634;880.823835232956;6494.318096599884;6763.7632682855865;6861.313868613099;6917.342852429011;7011.404030657349;684.090160087083;691.930806919304;709.9112610923635;720.8017795106283;729.9525530840478;4216.093539888939;4556.7721613919175;4802.73584952831;4811.639127065484;4822.590079843182;18215.271389144287;18837;18863.90568047151;18974.241030358906;18975;18340.908295458437;18926.3375781847;18946;19146.329878454464;19162.904185478983;10137.341072830242;10230.23273254509;10308.484575771181;10335.896641033643;10391.116755075904;7175.784726458214;7641.7707468776025;7727.613619319124;7964.730051935171;7985.680572777024;15755;16312;16515.256813443662;16575.91712041432;16634.25145868463;16059.678806424039;16447.753283700516;16586.419475318307;16610.58473538171;16688;12929.482820687443;13143.211407315592;13193.20840749558;13235.40440680191;13253.07228493996;12995.675108122372;13198.472061117447;13201.669958251121;13206.141600795918;13215.735685286048;10167.51808351153;10589.52347144365;10624.946875265574;10675.359478431319;10942.343459392143;9650.806983860142;10062.547185376528;10169.389836609827;10293.588256469657;10331;9068.863967040368;9127.22418594427;9151.17639412456;9200.447973121634;9203.125703058198;6181.474574661024;6231.22109736283;6392.296847346695;6526.869462610816;6597.109390232257;6279.717412716347;6496.642684652389;6618.900716489353;6665.633390163587;6691.832704182434 07:30:29 INFO - PID 16098 | |4;prototype.html;488.95859785395976;501.6780782637751;528.748844298958;529.3568314497884;532.4967905329464;6198;6200.53495987801;6220.858466474436;6264;6270.749170033193;5842.941570584293;6060.515158787298;6071.757129714806;6090.299615544213;6149.13912052313;4819.855404337858;4895;4950.108980383529;4952.678075925062;4958.578520825716;4876.2929375240565;4910.1898186799135;4914.70511769295;4915.754212289371;4960.280759289901;4704.717716936994;4807.927881081787;4832.830850920219;4840.588549973257;4883.780229889646;4740.620750339965;4786.21027530457;4797.112534181188;4850.199717046683;4939.333190019337;4376.781160941972;4412.955870441287;4414.735115893057;4443.955560444387;4444.755538445384;5967.806438712231;6021;6031.638101713867;6032.577719559633;6038.758449662008;5610.214569960209;5643.746031428595;5645.745941432645;5647.29408823897;5696.401877802854;6429.763129764121;6470;6471.1587493626;6480.15757951468;6510.381513756186;4798.328234047236;4811.518848115195;4823.855284341475;4835.153848076572;4862.270659401118;6771.932280677229;6877.415419689332;6917.204521480095;6923.411510021705;6931.1682598088555;4848.200046992207;4850.151223535867;4874.926876096862;4951.059298733301;4961.305417241589;4952.158133117378;4964.801407943642;4987.600991920638;5036.370453693288;5037.647364684437;5634.267545219095;5645.2096706461125;5686.289213848269;5688.459596338341;5698.857528561819;6410.391012853772;6482;6492.7084426192505;6576.177977752781;6594.934050659528;1650.867930565552;1701.7362308842148;1706.7610534525177;1729.2793670297883;1744.6597913407018;1144.9255798373079;1202.7955247607927;1212.7150119721857;1222.3888055972013;1231.4950870143198;5652.293463317085;5704.8859022819315;5708.800191993343;5724.656520608777;5760.884782304331;6473.935260647333;6502.423666983141;6579.605223686594;6611.272759996396;6669.7332106715185;5706.914396284059;5731.53543976086;5765.203876120078;5783.94216057834;5805.445603934256;3564.3762341590636;3564.8395822188063;3571.4464258040043;3575.7496975211484;3585.8027808470783;6464.5798023128345;6471.6764161791725;6491.285958544557;6510.381513756186;6539;6720.630365329954;6857.7256909723055;6896.310368963063;6916.827079323057;6947.5831450113155;6425.871482570323;6481.935180648133;6499.2850786413455;6510.414062734377;6544.9672751635935;6643.335666433318;6665.866682666223;6702.061711360316;6723.4285085766305;6763.357481039294;6587.736490540325;6616.66916654175;6617.371349721769;6653.700583473852;6659.234188068241;6494.7077381517;6507.511936604868;6512.990486474604;6529.95520716683;6567.802965911031;4894.241392584155;4895.828645997373;4896.2410826321975;4907.411110666742;4909.797099710594;6769.003144072412;6769.864602708017;6808.5914845109455;6832.692528836314;6839.452843772586;6346.90479642815;6475.352464753487;6498.480121590167;6510.641914694548;6574.506911981525;6432.453241474527;6441.613503189824;6492.188476440445;6533.248676402274;6537.477001839747;6163.537734669828;6207.472364849038;6345.96827015862;6363.331850155667;6432;6447.451966582893;6505.772297969548;6551.21385433751;6575.901361479678;6599.152237373543;6078.54410919174;6255;6342.2389313280855;6359.8092057238355;6376.234851817811 07:30:29 INFO - PID 16098 | |5;yui.html;904.1500989070274;910.808730166665;929.916307532322;929.986050209247;931.9021502742216;15790.4473343433;16191.595210119753;16206.432774852883;16241.106739129344;20251.696224556643;20166.395008149742;20893.582128357422;20951.47621309468;21080.367588972356;21099.894500527513;1028.8456731490298;1043.582566973213;1053.8893416191304;1060.9681709548727;1061.7080302916681;1005.7636455432968;1006.1598565198068;1006.3106771861262;1008.2236677758121;1051.9421431821247;992.7865508915581;1041.281515754131;1083.9078678312314;1113.048343666166;1120.1207052463817;1029.783745413464;1032.5766435761338;1033.1270076785179;1033.4264483211853;1076.4779082145153;788.2629741192004;790.517784151667;796.7171654062782;798.2137594469443;798.4889670610813;18215.0892455378;19270.132844022053;19798.901005495023;19968.600627987504;19974.300899468595;18485.44543663692;18548.44354669362;18665.88004719721;18886.43340699781;18908;15384.538463846102;15861.444849430214;15872.12703301318;15898;15973.281202345923;3423.5207071010077;3515.033365824393;3547.4324108142832;3558.6085530591613;3559.430491121434;108403;111986.44006779995;113263.43368283188;116645.99999999916;116758;15485.84514154844;15743.13412762309;15839;15859.731221502252;15909.52271431859;28170.15489535317;29559.85220073886;29810.850945745566;29842.70157298399;29888.701112989027;7724.420668449777;8020.959895200486;8103.0681471631515;8170.223828736261;8446.83106337882;4050.858219962287;4171.249175148441;4187.539370669224;4191.392248124005;4192.413062171298;4922.630802689812;4979.850604481871;5083.313752643419;5086.593072554187;5092.465291144451;987.0228473810876;995.6807230419723;997.9151772099308;999.1107913956585;999.5102399824034;651.9869602607921;657.9309172536911;682.5665702279089;683.8666460040344;692.7679227458858;11018.228723989405;11277.492512836941;12373.13388062845;13521.729565408637;13916.58250252494;653.8136631060125;675.6689222281145;680.452235950065;686.1046334533382;694.0456871801273;5336.386315573681;5400;5513.669179849222;5677.120046392815;5709.22925405065;92770;95752.52123739476;96477.5176124115;99143;100566.49716751369;81981.74603566853;84396;84917;86827.56586217029;88341.55829220942;74156;74472;77192.45615087666;77614.73770077954;78981.60509197302;11477.253978491372;11960.58137965167;12145.14983951115;12314.076444266537;12933.482660693844;33186.17034574155;35408.82295588506;35541;35910.64089359073;36922.63077369192;60074.699626501584;61185.38814611797;65595.34404655892;65960.34039659542;67039.66480167568;17883.821161788215;18536.351227706964;18725.25098996025;18730.625387492448;18770.249190032246;19201.807981920003;20447.59104817925;20878.68681969802;21034.68447973251;21512.354629361143;17821.821781782015;18158.182881770615;18371.724424133383;18722;18905.810941890406;17859.821401785815;17909.820901790816;17988.730169047212;18478.538036549195;19031.90484047571;16607.50177494677;16815.663686726442;16835.24241409164;16859.325626974784;17010.914945425193;12438.564650237198;12701.491940322283;13050.216986980811;13204;13320.400581974029;12715.682107947376;13627.454901803816;14029.719405611626;14236.145831250158;14247.101748657058 07:30:29 INFO - PID 16098 | __end_tp_report 07:30:29 INFO - PID 16098 | __start_cc_report 07:30:29 INFO - PID 16098 | _x_x_mozilla_cycle_collect,386 07:30:29 INFO - PID 16098 | __end_cc_report 07:30:29 INFO - PID 16098 | __startTimestamp1501252229078__endTimestamp 07:30:29 INFO - PID 16098 | 07:30:29 INFO - PID 16098 | ------- Summary: start ------- 07:30:29 INFO - PID 16098 | Number of tests: 6 07:30:29 INFO - PID 16098 | 07:30:29 INFO - PID 16098 | [#0] dojo.html Cycles:165 Average:6202.48 Median:6471.61 stddev:741.18 (11.5%) stddev-sans-first:703.09 07:30:29 INFO - PID 16098 | Values: 3127.1 3148.5 3182.6 3182.7 3221.8 6122.0 6183.4 6195.1 6284.7 6302.7 6226.3 6281.2 6285.6 6327.9 6372.7 5659.9 5694.1 5783.7 5819.2 5834.2 5604.7 5735.9 5736.7 5754.4 5760.3 5663.6 5675.4 5680.3 5683.2 5800.5 5658.0 5727.3 5727.5 5742.5 5743.7 5632.6 5637.6 5739.5 5777.9 5779.1 6068.7 6158.8 6206.4 6233.3 6257.1 6741.0 6744.6 6762.0 6764.4 6811.3 6757.2 6796.9 6864.6 6865.3 6902.3 6230.3 6238.7 6334.6 6337.4 6362.6 6895.2 6912.1 6984.8 6994.1 7044.1 6594.1 6600.9 6610.0 6613.5 6616.5 6642.9 6697.2 6726.1 6729.7 6739.5 6636.3 6667.5 6694.2 6708.1 6713.5 6043.8 6711.4 6754.7 6756.8 6858.9 5481.5 5482.1 5534.2 5559.8 5575.7 4638.5 4667.8 4699.6 4714.2 4738.6 5830.2 5869.6 5909.3 5911.1 5919.1 6825.3 6837.4 6842.7 6872.7 6874.8 5832.4 5835.0 5874.3 5904.2 5913.8 5835.1 5942.9 6007.0 6021.1 6023.7 6456.4 6543.6 6580.7 6588.1 6591.6 6762.4 6820.8 6843.3 6845.0 6848.1 6190.8 6529.7 6564.9 6591.7 6603.4 6586.3 6589.6 6675.6 6690.0 6709.1 6663.5 6767.5 6782.6 6859.0 6873.7 6577.2 6587.6 6604.7 6605.2 6610.0 6575.7 6576.6 6577.1 6604.5 6613.9 5464.5 6132.7 6563.7 6571.6 6573.0 6635.1 6640.2 6657.1 6667.0 6744.6 6438.9 6443.3 6471.6 6517.6 6545.9 07:30:29 INFO - PID 16098 | 07:30:29 INFO - PID 16098 | [#1] ext.html Cycles:180 Average:38399.29 Median:36676.86 stddev:54568.45 (148.8%) stddev-sans-first:54648.15 07:30:29 INFO - PID 16098 | Values: 712.0 746.1 767.2 779.3 784.1 39597.2 40482.4 40528.4 40603.8 40671.6 36483.8 36603.3 36750.4 36901.3 36973.6 6617.1 6806.3 6813.1 6822.4 6833.0 6740.3 6764.6 6781.5 6795.4 6818.5 7593.4 7687.3 7721.1 7741.3 7755.0 7675.5 7731.3 7741.5 7741.7 7741.9 7370.3 7549.4 7590.4 7603.0 7604.2 38307.8 38403.6 38807.0 38825.8 38867.8 3469.0 3519.7 3523.6 3533.8 3536.7 31086.7 31243.8 31367.5 31443.2 31468.4 4725.5 4754.4 4766.5 4796.3 4812.1 186055.0 188836.0 190128.0 190445.0 198363.0 47915.3 47994.3 48037.3 48041.0 48052.5 50953.5 51156.7 51240.7 51313.5 51450.0 16417.3 16541.4 16552.8 16568.7 16610.5 13518.7 13613.2 13627.5 13645.7 13661.9 14072.7 14283.9 14340.3 14412.8 14611.4 5691.9 5704.2 5728.5 5754.4 5854.4 977.3 986.3 998.7 1005.3 1018.9 21959.8 22256.8 22284.4 23166.3 23297.3 1061.7 1092.3 1110.6 1125.6 1127.2 11261.7 12087.8 12126.0 12192.9 15903.6 230121.0 230552.0 230845.0 230986.0 231104.0 55470.2 55714.4 56284.7 56330.4 56573.7 1459.1 1465.2 1469.6 1478.9 1479.9 25677.9 26755.7 26950.9 27442.6 27985.2 50265.2 51117.5 51230.2 51276.5 51278.7 206967.0 207716.0 207759.0 207770.0 208184.0 34854.7 35177.0 36819.0 37069.8 37082.4 43732.3 45347.8 46027.5 46060.8 46068.1 30281.2 30513.7 32134.7 32211.5 32219.7 30383.0 31374.5 31890.3 31951.8 32018.0 36851.4 37626.8 37665.2 37772.8 37780.2 30672.5 32191.0 32345.5 32454.2 32835.0 23603.2 23648.6 23832.5 24021.9 24125.2 07:30:29 INFO - PID 16098 | 07:30:29 INFO - PID 16098 | [#2] jquery.html Cycles:180 Average:36458.49 Median:12295.89 stddev:90127.92 (733.0%) stddev-sans-first:90340.44 07:30:29 INFO - PID 16098 | Values: 558.5 563.1 578.5 591.2 592.3 21459.1 22114.0 22116.7 22241.1 22318.4 24655.1 24684.6 24749.3 24765.4 24799.9 6532.9 6618.5 6673.6 6703.3 6704.2 5871.3 6401.7 6440.9 6505.7 6576.7 6393.9 6398.6 6402.8 6409.6 6410.5 6348.7 6386.9 6396.4 6399.8 6400.9 5894.4 5936.1 5949.2 5970.4 5970.8 29266.1 30223.2 30592.2 30664.4 30948.0 1892.9 1955.1 1955.3 1956.1 1956.1 18775.4 19227.6 19244.8 19279.5 19331.5 2273.3 2301.8 2304.1 2314.8 2399.8 133076.0 135394.0 136020.3 136074.0 138770.0 13994.5 14631.3 14855.2 15023.4 15079.3 23075.8 23543.8 23556.6 23622.8 23889.2 10315.4 10381.6 10516.0 10545.7 10667.3 9883.0 9993.2 10002.9 10051.1 10105.8 9157.9 9193.4 9324.7 9532.6 9563.4 2582.2 2592.3 2626.9 2697.1 2767.8 925.9 927.9 950.6 968.2 995.9 19317.8 19502.5 19944.0 22932.9 24358.4 1001.9 1005.5 1011.2 1034.7 1062.7 10835.5 11153.2 11335.7 11355.3 11402.9 509782.0 543475.0 544486.0 544831.0 547992.0 113216.0 118276.0 120849.0 127485.0 128428.0 1187.4 1215.1 1222.1 1258.2 1271.8 18646.9 20033.5 20795.3 21446.1 21868.2 56560.4 58350.1 59849.7 61209.1 62185.4 69135.3 70501.3 71217.6 71221.6 71501.6 14710.8 15048.6 15166.8 15266.3 15267.5 23571.4 23629.8 23866.0 23936.6 24154.0 16895.0 17177.9 18576.0 18726.4 18839.5 15478.6 16316.8 16480.5 16583.9 16701.4 18822.2 19334.6 19718.3 19813.1 19895.6 14893.9 15027.6 15096.8 15135.7 15174.0 18697.0 18700.3 18841.8 18998.6 19012.4 07:30:29 INFO - PID 16098 | 07:30:29 INFO - PID 16098 | [#3] mootools.html Cycles:180 Average:8582.51 Median:2638.61 stddev:5896.36 (223.5%) stddev-sans-first:5887.89 07:30:29 INFO - PID 16098 | Values: 1353.9 1398.3 1409.4 1413.9 1420.3 13608.5 13705.7 14124.4 14131.9 14139.3 14450.8 14760.3 14778.1 14783.6 14792.0 2673.5 2692.5 2708.5 2712.0 2712.1 2662.7 2684.8 2685.7 2686.5 2686.6 2605.4 2637.7 2638.9 2646.4 2672.8 2620.1 2638.4 2644.9 2647.6 2649.3 2611.6 2621.7 2624.6 2668.9 2678.1 13585.3 13889.2 13933.2 13948.1 13957.7 7627.1 7806.0 7856.9 7889.8 7909.0 4786.4 5013.3 5085.8 5095.5 5116.2 1815.3 1835.9 1838.0 1917.1 1919.7 22858.8 23096.5 23107.7 23132.3 23135.0 6224.8 6264.9 6271.3 6441.8 6509.2 15611.5 15611.8 15631.9 15672.1 15672.6 7548.5 7711.6 7729.5 7736.5 7740.1 6831.5 6932.1 7134.7 7136.1 7140.4 5001.8 5110.4 5162.0 5169.8 5231.8 1518.4 1519.4 1536.4 1542.3 1547.6 826.5 827.6 848.3 860.9 880.8 6494.3 6763.8 6861.3 6917.3 7011.4 684.1 691.9 709.9 720.8 730.0 4216.1 4556.8 4802.7 4811.6 4822.6 18215.3 18837.0 18863.9 18974.2 18975.0 18340.9 18926.3 18946.0 19146.3 19162.9 10137.3 10230.2 10308.5 10335.9 10391.1 7175.8 7641.8 7727.6 7964.7 7985.7 15755.0 16312.0 16515.3 16575.9 16634.3 16059.7 16447.8 16586.4 16610.6 16688.0 12929.5 13143.2 13193.2 13235.4 13253.1 12995.7 13198.5 13201.7 13206.1 13215.7 10167.5 10589.5 10624.9 10675.4 10942.3 9650.8 10062.5 10169.4 10293.6 10331.0 9068.9 9127.2 9151.2 9200.4 9203.1 6181.5 6231.2 6392.3 6526.9 6597.1 6279.7 6496.6 6618.9 6665.6 6691.8 07:30:29 INFO - PID 16098 | 07:30:29 INFO - PID 16098 | [#4] prototype.html Cycles:180 Average:5461.60 Median:6049.64 stddev:1545.53 (25.5%) stddev-sans-first:1504.13 07:30:29 INFO - PID 16098 | Values: 489.0 501.7 528.7 529.4 532.5 6198.0 6200.5 6220.9 6264.0 6270.7 5842.9 6060.5 6071.8 6090.3 6149.1 4819.9 4895.0 4950.1 4952.7 4958.6 4876.3 4910.2 4914.7 4915.8 4960.3 4704.7 4807.9 4832.8 4840.6 4883.8 4740.6 4786.2 4797.1 4850.2 4939.3 4376.8 4413.0 4414.7 4444.0 4444.8 5967.8 6021.0 6031.6 6032.6 6038.8 5610.2 5643.7 5645.7 5647.3 5696.4 6429.8 6470.0 6471.2 6480.2 6510.4 4798.3 4811.5 4823.9 4835.2 4862.3 6771.9 6877.4 6917.2 6923.4 6931.2 4848.2 4850.2 4874.9 4951.1 4961.3 4952.2 4964.8 4987.6 5036.4 5037.6 5634.3 5645.2 5686.3 5688.5 5698.9 6410.4 6482.0 6492.7 6576.2 6594.9 1650.9 1701.7 1706.8 1729.3 1744.7 1144.9 1202.8 1212.7 1222.4 1231.5 5652.3 5704.9 5708.8 5724.7 5760.9 6473.9 6502.4 6579.6 6611.3 6669.7 5706.9 5731.5 5765.2 5783.9 5805.4 3564.4 3564.8 3571.4 3575.7 3585.8 6464.6 6471.7 6491.3 6510.4 6539.0 6720.6 6857.7 6896.3 6916.8 6947.6 6425.9 6481.9 6499.3 6510.4 6545.0 6643.3 6665.9 6702.1 6723.4 6763.4 6587.7 6616.7 6617.4 6653.7 6659.2 6494.7 6507.5 6513.0 6530.0 6567.8 4894.2 4895.8 4896.2 4907.4 4909.8 6769.0 6769.9 6808.6 6832.7 6839.5 6346.9 6475.4 6498.5 6510.6 6574.5 6432.5 6441.6 6492.2 6533.2 6537.5 6163.5 6207.5 6346.0 6363.3 6432.0 6447.5 6505.8 6551.2 6575.9 6599.2 6078.5 6255.0 6342.2 6359.8 6376.2 07:30:29 INFO - PID 16098 | 07:30:29 INFO - PID 16098 | [#5] yui.html Cycles:180 Average:21859.10 Median:20209.05 stddev:28424.93 (140.7%) stddev-sans-first:28461.12 07:30:29 INFO - PID 16098 | Values: 904.2 910.8 929.9 930.0 931.9 15790.4 16191.6 16206.4 16241.1 20251.7 20166.4 20893.6 20951.5 21080.4 21099.9 1028.8 1043.6 1053.9 1061.0 1061.7 1005.8 1006.2 1006.3 1008.2 1051.9 992.8 1041.3 1083.9 1113.0 1120.1 1029.8 1032.6 1033.1 1033.4 1076.5 788.3 790.5 796.7 798.2 798.5 18215.1 19270.1 19798.9 19968.6 19974.3 18485.4 18548.4 18665.9 18886.4 18908.0 15384.5 15861.4 15872.1 15898.0 15973.3 3423.5 3515.0 3547.4 3558.6 3559.4 108403.0 111986.4 113263.4 116646.0 116758.0 15485.8 15743.1 15839.0 15859.7 15909.5 28170.2 29559.9 29810.9 29842.7 29888.7 7724.4 8021.0 8103.1 8170.2 8446.8 4050.9 4171.2 4187.5 4191.4 4192.4 4922.6 4979.9 5083.3 5086.6 5092.5 987.0 995.7 997.9 999.1 999.5 652.0 657.9 682.6 683.9 692.8 11018.2 11277.5 12373.1 13521.7 13916.6 653.8 675.7 680.5 686.1 694.0 5336.4 5400.0 5513.7 5677.1 5709.2 92770.0 95752.5 96477.5 99143.0 100566.5 81981.7 84396.0 84917.0 86827.6 88341.6 74156.0 74472.0 77192.5 77614.7 78981.6 11477.3 11960.6 12145.1 12314.1 12933.5 33186.2 35408.8 35541.0 35910.6 36922.6 60074.7 61185.4 65595.3 65960.3 67039.7 17883.8 18536.4 18725.3 18730.6 18770.2 19201.8 20447.6 20878.7 21034.7 21512.4 17821.8 18158.2 18371.7 18722.0 18905.8 17859.8 17909.8 17988.7 18478.5 19031.9 16607.5 16815.7 16835.2 16859.3 17010.9 12438.6 12701.5 13050.2 13204.0 13320.4 12715.7 13627.5 14029.7 14236.1 14247.1 07:30:29 INFO - PID 16098 | -------- Summary: end -------- 07:30:29 INFO - PID 16098 | 07:30:29 INFO - TEST-INFO | 16098: exit 0 07:30:29 INFO - TEST-OK | dromaeo_css | took 1113016ms 07:30:29 INFO - TEST-START | kraken 07:30:29 INFO - Initialising browser for kraken test... 07:30:29 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:54488/getInfo.html -profile /tmp/tmpRWEvcY/profile 07:30:29 INFO - TEST-INFO | started process 16471 (/builds/slave/test/build/application/firefox/firefox http://localhost:54488/getInfo.html) 07:30:35 INFO - TEST-INFO | 16471: exit 0 07:30:35 INFO - Browser initialized. 07:30:35 INFO - Running cycle 1/1 for kraken test... 07:30:35 INFO - TEST-INFO | started process 16699 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpRWEvcY/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 07:30:36 INFO - PID 16699 | 07:30:36 INFO - PID 16699 | (/builds/slave/test/build/application/firefox/firefox:16754): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 07:30:36 INFO - PID 16699 | 07:30:47 INFO - PID 16699 | RSS: Main: 173596672 07:30:47 INFO - PID 16699 | 07:30:49 INFO - PID 16699 | Cycle 1(1): loaded http://localhost:54488/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:54488/tests/kraken/driver.html?testName=audio-beat-detection) 07:30:50 INFO - PID 16699 | RSS: Main: 178380800 07:30:50 INFO - PID 16699 | 07:30:53 INFO - PID 16699 | Cycle 1(1): loaded http://localhost:54488/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:54488/tests/kraken/driver.html?testName=audio-dft) 07:30:54 INFO - PID 16699 | RSS: Main: 178896896 07:30:54 INFO - PID 16699 | 07:30:57 INFO - PID 16699 | Cycle 1(1): loaded http://localhost:54488/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:54488/tests/kraken/driver.html?testName=audio-fft) 07:30:57 INFO - PID 16699 | RSS: Main: 174092288 07:30:57 INFO - PID 16699 | 07:31:00 INFO - PID 16699 | Cycle 1(1): loaded http://localhost:54488/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:54488/tests/kraken/driver.html?testName=audio-oscillator) 07:31:00 INFO - PID 16699 | RSS: Main: 174772224 07:31:00 INFO - PID 16699 | 07:31:02 INFO - PID 16699 | Cycle 1(1): loaded http://localhost:54488/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:54488/tests/kraken/driver.html?testName=imaging-gaussian-blur) 07:31:03 INFO - PID 16699 | RSS: Main: 175017984 07:31:03 INFO - PID 16699 | 07:31:06 INFO - PID 16699 | Cycle 1(1): loaded http://localhost:54488/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:54488/tests/kraken/driver.html?testName=imaging-darkroom) 07:31:07 INFO - PID 16699 | RSS: Main: 177532928 07:31:07 INFO - PID 16699 | 07:31:10 INFO - PID 16699 | Cycle 1(1): loaded http://localhost:54488/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:54488/tests/kraken/driver.html?testName=imaging-desaturate) 07:31:11 INFO - PID 16699 | RSS: Main: 177090560 07:31:11 INFO - PID 16699 | 07:31:14 INFO - PID 16699 | Cycle 1(1): loaded http://localhost:54488/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:54488/tests/kraken/driver.html?testName=json-parse-financial) 07:31:15 INFO - PID 16699 | RSS: Main: 177696768 07:31:15 INFO - PID 16699 | 07:31:16 INFO - PID 16699 | Cycle 1(1): loaded http://localhost:54488/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:54488/tests/kraken/driver.html?testName=json-stringify-tinderbox) 07:31:17 INFO - PID 16699 | RSS: Main: 177483776 07:31:17 INFO - PID 16699 | 07:31:19 INFO - PID 16699 | Cycle 1(1): loaded http://localhost:54488/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:54488/tests/kraken/driver.html?testName=stanford-crypto-aes) 07:31:19 INFO - PID 16699 | RSS: Main: 174759936 07:31:19 INFO - PID 16699 | 07:31:22 INFO - PID 16699 | Cycle 1(1): loaded http://localhost:54488/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:54488/tests/kraken/driver.html?testName=stanford-crypto-ccm) 07:31:22 INFO - PID 16699 | RSS: Main: 175157248 07:31:22 INFO - PID 16699 | 07:31:25 INFO - PID 16699 | Cycle 1(1): loaded http://localhost:54488/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:54488/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 07:31:26 INFO - PID 16699 | RSS: Main: 175132672 07:31:26 INFO - PID 16699 | 07:31:29 INFO - PID 16699 | Cycle 1(1): loaded http://localhost:54488/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:54488/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 07:31:30 INFO - PID 16699 | RSS: Main: 175149056 07:31:30 INFO - PID 16699 | 07:31:32 INFO - PID 16699 | Cycle 1(1): loaded http://localhost:54488/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:54488/tests/kraken/driver.html?testName=ai-astar) 07:31:32 INFO - PID 16699 | RSS: Main: 176365568 07:31:32 INFO - PID 16699 | 07:31:32 INFO - PID 16699 | __start_tp_report 07:31:32 INFO - PID 16699 | _x_x_mozilla_page_load 07:31:32 INFO - PID 16699 | _x_x_mozilla_page_load_details 07:31:32 INFO - PID 16699 | |i|pagename|runs| 07:31:32 INFO - PID 16699 | |0;ai-astar;100;108;105;108;109;113;108;103;109;110 07:31:32 INFO - PID 16699 | |1;audio-beat-detection;142;118;158;183;119;152;151;125;182;119 07:31:32 INFO - PID 16699 | |2;audio-dft;164;165;163;162;170;170;165;173;164;164 07:31:32 INFO - PID 16699 | |3;audio-fft;77;75;77;171;88;77;81;88;97;82 07:31:32 INFO - PID 16699 | |4;audio-oscillator;80;79;133;87;79;78;128;79;79;142 07:31:32 INFO - PID 16699 | |5;imaging-gaussian-blur;113;114;114;114;114;114;115;114;117;114 07:31:32 INFO - PID 16699 | |6;imaging-darkroom;150;163;151;150;150;151;152;151;151;152 07:31:32 INFO - PID 16699 | |7;imaging-desaturate;94;93;95;94;101;95;96;94;94;94 07:31:32 INFO - PID 16699 | |8;json-parse-financial;57;58;58;59;58;58;63;56;57;57 07:31:32 INFO - PID 16699 | |9;json-stringify-tinderbox;55;54;55;55;54;54;65;54;55;55 07:31:32 INFO - PID 16699 | |10;stanford-crypto-aes;85;79;80;82;87;84;83;82;82;81 07:31:32 INFO - PID 16699 | |11;stanford-crypto-ccm;129;139;129;136;130;140;133;139;129;163 07:31:32 INFO - PID 16699 | |12;stanford-crypto-pbkdf2;177;188;188;199;187;181;188;181;178;189 07:31:32 INFO - PID 16699 | |13;stanford-crypto-sha256-iterative;70;74;77;73;69;74;75;78;73;68 07:31:32 INFO - PID 16699 | __end_tp_report 07:31:32 INFO - PID 16699 | __start_cc_report 07:31:32 INFO - PID 16699 | _x_x_mozilla_cycle_collect,935 07:31:32 INFO - PID 16699 | __end_cc_report 07:31:32 INFO - PID 16699 | __startTimestamp1501252292266__endTimestamp 07:31:32 INFO - PID 16699 | 07:31:32 INFO - PID 16699 | ------- Summary: start ------- 07:31:32 INFO - PID 16699 | Number of tests: 14 07:31:32 INFO - PID 16699 | 07:31:32 INFO - PID 16699 | [#0] ai-astar Cycles:10 Average:107.30 Median:108.50 stddev:3.71 (3.4%) stddev-sans-first:2.85 07:31:32 INFO - PID 16699 | Values: 100.0 108.0 105.0 108.0 109.0 113.0 108.0 103.0 109.0 110.0 07:31:32 INFO - PID 16699 | 07:31:32 INFO - PID 16699 | [#1] audio-beat-detection Cycles:10 Average:144.90 Median:151.50 stddev:24.85 (16.4%) stddev-sans-first:26.33 07:31:32 INFO - PID 16699 | Values: 142.0 118.0 158.0 183.0 119.0 152.0 151.0 125.0 182.0 119.0 07:31:32 INFO - PID 16699 | 07:31:32 INFO - PID 16699 | [#2] audio-dft Cycles:10 Average:166.00 Median:165.00 stddev:3.65 (2.2%) stddev-sans-first:3.80 07:31:32 INFO - PID 16699 | Values: 164.0 165.0 163.0 162.0 170.0 170.0 165.0 173.0 164.0 164.0 07:31:32 INFO - PID 16699 | 07:31:32 INFO - PID 16699 | [#3] audio-fft Cycles:10 Average:91.30 Median:81.50 stddev:28.83 (35.4%) stddev-sans-first:30.11 07:31:32 INFO - PID 16699 | Values: 77.0 75.0 77.0 171.0 88.0 77.0 81.0 88.0 97.0 82.0 07:31:32 INFO - PID 16699 | 07:31:32 INFO - PID 16699 | [#4] audio-oscillator Cycles:10 Average:96.40 Median:79.00 stddev:26.51 (33.6%) stddev-sans-first:27.44 07:31:32 INFO - PID 16699 | Values: 80.0 79.0 133.0 87.0 79.0 78.0 128.0 79.0 79.0 142.0 07:31:32 INFO - PID 16699 | 07:31:32 INFO - PID 16699 | [#5] imaging-gaussian-blur Cycles:10 Average:114.30 Median:114.00 stddev:1.06 (0.9%) stddev-sans-first:1.01 07:31:32 INFO - PID 16699 | Values: 113.0 114.0 114.0 114.0 114.0 114.0 115.0 114.0 117.0 114.0 07:31:32 INFO - PID 16699 | 07:31:32 INFO - PID 16699 | [#6] imaging-darkroom Cycles:10 Average:152.10 Median:151.00 stddev:3.90 (2.6%) stddev-sans-first:4.06 07:31:32 INFO - PID 16699 | Values: 150.0 163.0 151.0 150.0 150.0 151.0 152.0 151.0 151.0 152.0 07:31:32 INFO - PID 16699 | 07:31:32 INFO - PID 16699 | [#7] imaging-desaturate Cycles:10 Average:95.00 Median:94.00 stddev:2.26 (2.4%) stddev-sans-first:2.37 07:31:32 INFO - PID 16699 | Values: 94.0 93.0 95.0 94.0 101.0 95.0 96.0 94.0 94.0 94.0 07:31:32 INFO - PID 16699 | 07:31:32 INFO - PID 16699 | [#8] json-parse-financial Cycles:10 Average:58.10 Median:58.00 stddev:1.91 (3.3%) stddev-sans-first:1.99 07:31:32 INFO - PID 16699 | Values: 57.0 58.0 58.0 59.0 58.0 58.0 63.0 56.0 57.0 57.0 07:31:32 INFO - PID 16699 | 07:31:32 INFO - PID 16699 | [#9] json-stringify-tinderbox Cycles:10 Average:55.60 Median:55.00 stddev:3.34 (6.1%) stddev-sans-first:3.54 07:31:32 INFO - PID 16699 | Values: 55.0 54.0 55.0 55.0 54.0 54.0 65.0 54.0 55.0 55.0 07:31:32 INFO - PID 16699 | 07:31:32 INFO - PID 16699 | [#10] stanford-crypto-aes Cycles:10 Average:82.50 Median:82.50 stddev:2.37 (2.9%) stddev-sans-first:2.33 07:31:32 INFO - PID 16699 | Values: 85.0 79.0 80.0 82.0 87.0 84.0 83.0 82.0 82.0 81.0 07:31:32 INFO - PID 16699 | 07:31:32 INFO - PID 16699 | [#11] stanford-crypto-ccm Cycles:10 Average:136.70 Median:137.50 stddev:10.27 (7.5%) stddev-sans-first:10.51 07:31:32 INFO - PID 16699 | Values: 129.0 139.0 129.0 136.0 130.0 140.0 133.0 139.0 129.0 163.0 07:31:32 INFO - PID 16699 | 07:31:32 INFO - PID 16699 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:185.60 Median:188.00 stddev:6.54 (3.5%) stddev-sans-first:6.15 07:31:32 INFO - PID 16699 | Values: 177.0 188.0 188.0 199.0 187.0 181.0 188.0 181.0 178.0 189.0 07:31:32 INFO - PID 16699 | 07:31:32 INFO - PID 16699 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.10 Median:74.00 stddev:3.28 (4.4%) stddev-sans-first:3.28 07:31:32 INFO - PID 16699 | Values: 70.0 74.0 77.0 73.0 69.0 74.0 75.0 78.0 73.0 68.0 07:31:32 INFO - PID 16699 | -------- Summary: end -------- 07:31:32 INFO - PID 16699 | 07:31:32 INFO - PID 16699 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 07:31:32 INFO - TEST-INFO | 16699: exit 0 07:31:33 INFO - TEST-OK | kraken | took 63265ms 07:31:33 INFO - SUITE-END | took 1176s 07:31:33 INFO - Completed test suite (00:19:36) 07:31:33 INFO - javascript benchmark 07:31:33 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8409.669435926131, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3127.1087739994105, 3148.4962406015043, 3182.649908510064, 3182.6976437238454, 3221.806691598504, 6121.969390153048, 6183.381661833814, 6195.101710252016, 6284.7486100555925, 6302.684865756705, 6226.3462336454695, 6281.214848143982, 6285.6228626282455, 6327.9367206327925, 6372.681365931697, 5659.943400566003, 5694.088945768678, 5783.6529808211435, 5819.156222347757, 5834.241548598698, 5604.691741954191, 5735.885282294352, 5736.684482353469, 5754.424557544254, 5760.279965004374, 5663.631863928831, 5675.3757086720425, 5680.289963754531, 5683.204351390808, 5800.53595712342, 5658.0381335173115, 5727.341355744101, 5727.541796656257, 5742.454466825645, 5743.655380677173, 5632.577556683265, 5637.577181711388, 5739.483446489836, 5777.942220577783, 5779.104238842986, 6068.696565171768, 6158.84602884927, 6206.441420272197, 6233.345498722614, 6257.092721555349, 6740.966295168541, 6744.595324280559, 6761.966190169067, 6764.391204791593, 6811.25076241613, 6757.189137303555, 6796.898046529306, 6864.5881247124835, 6865.347791959756, 6902.344277293649, 6230.314665386803, 6238.656873871935, 6334.619922804646, 6337.429631333202, 6362.5546211765195, 6895.172579290517, 6912.066870972455, 6984.825379365455, 6994.125734283215, 7044.119485064367, 6594.142761440982, 6600.900986485207, 6609.966950165314, 6613.5370524062855, 6616.503762217853, 6642.86714265712, 6697.162854643169, 6726.091977583062, 6729.6971636276485, 6739.528233023641, 6636.336366363325, 6667.533272670867, 6694.196696396463, 6708.060871477997, 6713.530052896251, 6043.8186854394435, 6711.395974362331, 6754.729810807611, 6756.797296081126, 6858.931410685929, 5481.534069604048, 5482.068048431776, 5534.169874518854, 5559.833205003856, 5575.665460072328, 4638.536146385402, 4667.789949452283, 4699.600533954652, 4714.198586240347, 4738.597219236403, 5830.154627578977, 5869.589128761031, 5909.349971503131, 5911.142884281755, 5919.112133180058, 6825.283345248777, 6837.418819400406, 6842.726290948306, 6872.656367181621, 6874.828129296708, 5832.358440571534, 5835.013035143127, 5874.265716785402, 5904.1734157218025, 5913.793017244376, 5835.095560188178, 5942.8811423771285, 6007.0, 6021.0968354746265, 6023.66869822164, 6456.386643268882, 6543.574667646683, 6580.670966451658, 6588.110605068255, 6591.604503729792, 6762.357576030269, 6820.761273355409, 6843.349881761224, 6844.965775171092, 6848.075509806312, 6190.7523699051535, 6529.738810447719, 6564.868702626016, 6591.670416479061, 6603.372679595431, 6586.341365863375, 6589.571677840925, 6675.599464032173, 6689.966550167413, 6709.060731497497, 6663.5002374821115, 6767.526273160832, 6782.559133656297, 6859.039734437282, 6873.690683919136, 6577.177852768404, 6587.63767992765, 6604.7027883746305, 6605.207375115017, 6609.966950165219, 6575.736970521126, 6576.605403675795, 6577.079208910852, 6604.504662150454, 6613.933860661332, 5464.480874316933, 6132.693365331805, 6563.671816409256, 6571.605703657796, 6573.04690819838, 6635.1374321339845, 6640.169978752656, 6657.101291325609, 6666.966665166643, 6744.595324280559, 6438.903416448663, 6443.259025212161, 6471.611703297817, 6517.608943463408, 6545.934540654533], "value": 6147.061220754834, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [711.9822004449888, 746.052513308099, 767.1561282589153, 779.3453499060788, 784.051297929505, 39597.2080558389, 40482.39276410856, 40528.392074118914, 40603.796981015126, 40671.59328406715, 36483.81758091206, 36603.26793464129, 36750.448743268804, 36901.26197476049, 36973.630263697356, 6617.139771829668, 6806.319368063178, 6813.080234168398, 6822.385985261326, 6833.0, 6740.258571557125, 6764.627945462998, 6781.491388145884, 6795.422389096932, 6818.454523638097, 7593.354564861993, 7687.308142267195, 7721.112072111723, 7741.303282704555, 7755.0, 7675.539467631932, 7731.265529774662, 7741.5355078695175, 7741.690332386698, 7741.883871741872, 7370.26297370264, 7549.433792465587, 7590.3927685785, 7602.961985190039, 7604.239576042407, 38307.80846095779, 38403.61596384028, 38807.0, 38825.805870970464, 38867.80566097151, 3469.0460123465996, 3519.7184225261917, 3523.577170739502, 3533.770304930197, 3536.734744894143, 31086.689133108604, 31243.84378078095, 31367.529487057713, 31443.213919651964, 31468.37063258722, 4725.527447255281, 4754.381930349033, 4766.475687674353, 4796.280557916306, 4812.061647978646, 186055.0, 188836.0, 190128.0, 190445.0, 198363.0, 47915.281270780964, 47994.28008579874, 48037.279440808416, 48041.03917921622, 48052.5194748055, 50953.490465095616, 51156.744216279425, 51240.74379628078, 51313.48686513087, 51450.0, 16417.343306267856, 16541.421050263183, 16552.75170872438, 16568.6686266274, 16610.50168494947, 13518.662033449242, 13613.183209007491, 13627.454901803816, 13645.658858528615, 13661.863381366258, 14072.71854562903, 14283.857161428461, 14340.282985850667, 14412.846972242196, 14611.415543378145, 5691.897915635988, 5704.201411802351, 5728.455796699307, 5754.388771144087, 5854.385289544622, 977.3354119199013, 986.3194395866828, 998.6554638649654, 1005.2962925951863, 1018.8879223285433, 21959.780402196095, 22256.77743222547, 22284.44288892758, 23166.305010849363, 23297.301080967598, 1061.7153244573997, 1092.2736380307008, 1110.555777688931, 1125.6397952655236, 1127.233481232753, 11261.718457038476, 12087.758244835055, 12126.0, 12192.878071219351, 15903.602409939613, 230121.0, 230552.0, 230845.0, 230986.0, 231104.0, 55470.16794748082, 55714.442855570924, 56284.71857640768, 56330.43669563334, 56573.71713141408, 1459.1026518690928, 1465.1648560320516, 1469.5591322603177, 1478.8669019788272, 1479.8742106920818, 25677.87161064183, 26755.732442676104, 26950.865245673645, 27442.5883611742, 27985.160445186677, 50265.24602130898, 51117.48882511276, 51230.23154652608, 51276.48723512717, 51278.74360628173, 206967.0, 207716.0, 207759.0, 207770.0, 208184.0, 34854.65145348514, 35177.0, 36819.0, 37069.81465092657, 37082.44376334411, 43732.34401484044, 45347.773261134804, 46027.53972460367, 46060.76969615131, 46068.07863842637, 30281.24296892507, 30513.694863051085, 32134.67865321317, 32211.51682724808, 32219.677803221668, 30383.0, 31374.529382059744, 31890.332611749018, 31951.840240798647, 32018.0, 36851.447228292134, 37626.81186594049, 37665.2466950654, 37772.81113594415, 37780.2443951114, 30672.539911900894, 32190.970236098998, 32345.514817278232, 32454.188645284055, 32835.0, 23603.173888913807, 23648.645270321304, 23832.523349533258, 24021.879890600434, 24125.155619553232], "value": 17245.955998563422, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [558.5308340993568, 563.1355868741483, 578.4967078641582, 591.21663795471, 592.2862950145076, 21459.141634334628, 22114.0, 22116.668249976265, 22241.110355585777, 22318.442038949033, 24655.137070202545, 24684.629730554057, 24749.257522274314, 24765.38086547837, 24799.87600061997, 6532.934670653304, 6618.4705223582, 6673.566218195801, 6703.329667033307, 6704.229013663418, 5871.324797648261, 6401.679916004205, 6440.871182576346, 6505.674716264191, 6576.671166441659, 6393.936060639404, 6398.648074355909, 6402.839929001766, 6409.615423074631, 6410.455111315544, 6348.682565871711, 6386.9041964370335, 6396.392342727433, 6399.77600783975, 6400.9039864402075, 5894.3810899855325, 5936.079907614327, 5949.167116603679, 5970.432808883149, 5970.761169553256, 29266.122016339545, 30223.244418889484, 30592.2351941201, 30664.386712265852, 30948.0, 1892.933747318851, 1955.0908827395328, 1955.2667749593902, 1956.0513151121836, 1956.0708663384778, 18775.436736897915, 19227.61544769097, 19244.80755192444, 19279.51801204981, 19331.51671208217, 2273.3407311879537, 2301.838871279011, 2304.0898844956314, 2314.7685231476885, 2399.7600239976036, 133076.0, 135394.0, 136020.31989840086, 136074.0, 138770.0, 13994.510192143227, 14631.341589628491, 14855.182964936816, 15023.399064037316, 15079.321430535652, 23075.7692423077, 23543.764562354158, 23556.64665030026, 23622.7637723624, 23889.163879264142, 10315.432651204255, 10381.636642717469, 10516.0, 10545.683629491128, 10667.306625069501, 9882.950585247028, 9993.200543956465, 10002.899971000343, 10051.145652619609, 10105.848412273821, 9157.908420915839, 9193.356465047382, 9324.720258392259, 9532.571034303473, 9563.426194428357, 2582.1607977407416, 2592.2741632342972, 2626.908058217953, 2697.1234348836706, 2767.8339299642084, 925.8518637018045, 927.888653361601, 950.5817440326234, 968.2447690801187, 995.9352642078242, 19317.8068219316, 19502.5124371889, 19944.0, 22932.885335573217, 24358.39104022378, 1001.9148372388283, 1005.5173516712019, 1011.1506334678905, 1034.6999370182714, 1062.6705721226444, 10835.51240194193, 11153.163512736577, 11335.716607084889, 11355.318680879174, 11402.91672291131, 509782.0, 543475.0, 544486.0, 544831.0, 547992.0, 113216.0, 118276.0, 120849.0, 127485.0, 128428.0, 1187.352892673495, 1215.0765418281992, 1222.0651201830606, 1258.157034786695, 1271.841019872516, 18646.906765466087, 20033.499162521053, 20795.272165474136, 21446.142154314275, 21868.23461178851, 56560.43439565552, 58350.12474812966, 59849.70075149596, 61209.08186377297, 62185.378146219766, 69135.30864691289, 70501.29498704948, 71217.64391178219, 71221.64389178022, 71501.64249178929, 14710.779338310149, 15048.62378440504, 15166.772498412312, 15266.313015914533, 15267.465638702592, 23571.41071473227, 23629.763702362758, 23866.0, 23936.64095038541, 24154.0, 16895.0, 17177.914110429367, 18576.0, 18726.438206853818, 18839.529011774815, 15478.613034674223, 16316.836831631532, 16480.505584832474, 16583.91708041452, 16701.415450459175, 18822.153003115167, 19334.61330773405, 19718.309859154862, 19813.108410121866, 19895.602087957872, 14893.851061489246, 15027.624309392353, 15096.849031509544, 15135.697286053997, 15174.013689110172, 18697.0, 18700.251989920795, 18841.811581884005, 18998.620027599645, 19012.429627111207], "value": 12112.704399123404, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1353.8713822186892, 1398.2519352146596, 1409.4432699083861, 1413.9222342771156, 1420.28275720761, 13608.455661773529, 13705.65735856604, 14124.364403601838, 14131.858681413183, 14139.293035348217, 14450.783238251408, 14760.33578488968, 14778.113313201215, 14783.630409239775, 14792.0, 2673.451942351813, 2692.4749673813517, 2708.526007948611, 2711.9830063726104, 2712.0778935162043, 2662.6804783426014, 2684.7717943974785, 2685.650865387497, 2686.4895669822768, 2686.5835795451735, 2605.3877338825455, 2637.696664883534, 2638.868056597172, 2646.4310173312733, 2672.786177105827, 2620.135355332736, 2638.3536033671776, 2644.854533000684, 2647.5763877779464, 2649.3376655835946, 2611.647427597269, 2621.659184306028, 2624.6194301826317, 2668.8932442702267, 2678.116221646852, 13585.252811095386, 13889.16665000093, 13933.233672148028, 13948.093373930662, 13957.651058723512, 7627.122880868716, 7806.0, 7856.921430785676, 7889.8027549311155, 7908.960455197687, 4786.449558300805, 5013.273075404082, 5085.79656813727, 5095.490450954912, 5116.181410974264, 1815.310182130795, 1835.9265629374943, 1837.9908100459545, 1917.1468696430095, 1919.7024461208534, 22858.771412285998, 23096.538069238522, 23107.653385199235, 23132.306030818934, 23135.0, 6224.844378890473, 6264.906026409608, 6271.27880293763, 6441.838954026187, 6509.218893732783, 15611.531654050397, 15611.829112816356, 15631.921840390953, 15672.138032408098, 15672.608184795243, 7548.509346892434, 7711.575863327571, 7729.458937874408, 7736.458447908705, 7740.148583655793, 6831.521793474509, 6932.064171336906, 7134.714611415589, 7136.0723105995885, 7140.357367836919, 5001.774920128603, 5110.437851836295, 5161.974190129101, 5169.81905633301, 5231.764570594332, 1518.3926429428318, 1519.4302136698739, 1536.3624096000258, 1542.2627824492868, 1547.5744170353018, 826.5454000299811, 827.6275675945849, 848.291676450171, 860.8579584368634, 880.823835232956, 6494.318096599884, 6763.7632682855865, 6861.313868613099, 6917.342852429011, 7011.404030657349, 684.090160087083, 691.930806919304, 709.9112610923635, 720.8017795106283, 729.9525530840478, 4216.093539888939, 4556.7721613919175, 4802.73584952831, 4811.639127065484, 4822.590079843182, 18215.271389144287, 18837.0, 18863.90568047151, 18974.241030358906, 18975.0, 18340.908295458437, 18926.3375781847, 18946.0, 19146.329878454464, 19162.904185478983, 10137.341072830242, 10230.23273254509, 10308.484575771181, 10335.896641033643, 10391.116755075904, 7175.784726458214, 7641.7707468776025, 7727.613619319124, 7964.730051935171, 7985.680572777024, 15755.0, 16312.0, 16515.256813443662, 16575.91712041432, 16634.25145868463, 16059.678806424039, 16447.753283700516, 16586.419475318307, 16610.58473538171, 16688.0, 12929.482820687443, 13143.211407315592, 13193.20840749558, 13235.40440680191, 13253.07228493996, 12995.675108122372, 13198.472061117447, 13201.669958251121, 13206.141600795918, 13215.735685286048, 10167.51808351153, 10589.52347144365, 10624.946875265574, 10675.359478431319, 10942.343459392143, 9650.806983860142, 10062.547185376528, 10169.389836609827, 10293.588256469657, 10331.0, 9068.863967040368, 9127.22418594427, 9151.17639412456, 9200.447973121634, 9203.125703058198, 6181.474574661024, 6231.22109736283, 6392.296847346695, 6526.869462610816, 6597.109390232257, 6279.717412716347, 6496.642684652389, 6618.900716489353, 6665.633390163587, 6691.832704182434], "value": 6224.465158877998, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [488.95859785395976, 501.6780782637751, 528.748844298958, 529.3568314497884, 532.4967905329464, 6198.0, 6200.53495987801, 6220.858466474436, 6264.0, 6270.749170033193, 5842.941570584293, 6060.515158787298, 6071.757129714806, 6090.299615544213, 6149.13912052313, 4819.855404337858, 4895.0, 4950.108980383529, 4952.678075925062, 4958.578520825716, 4876.2929375240565, 4910.1898186799135, 4914.70511769295, 4915.754212289371, 4960.280759289901, 4704.717716936994, 4807.927881081787, 4832.830850920219, 4840.588549973257, 4883.780229889646, 4740.620750339965, 4786.21027530457, 4797.112534181188, 4850.199717046683, 4939.333190019337, 4376.781160941972, 4412.955870441287, 4414.735115893057, 4443.955560444387, 4444.755538445384, 5967.806438712231, 6021.0, 6031.638101713867, 6032.577719559633, 6038.758449662008, 5610.214569960209, 5643.746031428595, 5645.745941432645, 5647.29408823897, 5696.401877802854, 6429.763129764121, 6470.0, 6471.1587493626, 6480.15757951468, 6510.381513756186, 4798.328234047236, 4811.518848115195, 4823.855284341475, 4835.153848076572, 4862.270659401118, 6771.932280677229, 6877.415419689332, 6917.204521480095, 6923.411510021705, 6931.1682598088555, 4848.200046992207, 4850.151223535867, 4874.926876096862, 4951.059298733301, 4961.305417241589, 4952.158133117378, 4964.801407943642, 4987.600991920638, 5036.370453693288, 5037.647364684437, 5634.267545219095, 5645.2096706461125, 5686.289213848269, 5688.459596338341, 5698.857528561819, 6410.391012853772, 6482.0, 6492.7084426192505, 6576.177977752781, 6594.934050659528, 1650.867930565552, 1701.7362308842148, 1706.7610534525177, 1729.2793670297883, 1744.6597913407018, 1144.9255798373079, 1202.7955247607927, 1212.7150119721857, 1222.3888055972013, 1231.4950870143198, 5652.293463317085, 5704.8859022819315, 5708.800191993343, 5724.656520608777, 5760.884782304331, 6473.935260647333, 6502.423666983141, 6579.605223686594, 6611.272759996396, 6669.7332106715185, 5706.914396284059, 5731.53543976086, 5765.203876120078, 5783.94216057834, 5805.445603934256, 3564.3762341590636, 3564.8395822188063, 3571.4464258040043, 3575.7496975211484, 3585.8027808470783, 6464.5798023128345, 6471.6764161791725, 6491.285958544557, 6510.381513756186, 6539.0, 6720.630365329954, 6857.7256909723055, 6896.310368963063, 6916.827079323057, 6947.5831450113155, 6425.871482570323, 6481.935180648133, 6499.2850786413455, 6510.414062734377, 6544.9672751635935, 6643.335666433318, 6665.866682666223, 6702.061711360316, 6723.4285085766305, 6763.357481039294, 6587.736490540325, 6616.66916654175, 6617.371349721769, 6653.700583473852, 6659.234188068241, 6494.7077381517, 6507.511936604868, 6512.990486474604, 6529.95520716683, 6567.802965911031, 4894.241392584155, 4895.828645997373, 4896.2410826321975, 4907.411110666742, 4909.797099710594, 6769.003144072412, 6769.864602708017, 6808.5914845109455, 6832.692528836314, 6839.452843772586, 6346.90479642815, 6475.352464753487, 6498.480121590167, 6510.641914694548, 6574.506911981525, 6432.453241474527, 6441.613503189824, 6492.188476440445, 6533.248676402274, 6537.477001839747, 6163.537734669828, 6207.472364849038, 6345.96827015862, 6363.331850155667, 6432.0, 6447.451966582893, 6505.772297969548, 6551.21385433751, 6575.901361479678, 6599.152237373543, 6078.54410919174, 6255.0, 6342.2389313280855, 6359.8092057238355, 6376.234851817811], "value": 5007.533927812099, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [904.1500989070274, 910.808730166665, 929.916307532322, 929.986050209247, 931.9021502742216, 15790.4473343433, 16191.595210119753, 16206.432774852883, 16241.106739129344, 20251.696224556643, 20166.395008149742, 20893.582128357422, 20951.47621309468, 21080.367588972356, 21099.894500527513, 1028.8456731490298, 1043.582566973213, 1053.8893416191304, 1060.9681709548727, 1061.7080302916681, 1005.7636455432968, 1006.1598565198068, 1006.3106771861262, 1008.2236677758121, 1051.9421431821247, 992.7865508915581, 1041.281515754131, 1083.9078678312314, 1113.048343666166, 1120.1207052463817, 1029.783745413464, 1032.5766435761338, 1033.1270076785179, 1033.4264483211853, 1076.4779082145153, 788.2629741192004, 790.517784151667, 796.7171654062782, 798.2137594469443, 798.4889670610813, 18215.0892455378, 19270.132844022053, 19798.901005495023, 19968.600627987504, 19974.300899468595, 18485.44543663692, 18548.44354669362, 18665.88004719721, 18886.43340699781, 18908.0, 15384.538463846102, 15861.444849430214, 15872.12703301318, 15898.0, 15973.281202345923, 3423.5207071010077, 3515.033365824393, 3547.4324108142832, 3558.6085530591613, 3559.430491121434, 108403.0, 111986.44006779995, 113263.43368283188, 116645.99999999916, 116758.0, 15485.84514154844, 15743.13412762309, 15839.0, 15859.731221502252, 15909.52271431859, 28170.15489535317, 29559.85220073886, 29810.850945745566, 29842.70157298399, 29888.701112989027, 7724.420668449777, 8020.959895200486, 8103.0681471631515, 8170.223828736261, 8446.83106337882, 4050.858219962287, 4171.249175148441, 4187.539370669224, 4191.392248124005, 4192.413062171298, 4922.630802689812, 4979.850604481871, 5083.313752643419, 5086.593072554187, 5092.465291144451, 987.0228473810876, 995.6807230419723, 997.9151772099308, 999.1107913956585, 999.5102399824034, 651.9869602607921, 657.9309172536911, 682.5665702279089, 683.8666460040344, 692.7679227458858, 11018.228723989405, 11277.492512836941, 12373.13388062845, 13521.729565408637, 13916.58250252494, 653.8136631060125, 675.6689222281145, 680.452235950065, 686.1046334533382, 694.0456871801273, 5336.386315573681, 5400.0, 5513.669179849222, 5677.120046392815, 5709.22925405065, 92770.0, 95752.52123739476, 96477.5176124115, 99143.0, 100566.49716751369, 81981.74603566853, 84396.0, 84917.0, 86827.56586217029, 88341.55829220942, 74156.0, 74472.0, 77192.45615087666, 77614.73770077954, 78981.60509197302, 11477.253978491372, 11960.58137965167, 12145.14983951115, 12314.076444266537, 12933.482660693844, 33186.17034574155, 35408.82295588506, 35541.0, 35910.64089359073, 36922.63077369192, 60074.699626501584, 61185.38814611797, 65595.34404655892, 65960.34039659542, 67039.66480167568, 17883.821161788215, 18536.351227706964, 18725.25098996025, 18730.625387492448, 18770.249190032246, 19201.807981920003, 20447.59104817925, 20878.68681969802, 21034.68447973251, 21512.354629361143, 17821.821781782015, 18158.182881770615, 18371.724424133383, 18722.0, 18905.810941890406, 17859.821401785815, 17909.820901790816, 17988.730169047212, 18478.538036549195, 19031.90484047571, 16607.50177494677, 16815.663686726442, 16835.24241409164, 16859.325626974784, 17010.914945425193, 12438.564650237198, 12701.491940322283, 13050.216986980811, 13204.0, 13320.400581974029, 12715.682107947376, 13627.454901803816, 14029.719405611626, 14236.145831250158, 14247.101748657058], "value": 8837.393803422716, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1558.8999999999996, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 108.0, 105.0, 108.0, 109.0, 113.0, 108.0, 103.0, 109.0, 110.0], "value": 107.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 118.0, 158.0, 183.0, 119.0, 152.0, 151.0, 125.0, 182.0, 119.0], "value": 144.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 165.0, 163.0, 162.0, 170.0, 170.0, 165.0, 173.0, 164.0, 164.0], "value": 166.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 75.0, 77.0, 171.0, 88.0, 77.0, 81.0, 88.0, 97.0, 82.0], "value": 91.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 79.0, 133.0, 87.0, 79.0, 78.0, 128.0, 79.0, 79.0, 142.0], "value": 96.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 114.0, 114.0, 114.0, 114.0, 114.0, 115.0, 114.0, 117.0, 114.0], "value": 114.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 163.0, 151.0, 150.0, 150.0, 151.0, 152.0, 151.0, 151.0, 152.0], "value": 152.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 93.0, 95.0, 94.0, 101.0, 95.0, 96.0, 94.0, 94.0, 94.0], "value": 95.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 58.0, 58.0, 59.0, 58.0, 58.0, 63.0, 56.0, 57.0, 57.0], "value": 58.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 54.0, 55.0, 55.0, 54.0, 54.0, 65.0, 54.0, 55.0, 55.0], "value": 55.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 79.0, 80.0, 82.0, 87.0, 84.0, 83.0, 82.0, 82.0, 81.0], "value": 82.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [129.0, 139.0, 129.0, 136.0, 130.0, 140.0, 133.0, 139.0, 129.0, 163.0], "value": 136.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [177.0, 188.0, 188.0, 199.0, 187.0, 181.0, 188.0, 181.0, 178.0, 189.0], "value": 185.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 74.0, 77.0, 73.0, 69.0, 74.0, 75.0, 78.0, 73.0, 68.0], "value": 73.1, "unit": "score"}]}]} 07:31:33 INFO - Return code: 0 07:31:33 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 07:31:33 INFO - # TBPL SUCCESS # 07:31:33 INFO - Running post-action listener: _package_coverage_data 07:31:33 INFO - Running post-action listener: _resource_record_post_action 07:31:33 INFO - [mozharness: 2017-07-28 14:31:33.561205Z] Finished run-tests step (success) 07:31:33 INFO - Running post-run listener: _resource_record_post_run 07:31:33 INFO - Total resource usage - Wall time: 1195s; CPU: 13.0%; Read bytes: 4096; Write bytes: 445558784; Read time: 12; Write time: 298904 07:31:33 INFO - TinderboxPrint: CPU usage
13.2% 07:31:33 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 07:31:33 INFO - TinderboxPrint: I/O write bytes / time
445,558,784 / 298,904 07:31:33 INFO - TinderboxPrint: CPU idle
8,272.6 (86.8%) 07:31:33 INFO - TinderboxPrint: CPU user
1,204.7 (12.6%) 07:31:33 INFO - TinderboxPrint: Swap in / out
0 / 0 07:31:33 INFO - install - Wall time: 19s; CPU: 13.0%; Read bytes: 0; Write bytes: 112943104; Read time: 0; Write time: 84756 07:31:33 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 07:31:33 INFO - run-tests - Wall time: 1177s; CPU: 13.0%; Read bytes: 0; Write bytes: 331161600; Read time: 0; Write time: 214092 07:31:34 INFO - Running post-run listener: _upload_blobber_files 07:31:34 INFO - Blob upload gear active. 07:31:34 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 07:31:34 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 07:31:34 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'] 07:31:34 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 07:31:34 INFO - (blobuploader) - INFO - Open directory for files ... 07:31:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 07:31:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:31:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:31:41 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 07:31:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:31:41 INFO - (blobuploader) - INFO - Done attempting. 07:31:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 07:31:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:31:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:31:41 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 07:31:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:31:41 INFO - (blobuploader) - INFO - Done attempting. 07:31:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 07:31:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:31:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:31:42 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 07:31:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:31:42 INFO - (blobuploader) - INFO - Done attempting. 07:31:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 07:31:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:31:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:31:43 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 07:31:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:31:43 INFO - (blobuploader) - INFO - Done attempting. 07:31:43 INFO - (blobuploader) - INFO - Iteration through files over. 07:31:43 INFO - Return code: 0 07:31:43 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 07:31:43 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 07:31:43 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/13d470fcddea08f05015307dd15f4db2dafdce7de7b109860fcf7d766117e4a6552d5d9742e904b3b586f40c7ac650801696d5adc4bd11e0ed704f5687542ac0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4e9473d546cbf857a832d0f244aeff07ad283c222fa5758248783cb40b1f6cc1a6ea2f8201b47d245eb4de27d18bbed1fac8ed98936be74cc79b64e8969b1a2d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3e2d37271904dedfaf5d0df1217ab50542d6d11cc5040548da423996ec185b7947699c70b422e61219f182b36a321595954302807c8be29c22c796e916c023c5", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f7bccd7e31cb10b2de241cbd53764148fd1eebb1e23bbc54305845c0f87b0104a9764513b817390584b3ced4e2842cc6eef8689ee9dc4ad524b9b5a4a186a8b0"} 07:31:43 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 07:31:43 INFO - Writing to file /builds/slave/test/properties/blobber_files 07:31:43 INFO - Contents: 07:31:43 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/13d470fcddea08f05015307dd15f4db2dafdce7de7b109860fcf7d766117e4a6552d5d9742e904b3b586f40c7ac650801696d5adc4bd11e0ed704f5687542ac0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4e9473d546cbf857a832d0f244aeff07ad283c222fa5758248783cb40b1f6cc1a6ea2f8201b47d245eb4de27d18bbed1fac8ed98936be74cc79b64e8969b1a2d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3e2d37271904dedfaf5d0df1217ab50542d6d11cc5040548da423996ec185b7947699c70b422e61219f182b36a321595954302807c8be29c22c796e916c023c5", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f7bccd7e31cb10b2de241cbd53764148fd1eebb1e23bbc54305845c0f87b0104a9764513b817390584b3ced4e2842cc6eef8689ee9dc4ad524b9b5a4a186a8b0"} 07:31:43 INFO - Running post-run listener: copy_logs_to_upload_dir 07:31:43 INFO - Copying logs to upload dir... 07:31:43 INFO - mkdir: /builds/slave/test/build/upload/logs 07:31:43 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1260.243491 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 0 secs) (at 2017-07-28 07:31:43.313811) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-07-28 07:31:43.318774) ========= 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=51fc86400edbde9650d2781a000001d0-1501250581.574311-1448507222 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/13d470fcddea08f05015307dd15f4db2dafdce7de7b109860fcf7d766117e4a6552d5d9742e904b3b586f40c7ac650801696d5adc4bd11e0ed704f5687542ac0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4e9473d546cbf857a832d0f244aeff07ad283c222fa5758248783cb40b1f6cc1a6ea2f8201b47d245eb4de27d18bbed1fac8ed98936be74cc79b64e8969b1a2d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3e2d37271904dedfaf5d0df1217ab50542d6d11cc5040548da423996ec185b7947699c70b422e61219f182b36a321595954302807c8be29c22c796e916c023c5", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f7bccd7e31cb10b2de241cbd53764148fd1eebb1e23bbc54305845c0f87b0104a9764513b817390584b3ced4e2842cc6eef8689ee9dc4ad524b9b5a4a186a8b0"} build_url:https://queue.taskcluster.net/v1/task/BEg1anclTmmD7bmx1GwmKA/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011201 build_url: 'https://queue.taskcluster.net/v1/task/BEg1anclTmmD7bmx1GwmKA/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/13d470fcddea08f05015307dd15f4db2dafdce7de7b109860fcf7d766117e4a6552d5d9742e904b3b586f40c7ac650801696d5adc4bd11e0ed704f5687542ac0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4e9473d546cbf857a832d0f244aeff07ad283c222fa5758248783cb40b1f6cc1a6ea2f8201b47d245eb4de27d18bbed1fac8ed98936be74cc79b64e8969b1a2d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3e2d37271904dedfaf5d0df1217ab50542d6d11cc5040548da423996ec185b7947699c70b422e61219f182b36a321595954302807c8be29c22c796e916c023c5", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f7bccd7e31cb10b2de241cbd53764148fd1eebb1e23bbc54305845c0f87b0104a9764513b817390584b3ced4e2842cc6eef8689ee9dc4ad524b9b5a4a186a8b0"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-07-28 07:31:43.345760) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-07-28 07:31:43.346073) ========= 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=51fc86400edbde9650d2781a000001d0-1501250581.574311-1448507222 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004420 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-07-28 07:31:43.427054) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-07-28 07:31:43.430358) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-07-28 07:31:43.430638) ========= ========= Total master_lag: 0.19 =========