builder: mozilla-central_ubuntu64_hw_qr_test-dromaeojs-e10s slave: talos-linux64-ix-031 starttime: 1506141450.62 results: success (0) revision: 9d1fcbc2fdd58011903aa1b987264d58dc1a06b5 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-22 21:37:30.624139) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-22 21:37:30.624610) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-22 21:37:30.624891) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-22 21:37:30.643990) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-22 21:37:30.644326) ========= 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=db0483c52d987ecb441a263b000001ad-1506134377.817830-1745771307 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-22 21:37:30-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.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% 109M=0s 2017-09-22 21:37:30 (109 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.120070 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-22 21:37:30.798754) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-22 21:37:30.799065) ========= 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=db0483c52d987ecb441a263b000001ad-1506134377.817830-1745771307 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019357 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-22 21:37:30.849439) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-22 21:37:30.849744) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 9d1fcbc2fdd58011903aa1b987264d58dc1a06b5 --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 9d1fcbc2fdd58011903aa1b987264d58dc1a06b5 --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=db0483c52d987ecb441a263b000001ad-1506134377.817830-1745771307 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-22 21:37:30,894 truncating revision to first 12 chars 2017-09-22 21:37:30,894 Setting DEBUG logging. 2017-09-22 21:37:30,894 attempt 1/10 2017-09-22 21:37:30,894 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/9d1fcbc2fdd5?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-22 21:37:31,427 unpacking tar archive at: mozilla-central-9d1fcbc2fdd5/testing/mozharness/ program finished with exit code 0 elapsedTime=0.870267 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-22 21:37:31.741524) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-22 21:37:31.741816) ========= script_repo_revision: 9d1fcbc2fdd58011903aa1b987264d58dc1a06b5 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-22 21:37:31.742224) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-22 21:37:31.742507) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-22 21:37:31.764291) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 44 secs) (at 2017-09-22 21:37:31.764645) ========= /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=db0483c52d987ecb441a263b000001ad-1506134377.817830-1745771307 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 21:37:31 INFO - MultiFileLogger online at 20170922 21:37:31 in /builds/slave/test 21:37:31 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 21:37:31 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 21:37:31 INFO - {'append_to_log': False, 21:37:31 INFO - 'base_work_dir': '/builds/slave/test', 21:37:31 INFO - 'blob_upload_branch': 'Firefox', 21:37:31 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 21:37:31 INFO - 'branch': 'Firefox', 21:37:31 INFO - 'buildbot_json_path': 'buildprops.json', 21:37:31 INFO - 'code_coverage': False, 21:37:31 INFO - 'config_files': ('talos/linux_config.py',), 21:37:31 INFO - 'default_actions': ('clobber', 21:37:31 INFO - 'read-buildbot-config', 21:37:31 INFO - 'download-and-extract', 21:37:31 INFO - 'populate-webroot', 21:37:31 INFO - 'create-virtualenv', 21:37:31 INFO - 'install', 21:37:31 INFO - 'setup-mitmproxy', 21:37:31 INFO - 'run-tests'), 21:37:31 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 21:37:31 INFO - 'disable_ccov_upload': False, 21:37:31 INFO - 'disable_stylo': False, 21:37:31 INFO - 'download_minidump_stackwalk': True, 21:37:31 INFO - 'download_symbols': 'ondemand', 21:37:31 INFO - 'enable_stylo': False, 21:37:31 INFO - 'enable_webrender': False, 21:37:31 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 21:37:31 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 21:37:31 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 21:37:31 INFO - 'gecko_profile': False, 21:37:31 INFO - 'gecko_profile_interval': 0, 21:37:31 INFO - 'installer_path': 'installer.exe', 21:37:31 INFO - 'log_level': 'info', 21:37:31 INFO - 'log_name': 'talos', 21:37:31 INFO - 'log_to_console': True, 21:37:31 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 21:37:31 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 21:37:31 INFO - 'opt_config_files': (), 21:37:31 INFO - 'pip_index': False, 21:37:31 INFO - 'suite': 'dromaeojs-e10s', 21:37:31 INFO - 'system_bits': '32', 21:37:31 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 21:37:31 INFO - 'title': 'talos-linux64-ix-031', 21:37:31 INFO - 'tooltool_cache': '/builds/tooltool_cache', 21:37:31 INFO - 'use_talos_json': True, 21:37:31 INFO - 'verify': 'False', 21:37:31 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 21:37:31 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 21:37:31 INFO - 'work_dir': 'build'} 21:37:31 INFO - [mozharness: 2017-09-23 04:37:31.951373Z] Running clobber step. 21:37:31 INFO - Running pre-action listener: _resource_record_pre_action 21:37:31 INFO - Running main action method: clobber 21:37:31 INFO - rmtree: /builds/slave/test/build 21:37:31 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 21:37:33 INFO - Running post-action listener: _resource_record_post_action 21:37:33 INFO - [mozharness: 2017-09-23 04:37:33.450617Z] Finished clobber step (success) 21:37:33 INFO - [mozharness: 2017-09-23 04:37:33.450714Z] Running read-buildbot-config step. 21:37:33 INFO - Running pre-action listener: _resource_record_pre_action 21:37:33 INFO - Running main action method: read_buildbot_config 21:37:33 INFO - Using buildbot properties: 21:37:33 INFO - { 21:37:33 INFO - "project": "", 21:37:33 INFO - "product": "firefox", 21:37:33 INFO - "who": "ffxbld@noreply.mozilla.org", 21:37:33 INFO - "installer_path": "public/build/target.tar.bz2", 21:37:33 INFO - "builddir": "mozilla-central_ubuntu64_hw_qr_test-dromaeojs-e10s", 21:37:33 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 21:37:33 INFO - "buildername": "Ubuntu HW 12.04 x64 qr mozilla-central talos dromaeojs-e10s", 21:37:33 INFO - "stage_platform": "linux64-qr", 21:37:33 INFO - "basedir": "/builds/slave/test", 21:37:33 INFO - "buildnumber": 74, 21:37:33 INFO - "platform": "ubuntu64_hw_qr", 21:37:33 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 21:37:33 INFO - "slavebuilddir": "test", 21:37:33 INFO - "taskId": "HW0bQIDlQia-F75xXD3qkw", 21:37:33 INFO - "branch": "mozilla-central", 21:37:33 INFO - "script_repo_revision": "production", 21:37:33 INFO - "revision": "9d1fcbc2fdd58011903aa1b987264d58dc1a06b5", 21:37:33 INFO - "slavename": "talos-linux64-ix-031", 21:37:33 INFO - "repo_path": "mozilla-central" 21:37:33 INFO - } 21:37:33 INFO - Finding installer, test and symbols from parent task. 21:37:33 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HW0bQIDlQia-F75xXD3qkw'}, attempt #1 21:37:33 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HW0bQIDlQia-F75xXD3qkw'}, attempt #1 21:37:34 INFO - Task dependencies: VxRwKolgS2WkH9phigpZaw 21:37:34 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VxRwKolgS2WkH9phigpZaw'}, attempt #1 21:37:34 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/VxRwKolgS2WkH9phigpZaw/artifacts/public/build/target.tar.bz2 21:37:34 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/VxRwKolgS2WkH9phigpZaw/artifacts/public/build/target.test_packages.json 21:37:34 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/VxRwKolgS2WkH9phigpZaw/artifacts/public/build/target.crashreporter-symbols.zip 21:37:34 INFO - Running post-action listener: _resource_record_post_action 21:37:34 INFO - [mozharness: 2017-09-23 04:37:34.786320Z] Finished read-buildbot-config step (success) 21:37:34 INFO - [mozharness: 2017-09-23 04:37:34.786537Z] Running download-and-extract step. 21:37:34 INFO - Running pre-action listener: _resource_record_pre_action 21:37:34 INFO - Running main action method: download_and_extract 21:37:34 INFO - mkdir: /builds/slave/test/build/tests 21:37: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')]} 21:37:34 INFO - trying https://queue.taskcluster.net/v1/task/VxRwKolgS2WkH9phigpZaw/artifacts/public/build/target.test_packages.json 21:37:34 INFO - Downloading https://queue.taskcluster.net/v1/task/VxRwKolgS2WkH9phigpZaw/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 21:37:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VxRwKolgS2WkH9phigpZaw/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 21:37:35 INFO - Downloaded 989 bytes. 21:37:35 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 21:37:35 INFO - Using the following test package requirements: 21:37:35 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 21:37:35 INFO - u'common': [u'target.common.tests.zip'], 21:37:35 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 21:37:35 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 21:37:35 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 21:37:35 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 21:37:35 INFO - u'mozbase': [u'target.common.tests.zip'], 21:37:35 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 21:37:35 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 21:37:35 INFO - u'web-platform': [u'target.common.tests.zip', 21:37:35 INFO - u'target.web-platform.tests.tar.gz'], 21:37:35 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 21:37:35 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 21:37:35 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VxRwKolgS2WkH9phigpZaw/artifacts/public/build/target.common.tests.zip 21:37:35 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VxRwKolgS2WkH9phigpZaw/artifacts/public/build/target.common.tests.zip'}, attempt #1 21:37:35 INFO - Fetch https://queue.taskcluster.net/v1/task/VxRwKolgS2WkH9phigpZaw/artifacts/public/build/target.common.tests.zip into memory 21:37:37 INFO - Content-Length response header: 54892836 21:37:37 INFO - Bytes received: 54892836 21:37:48 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VxRwKolgS2WkH9phigpZaw/artifacts/public/build/target.talos.tests.zip 21:37:48 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VxRwKolgS2WkH9phigpZaw/artifacts/public/build/target.talos.tests.zip'}, attempt #1 21:37:48 INFO - Fetch https://queue.taskcluster.net/v1/task/VxRwKolgS2WkH9phigpZaw/artifacts/public/build/target.talos.tests.zip into memory 21:37:48 INFO - Content-Length response header: 14513823 21:37:48 INFO - Bytes received: 14513823 21:37:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:37:49 INFO - trying https://queue.taskcluster.net/v1/task/VxRwKolgS2WkH9phigpZaw/artifacts/public/build/target.tar.bz2 21:37:49 INFO - Downloading https://queue.taskcluster.net/v1/task/VxRwKolgS2WkH9phigpZaw/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 21:37:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VxRwKolgS2WkH9phigpZaw/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 21:37:50 INFO - Downloaded 61600446 bytes. 21:37:50 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/VxRwKolgS2WkH9phigpZaw/artifacts/public/build/target.tar.bz2 21:37:50 INFO - mkdir: /builds/slave/test/properties 21:37:50 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 21:37:50 INFO - Writing to file /builds/slave/test/properties/build_url 21:37:50 INFO - Contents: 21:37:50 INFO - build_url:https://queue.taskcluster.net/v1/task/VxRwKolgS2WkH9phigpZaw/artifacts/public/build/target.tar.bz2 21:37:50 INFO - Running post-action listener: _resource_record_post_action 21:37:50 INFO - Running post-action listener: find_tests_for_verification 21:37:50 INFO - Running post-action listener: set_extra_try_arguments 21:37:50 INFO - [mozharness: 2017-09-23 04:37:50.317700Z] Finished download-and-extract step (success) 21:37:50 INFO - [mozharness: 2017-09-23 04:37:50.317816Z] Running populate-webroot step. 21:37:50 INFO - Running pre-action listener: _resource_record_pre_action 21:37:50 INFO - Running main action method: populate_webroot 21:37:50 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 21:37:50 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 21:37:50 INFO - 'tests': ['tresize', 21:37:50 INFO - 'tcanvasmark']}, 21:37:50 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 21:37:50 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 21:37:50 INFO - 'tests': ['dromaeo_css', 21:37:50 INFO - 'kraken']}, 21:37:50 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 21:37:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:37:50 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 21:37:50 INFO - 'talos_options': ['--disable-stylo'], 21:37:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:37:50 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 21:37:50 INFO - 'tests': ['damp', 'tps']}, 21:37:50 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 21:37:50 INFO - 'talos_options': ['--disable-stylo'], 21:37:50 INFO - 'tests': ['damp', 'tps']}, 21:37:50 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 21:37:50 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 21:37:50 INFO - 'tests': ['dromaeo_dom']}, 21:37:50 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 21:37:50 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 21:37:50 INFO - 'tests': ['basic_compositor_video', 21:37:50 INFO - 'glvideo']}, 21:37:50 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 21:37:50 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 21:37:50 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 21:37:50 INFO - 'talos_options': ['--disable-stylo'], 21:37:50 INFO - 'tests': ['ts_paint_webext', 21:37:50 INFO - 'tp5o_webext']}, 21:37:50 INFO - 'other-e10s': {'tests': ['a11yr', 21:37:50 INFO - 'ts_paint', 21:37:50 INFO - 'tpaint', 21:37:50 INFO - 'sessionrestore', 21:37:50 INFO - 'sessionrestore_many_windows', 21:37:50 INFO - 'sessionrestore_no_auto_restore', 21:37:50 INFO - 'tabpaint']}, 21:37:50 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 21:37:50 INFO - 'tests': ['a11yr', 21:37:50 INFO - 'ts_paint', 21:37:50 INFO - 'tpaint', 21:37:50 INFO - 'sessionrestore', 21:37:50 INFO - 'sessionrestore_many_windows', 21:37:50 INFO - 'sessionrestore_no_auto_restore', 21:37:50 INFO - 'tabpaint']}, 21:37:50 INFO - 'perf-reftest-e10s': {'tests': ['perf_reftest']}, 21:37:50 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']}, 21:37:50 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 21:37:50 INFO - 'tests': ['perf_reftest_singletons']}, 21:37:50 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 21:37:50 INFO - 'tests': ['perf_reftest']}, 21:37:50 INFO - 'svgr-e10s': {'tests': ['tsvgx', 21:37:50 INFO - 'tsvgr_opacity', 21:37:50 INFO - 'tart', 21:37:50 INFO - 'tscrollx', 21:37:50 INFO - 'tsvg_static']}, 21:37:50 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 21:37:50 INFO - 'tests': ['tsvgx', 21:37:50 INFO - 'tsvgr_opacity', 21:37:50 INFO - 'tart', 21:37:50 INFO - 'tscrollx', 21:37:50 INFO - 'tsvg_static']}, 21:37:50 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 21:37:50 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 21:37:50 INFO - 'talos_options': ['--disable-stylo'], 21:37:50 INFO - 'tests': ['tp5o']}, 21:37:50 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 21:37:50 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 21:37:50 INFO - 'talos_options': ['--mitmproxy', 21:37:50 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 21:37:50 INFO - '--firstNonBlankPaint'], 21:37:50 INFO - 'tests': ['tp6_google', 21:37:50 INFO - 'tp6_youtube', 21:37:50 INFO - 'tp6_amazon', 21:37:50 INFO - 'tp6_facebook']}, 21:37:50 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 21:37:50 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 21:37:50 INFO - 'talos_options': ['--disable-stylo', 21:37:50 INFO - '--mitmproxy', 21:37:50 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 21:37:50 INFO - '--firstNonBlankPaint'], 21:37:50 INFO - 'tests': ['tp6_google', 21:37:50 INFO - 'tp6_youtube', 21:37:50 INFO - 'tp6_amazon', 21:37:50 INFO - 'tp6_facebook']}, 21:37:50 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 21:37:50 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 21:37:50 INFO - 'talos_options': ['--stylo-threads=1', 21:37:50 INFO - '--mitmproxy', 21:37:50 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 21:37:50 INFO - '--firstNonBlankPaint'], 21:37:50 INFO - 'tests': ['tp6_google', 21:37:50 INFO - 'tp6_youtube', 21:37:50 INFO - 'tp6_amazon', 21:37:50 INFO - 'tp6_facebook']}, 21:37:50 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 21:37:50 INFO - 'talos_options': ['--xperf_path', 21:37:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:37:50 INFO - 'tests': ['tp5n']}, 21:37:50 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 21:37:50 INFO - 'talos_options': ['--disable-stylo', 21:37:50 INFO - '--xperf_path', 21:37:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:37:50 INFO - 'tests': ['tp5n']}}} 21:37:50 INFO - Running post-action listener: _resource_record_post_action 21:37:50 INFO - [mozharness: 2017-09-23 04:37:50.335591Z] Finished populate-webroot step (success) 21:37:50 INFO - [mozharness: 2017-09-23 04:37:50.335698Z] Running create-virtualenv step. 21:37:50 INFO - Running pre-action listener: _resource_record_pre_action 21:37:50 INFO - Running main action method: create_virtualenv 21:37:50 INFO - Creating virtualenv /builds/slave/test/build/venv 21:37:50 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 21:37:50 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 21:37:50 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 21:37:50 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 21:37:50 INFO - New python executable in /builds/slave/test/build/venv/bin/python 21:37:51 INFO - Installing distribute.............................................................................................................................................................................................done. 21:37:52 INFO - Installing pip...............done. 21:37:52 INFO - Return code: 0 21:37:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 21:37:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 21:37:52 INFO - Reading from file tmpfile_stdout 21:37:52 INFO - Output received: 21:37:52 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 21:37:52 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 21:37:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:37:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:37:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:37:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:37:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c7a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e42b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fe79c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fe4d60>, '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 0x1fe5a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fe5ef0>, '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': 'db0483c52d987ecb441a263b000001ad-1506134377.817830-1745771307', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 21:37:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 21:37:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 21:37:52 INFO - Using env: {'DISPLAY': ':0', 21:37:52 INFO - 'HOME': '/home/cltbld', 21:37:52 INFO - 'LANG': 'en_US.UTF-8', 21:37:52 INFO - 'LANGUAGE': 'en_US:en', 21:37:52 INFO - 'LOGNAME': 'cltbld', 21:37:52 INFO - 'MAIL': '/var/mail/cltbld', 21:37:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:37:52 INFO - 'MOZ_NO_REMOTE': '1', 21:37:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:37:52 INFO - 'NO_EM_RESTART': '1', 21:37:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:37:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:37:52 INFO - 'PWD': '/builds/slave/test', 21:37:52 INFO - 'SHELL': '/bin/bash', 21:37:52 INFO - 'SHLVL': '1', 21:37:52 INFO - 'TERM': 'linux', 21:37:52 INFO - 'TMOUT': '86400', 21:37:52 INFO - 'USER': 'cltbld', 21:37:52 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1506134377.817830-1745771307', 21:37:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:37:52 INFO - '_': '/tools/buildbot/bin/python'} 21:37:53 INFO - Ignoring indexes: http://pypi.python.org/simple/ 21:37:53 INFO - Downloading/unpacking pip>=1.5 21:37:53 INFO - Running setup.py egg_info for package pip 21:37:53 INFO - warning: no files found matching 'pip/cacert.pem' 21:37:53 INFO - warning: no files found matching '*.html' under directory 'docs' 21:37:53 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 21:37:53 INFO - no previously-included directories found matching 'docs/_build/_sources' 21:37:53 INFO - Installing collected packages: pip 21:37:53 INFO - Found existing installation: pip 1.1 21:37:53 INFO - Uninstalling pip: 21:37:53 INFO - Successfully uninstalled pip 21:37:53 INFO - Running setup.py install for pip 21:37:53 INFO - warning: no files found matching 'pip/cacert.pem' 21:37:53 INFO - warning: no files found matching '*.html' under directory 'docs' 21:37:53 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 21:37:53 INFO - no previously-included directories found matching 'docs/_build/_sources' 21:37:53 INFO - Installing pip script to /builds/slave/test/build/venv/bin 21:37:53 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 21:37:53 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 21:37:53 INFO - Successfully installed pip 21:37:53 INFO - Cleaning up... 21:37:53 INFO - Return code: 0 21:37:53 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 21:37:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:37:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:37:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:37:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:37:53 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 0x1c7a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e42b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fe79c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fe4d60>, '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 0x1fe5a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fe5ef0>, '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': 'db0483c52d987ecb441a263b000001ad-1506134377.817830-1745771307', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 21:37:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 21:37:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 21:37:53 INFO - Using env: {'DISPLAY': ':0', 21:37:53 INFO - 'HOME': '/home/cltbld', 21:37:53 INFO - 'LANG': 'en_US.UTF-8', 21:37:53 INFO - 'LANGUAGE': 'en_US:en', 21:37:53 INFO - 'LOGNAME': 'cltbld', 21:37:53 INFO - 'MAIL': '/var/mail/cltbld', 21:37:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:37:53 INFO - 'MOZ_NO_REMOTE': '1', 21:37:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:37:53 INFO - 'NO_EM_RESTART': '1', 21:37:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:37:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:37:53 INFO - 'PWD': '/builds/slave/test', 21:37:53 INFO - 'SHELL': '/bin/bash', 21:37:53 INFO - 'SHLVL': '1', 21:37:53 INFO - 'TERM': 'linux', 21:37:53 INFO - 'TMOUT': '86400', 21:37:53 INFO - 'USER': 'cltbld', 21:37:53 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1506134377.817830-1745771307', 21:37:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:37:53 INFO - '_': '/tools/buildbot/bin/python'} 21:37:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:37:53 INFO - Downloading/unpacking psutil>=3.1.1 21:37:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:37:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:37:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:37:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:37:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 21:37:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:37:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:37:54 INFO - Installing collected packages: psutil 21:37:54 INFO - Running setup.py install for psutil 21:37:54 INFO - building 'psutil._psutil_linux' extension 21:37:54 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 21:37:55 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 21:37:55 INFO - building 'psutil._psutil_posix' extension 21:37:55 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 21:37:55 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 21:37:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:37:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:37:55 INFO - Successfully installed psutil 21:37:55 INFO - Cleaning up... 21:37:55 INFO - Return code: 0 21:37:55 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 21:37:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:37:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:37:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:37:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:37:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c7a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e42b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fe79c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fe4d60>, '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 0x1fe5a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fe5ef0>, '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': 'db0483c52d987ecb441a263b000001ad-1506134377.817830-1745771307', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 21:37:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 21:37:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 21:37:55 INFO - Using env: {'DISPLAY': ':0', 21:37:55 INFO - 'HOME': '/home/cltbld', 21:37:55 INFO - 'LANG': 'en_US.UTF-8', 21:37:55 INFO - 'LANGUAGE': 'en_US:en', 21:37:55 INFO - 'LOGNAME': 'cltbld', 21:37:55 INFO - 'MAIL': '/var/mail/cltbld', 21:37:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:37:55 INFO - 'MOZ_NO_REMOTE': '1', 21:37:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:37:55 INFO - 'NO_EM_RESTART': '1', 21:37:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:37:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:37:55 INFO - 'PWD': '/builds/slave/test', 21:37:55 INFO - 'SHELL': '/bin/bash', 21:37:55 INFO - 'SHLVL': '1', 21:37:55 INFO - 'TERM': 'linux', 21:37:55 INFO - 'TMOUT': '86400', 21:37:55 INFO - 'USER': 'cltbld', 21:37:55 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1506134377.817830-1745771307', 21:37:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:37:55 INFO - '_': '/tools/buildbot/bin/python'} 21:37:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:37:55 INFO - Downloading/unpacking mozsystemmonitor==0.3 21:37:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:37:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:37:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:37:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:37:56 INFO - Downloading mozsystemmonitor-0.3.tar.gz 21:37:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 21:37:56 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 21:37:56 INFO - Installing collected packages: mozsystemmonitor 21:37:56 INFO - Running setup.py install for mozsystemmonitor 21:37:57 INFO - Successfully installed mozsystemmonitor 21:37:57 INFO - Cleaning up... 21:37:58 INFO - Return code: 0 21:37:58 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 21:37:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:37:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:37:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:37:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:37:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c7a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e42b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fe79c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fe4d60>, '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 0x1fe5a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fe5ef0>, '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': 'db0483c52d987ecb441a263b000001ad-1506134377.817830-1745771307', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 21:37:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 21:37:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 21:37:58 INFO - Using env: {'DISPLAY': ':0', 21:37:58 INFO - 'HOME': '/home/cltbld', 21:37:58 INFO - 'LANG': 'en_US.UTF-8', 21:37:58 INFO - 'LANGUAGE': 'en_US:en', 21:37:58 INFO - 'LOGNAME': 'cltbld', 21:37:58 INFO - 'MAIL': '/var/mail/cltbld', 21:37:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:37:58 INFO - 'MOZ_NO_REMOTE': '1', 21:37:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:37:58 INFO - 'NO_EM_RESTART': '1', 21:37:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:37:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:37:58 INFO - 'PWD': '/builds/slave/test', 21:37:58 INFO - 'SHELL': '/bin/bash', 21:37:58 INFO - 'SHLVL': '1', 21:37:58 INFO - 'TERM': 'linux', 21:37:58 INFO - 'TMOUT': '86400', 21:37:58 INFO - 'USER': 'cltbld', 21:37:58 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1506134377.817830-1745771307', 21:37:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:37:58 INFO - '_': '/tools/buildbot/bin/python'} 21:37:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:37:58 INFO - Downloading/unpacking jsonschema==2.5.1 21:37:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:37:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:37:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:37:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:37:59 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 21:37:59 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 21:37:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:37:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:37:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:37:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:37:59 INFO - Downloading functools32-3.2.3-2.tar.gz 21:37:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 21:37:59 INFO - warning: no files found matching '*.txt' 21:37:59 INFO - no previously-included directories found matching 'build' 21:37:59 INFO - no previously-included directories found matching 'dist' 21:37:59 INFO - no previously-included directories found matching '.git*' 21:37:59 INFO - Installing collected packages: jsonschema, functools32 21:37:59 INFO - Running setup.py install for functools32 21:38:00 INFO - warning: no files found matching '*.txt' 21:38:00 INFO - no previously-included directories found matching 'build' 21:38:00 INFO - no previously-included directories found matching 'dist' 21:38:00 INFO - no previously-included directories found matching '.git*' 21:38:00 INFO - Successfully installed jsonschema functools32 21:38:00 INFO - Cleaning up... 21:38:00 INFO - Return code: 0 21:38:00 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 21:38:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:38:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:38:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:38:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:38:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c7a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e42b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fe79c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fe4d60>, '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 0x1fe5a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fe5ef0>, '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': 'db0483c52d987ecb441a263b000001ad-1506134377.817830-1745771307', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 21:38:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 21:38:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 21:38:00 INFO - Using env: {'DISPLAY': ':0', 21:38:00 INFO - 'HOME': '/home/cltbld', 21:38:00 INFO - 'LANG': 'en_US.UTF-8', 21:38:00 INFO - 'LANGUAGE': 'en_US:en', 21:38:00 INFO - 'LOGNAME': 'cltbld', 21:38:00 INFO - 'MAIL': '/var/mail/cltbld', 21:38:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:38:00 INFO - 'MOZ_NO_REMOTE': '1', 21:38:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:38:00 INFO - 'NO_EM_RESTART': '1', 21:38:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:38:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:38:00 INFO - 'PWD': '/builds/slave/test', 21:38:00 INFO - 'SHELL': '/bin/bash', 21:38:00 INFO - 'SHLVL': '1', 21:38:00 INFO - 'TERM': 'linux', 21:38:00 INFO - 'TMOUT': '86400', 21:38:00 INFO - 'USER': 'cltbld', 21:38:00 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1506134377.817830-1745771307', 21:38:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:38:00 INFO - '_': '/tools/buildbot/bin/python'} 21:38:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:38:00 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 21:38:00 INFO - Cleaning up... 21:38:00 INFO - Return code: 0 21:38:00 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 21:38:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:38:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:38:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:38:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:38:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1c7a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e42b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fe79c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fe4d60>, '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 0x1fe5a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fe5ef0>, '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': 'db0483c52d987ecb441a263b000001ad-1506134377.817830-1745771307', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 21:38:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 21:38:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 21:38:00 INFO - Using env: {'DISPLAY': ':0', 21:38:00 INFO - 'HOME': '/home/cltbld', 21:38:00 INFO - 'LANG': 'en_US.UTF-8', 21:38:00 INFO - 'LANGUAGE': 'en_US:en', 21:38:00 INFO - 'LOGNAME': 'cltbld', 21:38:00 INFO - 'MAIL': '/var/mail/cltbld', 21:38:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:38:00 INFO - 'MOZ_NO_REMOTE': '1', 21:38:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:38:00 INFO - 'NO_EM_RESTART': '1', 21:38:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:38:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:38:00 INFO - 'PWD': '/builds/slave/test', 21:38:00 INFO - 'SHELL': '/bin/bash', 21:38:00 INFO - 'SHLVL': '1', 21:38:00 INFO - 'TERM': 'linux', 21:38:00 INFO - 'TMOUT': '86400', 21:38:00 INFO - 'USER': 'cltbld', 21:38:00 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1506134377.817830-1745771307', 21:38:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:38:00 INFO - '_': '/tools/buildbot/bin/python'} 21:38:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:38:00 INFO - Downloading/unpacking blobuploader==1.2.4 21:38:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:38:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:38:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:38:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:38:01 INFO - Downloading blobuploader-1.2.4.tar.gz 21:38:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 21:38:01 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 21:38:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:38:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:38:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:38:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:38:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 21:38:02 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 21:38:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:38:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:38:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:38:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:38:02 INFO - Downloading docopt-0.6.1.tar.gz 21:38:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 21:38:02 INFO - Installing collected packages: blobuploader, requests, docopt 21:38:02 INFO - Running setup.py install for blobuploader 21:38:02 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 21:38:02 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 21:38:02 INFO - Running setup.py install for requests 21:38:02 INFO - Running setup.py install for docopt 21:38:02 INFO - Successfully installed blobuploader requests docopt 21:38:02 INFO - Cleaning up... 21:38:02 INFO - Return code: 0 21:38:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:38:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:38:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:38:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:38:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:38:02 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 0x1c7a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e42b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fe79c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fe4d60>, '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 0x1fe5a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fe5ef0>, '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': 'db0483c52d987ecb441a263b000001ad-1506134377.817830-1745771307', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 21:38:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 21:38:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 21:38:02 INFO - Using env: {'DISPLAY': ':0', 21:38:02 INFO - 'HOME': '/home/cltbld', 21:38:02 INFO - 'LANG': 'en_US.UTF-8', 21:38:02 INFO - 'LANGUAGE': 'en_US:en', 21:38:02 INFO - 'LOGNAME': 'cltbld', 21:38:02 INFO - 'MAIL': '/var/mail/cltbld', 21:38:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:38:02 INFO - 'MOZ_NO_REMOTE': '1', 21:38:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:38:02 INFO - 'NO_EM_RESTART': '1', 21:38:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:38:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:38:02 INFO - 'PWD': '/builds/slave/test', 21:38:02 INFO - 'SHELL': '/bin/bash', 21:38:02 INFO - 'SHLVL': '1', 21:38:02 INFO - 'TERM': 'linux', 21:38:02 INFO - 'TMOUT': '86400', 21:38:02 INFO - 'USER': 'cltbld', 21:38:02 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1506134377.817830-1745771307', 21:38:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:38:02 INFO - '_': '/tools/buildbot/bin/python'} 21:38:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:38:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:38:03 INFO - Running setup.py (path:/tmp/pip-cgiW_K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:38:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:38:03 INFO - Running setup.py (path:/tmp/pip-txmYyL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:38:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:38:03 INFO - Running setup.py (path:/tmp/pip-j0vmf5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:38:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:38:03 INFO - Running setup.py (path:/tmp/pip-9xKgnb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:38:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:38:03 INFO - Running setup.py (path:/tmp/pip-hSdMwb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:38:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:38:03 INFO - Running setup.py (path:/tmp/pip-z7RHZV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:38:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:38:03 INFO - Running setup.py (path:/tmp/pip-SBDrFM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:38:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:38:03 INFO - Running setup.py (path:/tmp/pip-z2Ir0l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:38:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:38:03 INFO - Running setup.py (path:/tmp/pip-ZOGqWD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:38:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:38:03 INFO - Running setup.py (path:/tmp/pip-GnvxKZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:38:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:38:03 INFO - Running setup.py (path:/tmp/pip-F8mAh4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:38:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:38:03 INFO - Running setup.py (path:/tmp/pip-RrM9Hy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:38:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:38:03 INFO - Running setup.py (path:/tmp/pip-e74Tj9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:38:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:38:04 INFO - Running setup.py (path:/tmp/pip-j9MYMC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:38:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:38:04 INFO - Running setup.py (path:/tmp/pip-Lg4In7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:38:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:38:04 INFO - Running setup.py (path:/tmp/pip-m2bXPE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:38:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:38:04 INFO - Running setup.py (path:/tmp/pip-Z6q0qT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:38:04 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 21:38:04 INFO - Running setup.py install for manifestparser 21:38:04 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 21:38:04 INFO - Running setup.py install for mozcrash 21:38:04 INFO - Running setup.py install for mozdebug 21:38:04 INFO - Running setup.py install for mozdevice 21:38:04 INFO - Installing dm script to /builds/slave/test/build/venv/bin 21:38:04 INFO - Running setup.py install for mozfile 21:38:04 INFO - Running setup.py install for mozhttpd 21:38:04 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 21:38:04 INFO - Running setup.py install for mozinfo 21:38:04 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 21:38:04 INFO - Running setup.py install for mozInstall 21:38:05 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 21:38:05 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 21:38:05 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 21:38:05 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 21:38:05 INFO - Running setup.py install for mozleak 21:38:05 INFO - Running setup.py install for mozlog 21:38:05 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 21:38:05 INFO - Running setup.py install for moznetwork 21:38:05 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 21:38:05 INFO - Running setup.py install for mozprocess 21:38:05 INFO - Running setup.py install for mozprofile 21:38:05 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 21:38:05 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 21:38:05 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 21:38:05 INFO - Running setup.py install for mozrunner 21:38:05 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 21:38:05 INFO - Running setup.py install for mozscreenshot 21:38:05 INFO - Running setup.py install for moztest 21:38:05 INFO - Running setup.py install for mozversion 21:38:05 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 21:38:06 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 21:38:06 INFO - Cleaning up... 21:38:06 INFO - Return code: 0 21:38:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:38:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:38:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:38:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:38:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:38:06 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 0x1c7a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e42b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fe79c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fe4d60>, '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 0x1fe5a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fe5ef0>, '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': 'db0483c52d987ecb441a263b000001ad-1506134377.817830-1745771307', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 21:38:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 21:38:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 21:38:06 INFO - Using env: {'DISPLAY': ':0', 21:38:06 INFO - 'HOME': '/home/cltbld', 21:38:06 INFO - 'LANG': 'en_US.UTF-8', 21:38:06 INFO - 'LANGUAGE': 'en_US:en', 21:38:06 INFO - 'LOGNAME': 'cltbld', 21:38:06 INFO - 'MAIL': '/var/mail/cltbld', 21:38:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:38:06 INFO - 'MOZ_NO_REMOTE': '1', 21:38:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:38:06 INFO - 'NO_EM_RESTART': '1', 21:38:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:38:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:38:06 INFO - 'PWD': '/builds/slave/test', 21:38:06 INFO - 'SHELL': '/bin/bash', 21:38:06 INFO - 'SHLVL': '1', 21:38:06 INFO - 'TERM': 'linux', 21:38:06 INFO - 'TMOUT': '86400', 21:38:06 INFO - 'USER': 'cltbld', 21:38:06 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1506134377.817830-1745771307', 21:38:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:38:06 INFO - '_': '/tools/buildbot/bin/python'} 21:38:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:38:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:38:06 INFO - Running setup.py (path:/tmp/pip-O7O3Mx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:38:06 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 21:38:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:38:06 INFO - Running setup.py (path:/tmp/pip-BQOP2M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:38:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 21:38:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:38:06 INFO - Running setup.py (path:/tmp/pip-iVY5wE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:38:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 21:38:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:38:06 INFO - Running setup.py (path:/tmp/pip-Fo1wzW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:38:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 21:38:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:38:06 INFO - Running setup.py (path:/tmp/pip-GAxDni-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:38:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 21:38:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:38:06 INFO - Running setup.py (path:/tmp/pip-jp9Vnl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:38:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 21:38:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:38:06 INFO - Running setup.py (path:/tmp/pip-O3Z2VG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:38:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 21:38:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:38:06 INFO - Running setup.py (path:/tmp/pip-eL7n2I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:38:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.13 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 21:38:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:38:06 INFO - Running setup.py (path:/tmp/pip-uS2JAF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:38:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 21:38:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:38:06 INFO - Running setup.py (path:/tmp/pip-Q6uUG1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:38:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.5 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 21:38:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:38:06 INFO - Running setup.py (path:/tmp/pip-6A6x1f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:38:07 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 21:38:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:38:07 INFO - Running setup.py (path:/tmp/pip-1Tjx_1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:38:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 21:38:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:38:07 INFO - Running setup.py (path:/tmp/pip-pMejGK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:38:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 21:38:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:38:07 INFO - Running setup.py (path:/tmp/pip-ufXn92-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:38:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 21:38:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:38:07 INFO - Running setup.py (path:/tmp/pip-KpPYQU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:38:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 21:38:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:38:07 INFO - Running setup.py (path:/tmp/pip-JerJGp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:38:07 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 21:38:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:38:07 INFO - Running setup.py (path:/tmp/pip-DpWjaY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:38:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 21:38:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 21:38:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 21:38:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 21:38:07 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 21:38:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 21:38:07 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.13->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 21:38:07 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 21:38:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:38:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:38:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:38:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:38:08 INFO - Downloading blessings-1.6.tar.gz 21:38:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 21:38:08 INFO - Installing collected packages: blessings 21:38:08 INFO - Running setup.py install for blessings 21:38:08 INFO - Successfully installed blessings 21:38:08 INFO - Cleaning up... 21:38:08 INFO - Return code: 0 21:38:08 INFO - Done creating virtualenv /builds/slave/test/build/venv. 21:38:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:38:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:38:09 INFO - Reading from file tmpfile_stdout 21:38:09 INFO - Current package versions: 21:38:09 INFO - argparse == 1.2.1 21:38:09 INFO - blessings == 1.6 21:38:09 INFO - blobuploader == 1.2.4 21:38:09 INFO - docopt == 0.6.1 21:38:09 INFO - functools32 == 3.2.3-2 21:38:09 INFO - jsonschema == 2.5.1 21:38:09 INFO - manifestparser == 1.1 21:38:09 INFO - mozInstall == 1.13 21:38:09 INFO - mozcrash == 1.0 21:38:09 INFO - mozdebug == 0.1 21:38:09 INFO - mozdevice == 0.51 21:38:09 INFO - mozfile == 1.2 21:38:09 INFO - mozhttpd == 0.7 21:38:09 INFO - mozinfo == 0.10 21:38:09 INFO - mozleak == 0.1 21:38:09 INFO - mozlog == 3.5 21:38:09 INFO - moznetwork == 0.27 21:38:09 INFO - mozprocess == 0.25 21:38:09 INFO - mozprofile == 0.28 21:38:09 INFO - mozrunner == 6.13 21:38:09 INFO - mozscreenshot == 0.1 21:38:09 INFO - mozsystemmonitor == 0.3 21:38:09 INFO - moztest == 0.8 21:38:09 INFO - mozversion == 1.4 21:38:09 INFO - psutil == 3.1.1 21:38:09 INFO - requests == 1.2.3 21:38:09 INFO - wsgiref == 0.1.2 21:38:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:38:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:38:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:38:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:38:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:38:09 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 0x1c7a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e42b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fe79c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fe4d60>, '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 0x1fe5a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fe5ef0>, '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': 'db0483c52d987ecb441a263b000001ad-1506134377.817830-1745771307', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 21:38:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 21:38:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 21:38:09 INFO - Using env: {'DISPLAY': ':0', 21:38:09 INFO - 'HOME': '/home/cltbld', 21:38:09 INFO - 'LANG': 'en_US.UTF-8', 21:38:09 INFO - 'LANGUAGE': 'en_US:en', 21:38:09 INFO - 'LOGNAME': 'cltbld', 21:38:09 INFO - 'MAIL': '/var/mail/cltbld', 21:38:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:38:09 INFO - 'MOZ_NO_REMOTE': '1', 21:38:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:38:09 INFO - 'NO_EM_RESTART': '1', 21:38:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:38:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:38:09 INFO - 'PWD': '/builds/slave/test', 21:38:09 INFO - 'SHELL': '/bin/bash', 21:38:09 INFO - 'SHLVL': '1', 21:38:09 INFO - 'TERM': 'linux', 21:38:09 INFO - 'TMOUT': '86400', 21:38:09 INFO - 'USER': 'cltbld', 21:38:09 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1506134377.817830-1745771307', 21:38:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:38:09 INFO - '_': '/tools/buildbot/bin/python'} 21:38:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:38:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 21:38:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 21:38:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 21:38:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 21:38:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 21:38:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 21:38:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 21:38:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 21:38:09 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 21:38:09 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 21:38:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:38:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:38:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:38:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:38:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 21:38:10 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 21:38:10 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 21:38:10 INFO - Installing collected packages: simplejson 21:38:10 INFO - Running setup.py install for simplejson 21:38:10 INFO - building 'simplejson._speedups' extension 21:38:10 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c simplejson/_speedups.c -o build/temp.linux-x86_64-2.7/simplejson/_speedups.o 21:38:11 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/simplejson/_speedups.o -o build/lib.linux-x86_64-2.7/simplejson/_speedups.so 21:38:11 INFO - Successfully installed simplejson 21:38:11 INFO - Cleaning up... 21:38:11 INFO - Return code: 0 21:38:11 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 21:38:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:38:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:38:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:38:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:38:11 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 0x1c7a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e42b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fe79c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fe4d60>, '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 0x1fe5a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fe5ef0>, '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': 'db0483c52d987ecb441a263b000001ad-1506134377.817830-1745771307', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 21:38:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 21:38:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 21:38:11 INFO - Using env: {'DISPLAY': ':0', 21:38:11 INFO - 'HOME': '/home/cltbld', 21:38:11 INFO - 'LANG': 'en_US.UTF-8', 21:38:11 INFO - 'LANGUAGE': 'en_US:en', 21:38:11 INFO - 'LOGNAME': 'cltbld', 21:38:11 INFO - 'MAIL': '/var/mail/cltbld', 21:38:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:38:11 INFO - 'MOZ_NO_REMOTE': '1', 21:38:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:38:11 INFO - 'NO_EM_RESTART': '1', 21:38:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:38:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:38:11 INFO - 'PWD': '/builds/slave/test', 21:38:11 INFO - 'SHELL': '/bin/bash', 21:38:11 INFO - 'SHLVL': '1', 21:38:11 INFO - 'TERM': 'linux', 21:38:11 INFO - 'TMOUT': '86400', 21:38:11 INFO - 'USER': 'cltbld', 21:38:11 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1506134377.817830-1745771307', 21:38:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:38:11 INFO - '_': '/tools/buildbot/bin/python'} 21:38:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:38:11 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 21:38:11 INFO - Cleaning up... 21:38:11 INFO - Return code: 0 21:38:11 INFO - Running post-action listener: _resource_record_post_action 21:38:11 INFO - Running post-action listener: _start_resource_monitoring 21:38:11 INFO - Starting resource monitoring. 21:38:11 INFO - [mozharness: 2017-09-23 04:38:11.730866Z] Finished create-virtualenv step (success) 21:38:11 INFO - [mozharness: 2017-09-23 04:38:11.731384Z] Running install step. 21:38:11 INFO - Running pre-action listener: _resource_record_pre_action 21:38:11 INFO - Running main action method: install 21:38:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:38:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:38:11 INFO - Reading from file tmpfile_stdout 21:38:11 INFO - Detecting whether we're running mozinstall >=1.0... 21:38:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 21:38:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 21:38:12 INFO - Reading from file tmpfile_stdout 21:38:12 INFO - Output received: 21:38:12 INFO - Usage: mozinstall [options] installer 21:38:12 INFO - Options: 21:38:12 INFO - -h, --help show this help message and exit 21:38:12 INFO - -d DEST, --destination=DEST 21:38:12 INFO - Directory to install application into. [default: 21:38:12 INFO - "/builds/slave/test"] 21:38:12 INFO - --app=APP Application being installed. [default: firefox] 21:38:12 INFO - mkdir: /builds/slave/test/build/application 21:38:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 21:38:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 21:38:29 INFO - Reading from file tmpfile_stdout 21:38:29 INFO - Output received: 21:38:29 INFO - /builds/slave/test/build/application/firefox/firefox 21:38:29 INFO - Running post-action listener: _resource_record_post_action 21:38:29 INFO - [mozharness: 2017-09-23 04:38:29.836553Z] Finished install step (success) 21:38:29 INFO - [mozharness: 2017-09-23 04:38:29.836700Z] Running setup-mitmproxy step. 21:38:29 INFO - Running pre-action listener: _resource_record_pre_action 21:38:29 INFO - Running main action method: setup_mitmproxy 21:38:29 INFO - Skipping: mitmproxy is not required 21:38:29 INFO - Running post-action listener: _resource_record_post_action 21:38:29 INFO - [mozharness: 2017-09-23 04:38:29.837231Z] Finished setup-mitmproxy step (success) 21:38:29 INFO - [mozharness: 2017-09-23 04:38:29.837332Z] Running run-tests step. 21:38:29 INFO - Running pre-action listener: _resource_record_pre_action 21:38:29 INFO - Running pre-action listener: _set_gcov_prefix 21:38:29 INFO - Running main action method: run_tests 21:38:29 WARNING - Try message not found. 21:38:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 21:38:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 21:38:29 INFO - Python 2.7.3 21:38:29 INFO - Return code: 0 21:38:29 INFO - grabbing minidump binary from tooltool 21:38:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:38:29 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--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 0x1fe4d60>, '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 0x1fe5a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fe5ef0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 21:38:29 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 21:38:29 INFO - Copy/paste: /tools/tooltool.py --url https://tooltool.mozilla-releng.net/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 21:38:29 INFO - Calling ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 21:38:29 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 21:38:29 INFO - Return code: 0 21:38:29 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 21:38:29 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 21:38:29 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 21:38:29 INFO - ENV: RUST_BACKTRACE is now full 21:38:29 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 21:38:29 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 21:38:29 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/VxRwKolgS2WkH9phigpZaw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-031', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 21:38:29 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/VxRwKolgS2WkH9phigpZaw/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-031 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 21:38:29 INFO - Using env: {'DISPLAY': ':0', 21:38:29 INFO - 'HOME': '/home/cltbld', 21:38:29 INFO - 'LANG': 'en_US.UTF-8', 21:38:29 INFO - 'LANGUAGE': 'en_US:en', 21:38:29 INFO - 'LOGNAME': 'cltbld', 21:38:29 INFO - 'MAIL': '/var/mail/cltbld', 21:38:29 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 21:38:29 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 21:38:29 INFO - 'MOZ_ACCELERATED': '1', 21:38:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:38:29 INFO - 'MOZ_NO_REMOTE': '1', 21:38:29 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 21:38:29 INFO - 'MOZ_WEBRENDER': '1', 21:38:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:38:29 INFO - 'NO_EM_RESTART': '1', 21:38:29 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:38:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:38:29 INFO - 'PWD': '/builds/slave/test', 21:38:29 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 21:38:29 INFO - 'RUST_BACKTRACE': 'full', 21:38:29 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 21:38:29 INFO - 'SHELL': '/bin/bash', 21:38:29 INFO - 'SHLVL': '1', 21:38:29 INFO - 'TERM': 'linux', 21:38:29 INFO - 'TMOUT': '86400', 21:38:29 INFO - 'USER': 'cltbld', 21:38:29 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1506134377.817830-1745771307', 21:38:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:38:29 INFO - '_': '/tools/buildbot/bin/python'} 21:38:29 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/VxRwKolgS2WkH9phigpZaw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-031', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 21:38:30 INFO - mozversion application_buildid: 20170923034137 21:38:30 INFO - mozversion application_changeset: 9d1fcbc2fdd58011903aa1b987264d58dc1a06b5 21:38:30 INFO - mozversion application_display_name: Nightly 21:38:30 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 21:38:30 INFO - mozversion application_name: Firefox 21:38:30 INFO - mozversion application_remotingname: firefox 21:38:30 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 21:38:30 INFO - mozversion application_vendor: Mozilla 21:38:30 INFO - mozversion application_version: 58.0a1 21:38:30 INFO - mozversion platform_buildid: 20170923034137 21:38:30 INFO - mozversion platform_changeset: 9d1fcbc2fdd58011903aa1b987264d58dc1a06b5 21:38:30 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 21:38:30 INFO - mozversion platform_version: 58.0a1 21:38:30 INFO - using testdate: 1506141510 21:38:30 INFO - actual date: 1506141510 21:38:30 INFO - starting webserver on 'localhost:50810' 21:38:30 INFO - SUITE-START | Running 2 tests 21:38:30 INFO - TEST-START | dromaeo_css 21:38:30 INFO - Initialising browser for dromaeo_css test... 21:38:30 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:50810/getInfo.html -profile /tmp/tmpPEWqZa/profile 21:38:30 INFO - TEST-INFO | started process 8376 (/builds/slave/test/build/application/firefox/firefox http://localhost:50810/getInfo.html) 21:38:37 INFO - TEST-INFO | 8376: exit 0 21:38:37 INFO - Browser initialized. 21:38:37 INFO - Running cycle 1/1 for dromaeo_css test... 21:38:37 INFO - Using env: {'DISPLAY': ':0', 21:38:37 INFO - 'HOME': '/home/cltbld', 21:38:37 INFO - 'JSGC_DISABLE_POISONING': '1', 21:38:37 INFO - 'LANG': 'en_US.UTF-8', 21:38:37 INFO - 'LANGUAGE': 'en_US:en', 21:38:37 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 21:38:37 INFO - 'LOGNAME': 'cltbld', 21:38:37 INFO - 'MAIL': '/var/mail/cltbld', 21:38:37 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 21:38:37 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 21:38:37 INFO - 'MOZ_ACCELERATED': '1', 21:38:37 INFO - 'MOZ_CRASHREPORTER': '1', 21:38:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:38:37 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 21:38:37 INFO - 'MOZ_NO_REMOTE': '1', 21:38:37 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 21:38:37 INFO - 'MOZ_WEBRENDER': '1', 21:38:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:38:37 INFO - 'NO_EM_RESTART': '1', 21:38:37 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:38:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:38:37 INFO - 'PWD': '/builds/slave/test', 21:38:37 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 21:38:37 INFO - 'RUST_BACKTRACE': 'full', 21:38:37 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 21:38:37 INFO - 'SHELL': '/bin/bash', 21:38:37 INFO - 'SHLVL': '1', 21:38:37 INFO - 'TERM': 'linux', 21:38:37 INFO - 'TMOUT': '86400', 21:38:37 INFO - 'USER': 'cltbld', 21:38:37 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1506134377.817830-1745771307', 21:38:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:38:37 INFO - '_': '/tools/buildbot/bin/python'} 21:38:37 INFO - TEST-INFO | started process 8620 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpPEWqZa/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 21:38:38 INFO - PID 8620 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 21:38:38 INFO - PID 8620 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 21:38:38 INFO - PID 8620 | 21:38:38 INFO - PID 8620 | (/builds/slave/test/build/application/firefox/firefox:8680): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 21:38:38 INFO - PID 8620 | 21:38:49 INFO - PID 8620 | RSS: Main: 187346944 21:38:49 INFO - PID 8620 | 21:41:45 INFO - PID 8620 | Cycle 1(1): loaded http://localhost:50810/tests/dromaeo/cssquery-dojo.html (next: http://localhost:50810/tests/dromaeo/cssquery-ext.html) 21:41:47 INFO - PID 8620 | RSS: Main: 192397312 21:41:47 INFO - PID 8620 | 21:44:49 INFO - PID 8620 | Cycle 1(1): loaded http://localhost:50810/tests/dromaeo/cssquery-ext.html (next: http://localhost:50810/tests/dromaeo/cssquery-jquery.html) 21:44:50 INFO - PID 8620 | RSS: Main: 193306624 21:44:50 INFO - PID 8620 | 21:47:52 INFO - PID 8620 | Cycle 1(1): loaded http://localhost:50810/tests/dromaeo/cssquery-jquery.html (next: http://localhost:50810/tests/dromaeo/cssquery-mootools.html) 21:47:52 INFO - PID 8620 | RSS: Main: 195112960 21:47:52 INFO - PID 8620 | 21:50:54 INFO - PID 8620 | Cycle 1(1): loaded http://localhost:50810/tests/dromaeo/cssquery-mootools.html (next: http://localhost:50810/tests/dromaeo/cssquery-prototype.html) 21:50:55 INFO - PID 8620 | RSS: Main: 194547712 21:50:55 INFO - PID 8620 | 21:53:59 INFO - PID 8620 | Cycle 1(1): loaded http://localhost:50810/tests/dromaeo/cssquery-prototype.html (next: http://localhost:50810/tests/dromaeo/cssquery-yui.html) 21:53:59 INFO - PID 8620 | RSS: Main: 194809856 21:53:59 INFO - PID 8620 | 21:57:01 INFO - PID 8620 | Cycle 1(1): loaded http://localhost:50810/tests/dromaeo/cssquery-yui.html (next: http://localhost:50810/tests/dromaeo/cssquery-dojo.html) 21:57:02 INFO - PID 8620 | RSS: Main: 197869568 21:57:02 INFO - PID 8620 | 21:57:02 INFO - PID 8620 | __start_tp_report 21:57:02 INFO - PID 8620 | _x_x_mozilla_page_load 21:57:02 INFO - PID 8620 | _x_x_mozilla_page_load_details 21:57:02 INFO - PID 8620 | |i|pagename|runs| 21:57:02 INFO - PID 8620 | |0;dojo.html;3126.0309304115713;3135.0908236611385;3165.8100513969157;3189.1867573768686;3191.21815155287;6172.5061995040405;6240.596650297183;6279.309275979645;6358.936410635893;6360.300366959631;6207.937920620792;6231;6266.46735027522;6299.685015749217;6302.810915672526;5639.605227634067;5766.221560089376;5788.363280039192;5798.65208087514;5805.216295800076;5758.42415758425;5791.652500849941;5812.622179558315;5878.911816322737;5916.201312822757;5795.217645617851;5819.476247137757;5821.621594596359;5833.591648584601;5848.4736373726355;86.05317682050833;5669.603127781057;5709.343425506078;5746.885062298772;5850.736716847752;5731.598788084836;5745.482906538432;5755.8561035974435;5809.506191973687;5830.32951210612;6209.534284928648;6237.968810155966;6248.343923887972;6296.181496405438;6344.873102537969;6643;6665.800025999228;6675.899861502081;6695.2969938156275;6738.494612904052;6702.329767023308;6747.696353664097;6758.628275444848;6816.113905192343;6830.214525329601;6045.697715114271;6145.385461453819;6152.169457123276;6158.692065396757;6294.244690637107;6769.627670478072;6811.761588344434;6828.7268509259075;6853.623050732158;6878.65606719662;6515.73937042513;6520.347965203442;6526.118973938553;6531.673416329165;6683.966580167068;6640.369164929324;6717.294684058202;6724.361185687352;6739.494537909676;6742.595444273359;6568.113304703899;6622.337766223339;6713.4964877634375;6714.060031595581;6714.261431242559;6514.706838192293;6600.933990660128;6624.735010599522;6638.933610663928;6721.563098398588;5231.3199284092825;5231.738413079407;5247.081760691864;5250.028744682246;5260.28986086881;4553.203189441834;4620.607248383858;4626.653001024936;4628.375169352162;4675.719456832601;5722.456366645162;5743.4543718346695;5785.971070144622;5814.098814683731;5820.854478638068;6580.144581204413;6665.200175978914;6677.399034086956;6711.63086030273;6747.426468750113;5732.942670573241;5839.795607153729;5861.1501332307425;5875.294964604275;5877.941220587825;5774.769009239583;5801.129830525455;5833.300003999547;5850.5027072699295;5855.648661080264;6486.189226346707;6493.577917435446;6582.111414959015;6589;6600.141981542369;6692.732290708317;6710.899336509956;6800.93199068003;6803.4557235422035;6806.149231346082;6325.652089135142;6377.23473183221;6405.679716014274;6472.223333200046;6473.190851143607;6442.935570644234;6508.9349106508325;6556.803295901131;6570.5729127606555;6644.501662375438;6749.156355455568;6758.5269031167345;6767.323267673193;6783.152105986752;6794.93205067943;6457.483401327789;6461.483081353575;6474.093626892333;6555.147830781968;6570.080188773671;6337.207849018872;6449.6130232186215;6473.287938326686;6536.869262614816;6555.80332590023;6271.090691849748;6323.810285691437;6337;6337.302896681454;6352.714127864349;6509.121268628864;6604.108445359815;6604.339566043357;6611.975143852711;6637.435817955527;6327.240731112296;6361.2048493938255;6397.104405383343;6418.29398766126;6422.550421470452 21:57:02 INFO - PID 8620 | |1;ext.html;870.9069738822569;895.6193617712471;911.5351170902837;916.6516723645018;922.0502882031506;39114.60885391145;39725.20549589006;40311.59688403119;40338.39492407609;40384.39423408651;36712.26575468489;36767.63232367676;37652;39379.40930886032;39617.20765584687;7859.764207073783;7932.405069619773;7958.4031197660115;7968.681252749912;8000.239977202156;7880.842383152333;7897.091834439027;7982.121966583672;7984.24149705777;8017.438779285452;7952.443328966975;7972.963514743075;8063.516189028648;8087.029556453233;8095.311898488636;7965.6813727450835;7981.800454988643;8010.158933311976;8014.519128852229;8018.278354948054;7820.413468989792;7868.803279917991;7869.2130786921425;7895.210478952116;7900.446968712193;36448.08879778;36483;36731.63268367309;36823.07942301437;36851.631483685356;3497.9475307870403;3515.54297941266;3522;3523.6476352364816;3530.2409981853766;30402.84798575993;30469;30496.237594060105;30563.847180764176;30627.387452251052;4937.7777999990085;5123.871903202413;5170.379554453452;5190.221466779975;5197.090509160882;186331;186338;186614;188291;189161.0541947295;46853;47077;47229.5277047232;47405.288920666215;47450;48732;48811.267830982564;49132.263016054785;50693.23960140601;52735.472645273825;14713.852861471463;14750.852491475163;14801.555953321418;14836.406543738345;15019.24903754808;12082.154249202647;12108.21296615717;12108.69728256783;12170.513179472722;12201.267923924594;13896.930515347358;14016.439342426189;14268.14391136535;14310.212938288287;14451.638709032148;5609.1305847593685;5689.118186681071;5758.165066065397;5795.681237531976;5852.94147058524;993.3791380387258;1001.1490233301781;1012.0164477623939;1012.4128005756644;1022.846573014039;22602.547949041254;23155.768442315697;23263.88368058149;23381.181658641864;23739.1691290804;1088.8693356797078;1111.855458790352;1124.2860783402598;1129.5594718059963;1132.7451323452158;12035.759284814254;12155.209911355734;12159.331236782062;12279.017678585871;12285.508579656713;240037;240114;242057;242541;242943;43175.35236971448;43738.46915357947;46158.538414616094;46357.536424636;46406;1301.8242537257538;1303.6414985878769;1425.9732992245567;1428.7641190370255;1430.6065831896312;27111.322216944733;27550.862245688644;27625;27725.584116237867;27841.44317113687;35616.287674245854;35701.82149089238;35822.8208858954;35942.8202858984;36047.81976090103;186379;187351;187881;187893;187895;32158.67841321557;32922.17694557655;33061.17347066342;33347;33354.33291334111;41952.790236048626;41970;42084.57915420806;42360.78819605882;43022.784886075366;30457.390852183275;30519;30932.69067309298;31033.68966310398;31276.218094547818;29971.400571988874;30204.24489387783;30408.695913041473;30461.847690761406;30585.388292234475;33079;33808.15479613029;34007.149821253675;34353.31293374168;34479.13802154966;30032;30157.246068848453;30440.543391848696;30618.693813061585;30720.539191912587;22671.43321416912;23142.305730828102;23250.65124023175;23329.76670233276;23599 21:57:02 INFO - PID 8620 | |2;jquery.html;564.9491545760883;568.5991376079864;575.2722805650855;600.1058422949804;611.7919907231542;22076.668849967264;22765.772342276574;22788.31635050947;22846.31461056167;22919.312420627368;23349.533009339804;24024.75975240247;24447.26658200257;24497.632535511937;24731.876340618317;6479.287278399361;6633.170853643294;6635.43598794103;6675.165604299463;6687.498437617198;6546.672666366662;6620.841352763271;6663.167104111986;6705.530612857102;6709.396154346108;6308.4322410983;6627.966860165717;6637.303083176269;6640.7343706251695;6648.434883034948;6603.504737144734;6608.2730899601;6655.3344665533305;6658.10115634388;6667.099941507907;5917.822465326047;6149.3235744068115;6177.81466556004;6200.3179650238435;6211.068339749074;27024.729752702613;27144.321391965357;27300.72699273002;27391.315217119532;27737.58393624097;1976.980230197694;2029.6042271757012;2029.8680585761879;2033.2070492507933;2033.4712974626555;18229.361972330906;18768.061596920237;19093.809061909342;19122;19126.23495060196;2417.504411595636;2462.22439931421;2474.3319303788057;2481.3796550862285;2487.8507289562685;123168.38415807864;128834;136288.31855840757;149013;155696.99999999886;20606.690899636516;20921.686174707393;21459.78540214609;21711.02300396486;21930.84625113097;41302.79348603238;41547.7922610385;41552.506849589125;41679.374809377885;41919;10763.569457221623;11014.339139651647;11158.218924675195;11181.273217240854;11220.66338009861;8976.730698079067;9055.411398259092;9119.99360140768;9120.224780893566;9146.451212927246;11210.775784484265;11415.48630311638;11434.656960291204;11548.595799147157;11981.161318707607;4060.573639767782;4301.290287102593;4333.501647310537;4428.490723566831;4490.595846373842;959.8608201810698;972.8394814855611;974.444566597047;975.059067999384;986.9851952220722;18178.90910545465;18304.908475457803;18459.538511537317;18640.16119274636;18668.81331186698;1032.6282538286212;1033.452270296744;1035.5236591167995;1036.8341065429495;1039.1686650679426;10164.237682173867;10208.948955255324;10273.948630256951;10373.170146388271;10390.116840068527;466519;472135;474361;478564;492985;120335;121372;121504.39247803704;123744;125534;1160.785254727878;1174.0959461214816;1222.0712258683286;1239.1821397877538;1239.231676360662;19411;19554.413367598994;19689.901550492155;19769.308074217333;19776.90111549433;60799.87200447963;61445.692771535854;62379.37620623736;62386.376136238054;63178.684106579174;75100.24899750932;75665.93310297582;76051.23948760441;76360.61819690866;77034.6148269255;14038.438462461387;14220.715585688022;14434.711305774035;14442.566722998326;14544.85455145435;21593.676094858274;21668.241611544152;21781.23765668194;21883;21930.671039934732;17405;17463.825361746727;17541.210645521238;17582.120893954994;17622;14694.485693000694;14718.26408679583;14896.702065958403;15230.77153842671;15594.064356138668;16116.435924742578;17881.46355609334;18063.909680451514;18064.548386290448;18141.183646735666;14244.216568088854;14246.715065698836;14259.572212833631;14364;14391.424343026161;17474.91262543679;17498.82501174972;17519.82480175182;17651.205695743978;17655.735163972295 21:57:02 INFO - PID 8620 | |3;mootools.html;1315.4869600855666;1373.6771858613224;1394.1147371419145;1396.197186617694;1398.6433459467835;15031.849681503196;15482.225888705576;15537.611559711013;15643;15659.999999999985;15112.546623601282;15207.46773862915;15230.543083707507;15282;15318.923405382957;2717.184844546633;2817.1407720645193;2840.8295502269825;2848.387596666716;2848.971510284902;2750.9587356189572;2770.9584356234573;2785.679646840619;2791.1626512046405;2802.579613058037;2616.5682662360687;2639.6304517367585;2671.3855813162986;2676.598510223472;2680.1021657744677;2591.8185726999022;2599.4151315953854;2601.2846467221475;2617;2618.9476210475873;2590.080521414908;2596.974030259711;2603.9869800650877;2630.118910165097;2633.9069286246186;14950.40198392063;15001.3249403776;15060.548183554401;15162.31769570372;15168.696626067527;7883.684652613889;8119.837603247902;8164.101948785629;8165.67337306507;8167.305779008791;5248.9737551312;5456.590755693299;5497.7570867575905;5563.5271001964475;5724.742386592572;1903.3148066695978;1917.1181256621971;1920.9615807683908;1952.5704345044066;1969.6946973219174;23166.073357065696;23861;25636.87181564099;27235.1829445115;27256.863715681295;7069.752558660422;7118.28817118284;7160.498765086394;7397.741079062207;7447;20317;20329.18683252683;20442.591148176954;20543.178272869216;20617.381478555668;8029.518228906284;8163;8165;8189.262966333059;8227.54748488827;7212.8918066228;7398.297161769623;7484.251574842472;7563.130240022468;7587.58268295238;5496.890062198733;5615.775368985276;5706.686132262766;5754.223179870748;5756.424357564294;1760.2079064421062;1798.9550261243573;1830.6796310645584;1849.306510058728;1865.3957596467028;863.7192912303401;866.9393142480092;872.9301655867515;889.2663552569156;895.6283142495921;7146.749863754743;7395;7460.216677248919;7481.8877716834295;7568.470207085561;703.9876082293997;704.084689903124;734.5812886654662;738.0405472885335;742.3652776875801;4520.276755719069;4563.748993805372;4575.176468235683;4583.175028494837;4600.907981840412;20884.37346879596;21011.36965891026;22221.666674999888;22279.331620051424;22315.665265021038;21059.68410473844;21220.151193952068;21802.454938626342;21949.451263718536;22117.778822211894;11233.326000439836;11260.887391125983;11271.09831213501;11272.549098035986;11281.435928203557;8180.222878826502;8273.172682731678;8274.627641756257;8383.371247156365;8411.495310281403;18211.271549137888;18314.358997435025;19069.427917162506;19328.226870925006;19508.21967121299;19066;19357.90321048386;19388.61222775565;19473.123709432824;20014.49963750918;12504.687382815502;12580.87419125797;12643.304618246084;12693.682657933625;12698.555550555686;13895;14097.859021409655;14157.504487343305;14183;14272.429102835771;11589.536418543164;11888.821667675165;11919.404029798648;12017.63947081589;12211.816822747844;11202.551897923991;11413.828792567952;11483.25358851672;11501.712457188638;11527;10187.471879218176;10193.337433066827;10255.538500767632;10330.793384132125;10366;7428.851422971402;7439.40484761207;7459.142198647225;7495.475316727777;7592.240775922363;7442.1069471661585;7454.291842274975;7530.94566760643;7645.923540764521;7691.730789422343 21:57:02 INFO - PID 8620 | |4;prototype.html;508.9847304580864;526.2816255810818;540.7377421950355;548.1175307754514;548.934127904651;6220.253569571646;6244.157038799766;6249.343818899013;6260.966940454831;6275.435210831024;6080.513558915287;6118.571699981003;6119.602225855328;6133.447989680928;6166.814995550129;5146.6140039497095;5148.639595228336;5191.351081114861;5210.973945130269;5214.843554693365;5082.771275292621;5155.123628983082;5163.457836927125;5195.896082078356;5205.297284866551;5024.422191447994;5038.765502451886;5049.242613607951;5064.974675126619;5079.6190285728535;4961.206207006898;5050.4697006814495;5104.591632669396;5119.334486516747;5132.666376685504;4630.124799456225;4648.651351148677;4651.441826980784;4658.161530924433;4701.553352431513;5913.231279933581;6008.519318454513;6026.879462410771;6052.521134360585;6068.6358818471035;5809.796657117023;5831.5334773218465;5857.824265272048;5868.828533442654;5884.881442680631;6466.708998095097;6495.382938620824;6524.217093948709;6555.3772391622715;6562.409383155539;4881.633877459205;4999.975000125012;5010.624203184776;5144.948550514522;5166.276721259027;6480.546361754679;6548.639824809681;6769.72921083161;6778.525503214777;6808.6936087875665;5255.211718242294;5278.5689301954035;5317.7872885084935;5326.201069839555;5341.732913354317;5437;5461.726913654381;5465.415029641439;5484.7531861066345;5493.615446918678;5853.385394533512;5903.3801450847905;5925.970370148208;5935.792247271324;5940.762369505258;6540.149780528501;6541.967290163519;6592.868142637216;6602.1610707396285;6631.635260060646;1873.597176607036;1882.4917272336393;1897.535103899526;1901.7812951510477;1901.8098190180872;1340.162398500937;1405.7539930512119;1412.180935057664;1414.8115582910405;1425.5438259756986;5533.170024496278;5565.415631358649;5668.348139963957;5688.601797874109;5696.458836410535;6432.935670643234;6490.156279683611;6542.953127499577;6576.342365763481;6622.668866556653;5611;5638.915416268759;5665.291838520185;5672.092465205547;5678.4037676044245;3487.267673788482;3498.3003399320237;3501.1772233525103;3521.876734314338;3538.5046093546925;6368.319285325282;6382.521310901701;6407.551471396958;6427.357264273535;6470.4823614110755;6737.225219099768;6762.932370676329;6779.864402711918;6845;6870.141232345957;6300.1494798202575;6319.894018546736;6369.2484566744515;6378.77674281398;6380.680965951684;6570.737170513126;6599.934000660124;6601.636909969998;6615.338466153346;6696.966515167393;6516.588862121045;6521.64130972801;6559.672016399257;6567.916293811659;6607.702653380706;6361.90457143134;6363.204599425072;6386.43532334581;6409.4872410206135;6447.550560450137;5251.396089449762;5265.21021846726;5281.841544753664;5322.09524380864;5357.892842143213;6266.467350275278;6490.448311893353;6508.316626754123;6568.868622627616;6589.4069533742195;6160.952638051453;6339.334369891095;6429.98796125624;6449.258335291502;6465.418112369721;6355.014972679242;6403.199600049994;6404.199475065617;6430.070978706276;6471.543902622061;6217.160683307696;6218.875622487615;6242.157308763441;6343.746250149942;6381.680915954276;6243.469305109116;6244.782267457802;6351.68241587928;6377.96811015942;6382.170317858649;6118.204633397808;6171.537134714825;6238.344973777688;6241.78153764616;6246.437820596168 21:57:02 INFO - PID 8620 | |5;yui.html;917.7338571814174;924.7503174142981;938.7653086728318;944.2446043165465;952.1002652493393;19312.034398280084;20227.292044778435;20330.491737706565;20337.186512539498;20364.59270814583;17207.397741079065;17431.302747890102;17551.91224043881;19399.709004364944;19535;953.7997020625677;991.5984026469291;996.8803743550784;997;998.0518507417946;993.1558175550796;1000.3597697473587;1001.6344034427418;1002.0931057393035;1003.9899601004006;986.5905649155628;1032.6798692405341;1036.5750042482584;1041.8437234414823;1048.4024106259435;1020.6886899495651;1021.6475316015963;1022.4529876516028;1023.5291765787747;1029.2075102171323;712.9536580122275;732.706917233111;744.9031625888654;745.8433728916934;748.4947660329256;19971.50071248216;20272;20278.087486063163;20310.390688279374;20373.592528149355;21186.470338241514;21251.46871328229;21963.780362196492;21975.011124499277;22050.88974555117;17232.48302550913;17583.384581539714;17601;17732.468025959242;17915.73126403105;3825.3114439400897;3838.5201849768778;3863.323918314312;3864.0726225705907;3868.400397938324;132130.3393483036;134816;136852;136948;138143.99999999898;16632.750508742378;16664.16679166037;16713.331466741194;16855.410060647817;16901.577460563585;30024.399512009637;30317;30456.238594035327;30790.846045769627;31301.373972520876;8256;8310.667573297;8382.87425688615;8393.328533717287;8417.452865563719;4346.304591265382;4522.66080043998;4525.683202175882;4527.026689261825;4529.479109902338;5345.545628621533;5535.640183388067;5541.473560011793;5569.470900264469;5612.971935140298;978.3347323819728;1008.3647302199714;1028.3521381529738;1031.4275577054657;1031.63377001165;659.61412573644;669.6584741781629;680.0784936411092;687.1994126842272;699.7900629811037;11867.703307417383;12467.812982805266;12496.625101246977;12528.185667931555;13444.731105378034;676.0394854920769;676.2088356622763;682.1950098883266;684.1004079635263;688.8209065642969;5712.6286791358425;5779.190913272145;5781.959247335436;5810;5837.795677151279;118253.40873295578;120097.39951300362;120187;121843.39078304551;124125.37937310255;101112.49443752882;101502.49248753709;103860.48069759602;103987;104130.4793476043;91279.54360228157;91619.54190229006;91869;93470.53264733632;93613;10146.188304935735;10312.690619281433;10459.006394392825;10473.162147028068;10828.5127169276;42662.57337426586;43447.348289775044;43603.781981089895;43693.126137476436;43837.56162438335;55482.44517554773;55944.160837586656;56467.43532564622;56811.71594142003;56852;14801.481948131765;15135.772963405338;15205.695886081996;15267.3893044277;15311.46409875649;16252.593685157493;16317.836821632107;16528.504144875675;16647.833521665114;16726.749098763285;14945.103293802407;15059.1717455541;15249.31378088011;15313.923430382776;15329.46346877854;14927.552173434815;14997.475088371855;15134.091954482767;15261.6184595386;15279.083255004734;15003.549893503212;15243.695126097195;15256.694866102394;15417;15525.922370388076;11970.461329240032;11999.460024298753;12016.399180041139;12132.636020919386;12202.81695774582;11267.605633802777;11282.661520154408;11338.206325557132;11477.655670329903;11496.425178740863 21:57:02 INFO - PID 8620 | __end_tp_report 21:57:02 INFO - PID 8620 | __start_cc_report 21:57:02 INFO - PID 8620 | _x_x_mozilla_cycle_collect,292 21:57:02 INFO - PID 8620 | __end_cc_report 21:57:02 INFO - PID 8620 | __startTimestamp1506142622024__endTimestamp 21:57:02 INFO - PID 8620 | 21:57:02 INFO - PID 8620 | ------- Summary: start ------- 21:57:02 INFO - PID 8620 | Number of tests: 6 21:57:02 INFO - PID 8620 | 21:57:02 INFO - PID 8620 | [#0] dojo.html Cycles:165 Average:6123.27 Median:6361.20 stddev:865.23 (13.6%) stddev-sans-first:835.33 21:57:02 INFO - PID 8620 | Values: 3126.0 3135.1 3165.8 3189.2 3191.2 6172.5 6240.6 6279.3 6358.9 6360.3 6207.9 6231.0 6266.5 6299.7 6302.8 5639.6 5766.2 5788.4 5798.7 5805.2 5758.4 5791.7 5812.6 5878.9 5916.2 5795.2 5819.5 5821.6 5833.6 5848.5 86.1 5669.6 5709.3 5746.9 5850.7 5731.6 5745.5 5755.9 5809.5 5830.3 6209.5 6238.0 6248.3 6296.2 6344.9 6643.0 6665.8 6675.9 6695.3 6738.5 6702.3 6747.7 6758.6 6816.1 6830.2 6045.7 6145.4 6152.2 6158.7 6294.2 6769.6 6811.8 6828.7 6853.6 6878.7 6515.7 6520.3 6526.1 6531.7 6684.0 6640.4 6717.3 6724.4 6739.5 6742.6 6568.1 6622.3 6713.5 6714.1 6714.3 6514.7 6600.9 6624.7 6638.9 6721.6 5231.3 5231.7 5247.1 5250.0 5260.3 4553.2 4620.6 4626.7 4628.4 4675.7 5722.5 5743.5 5786.0 5814.1 5820.9 6580.1 6665.2 6677.4 6711.6 6747.4 5732.9 5839.8 5861.2 5875.3 5877.9 5774.8 5801.1 5833.3 5850.5 5855.6 6486.2 6493.6 6582.1 6589.0 6600.1 6692.7 6710.9 6800.9 6803.5 6806.1 6325.7 6377.2 6405.7 6472.2 6473.2 6442.9 6508.9 6556.8 6570.6 6644.5 6749.2 6758.5 6767.3 6783.2 6794.9 6457.5 6461.5 6474.1 6555.1 6570.1 6337.2 6449.6 6473.3 6536.9 6555.8 6271.1 6323.8 6337.0 6337.3 6352.7 6509.1 6604.1 6604.3 6612.0 6637.4 6327.2 6361.2 6397.1 6418.3 6422.6 21:57:02 INFO - PID 8620 | 21:57:02 INFO - PID 8620 | [#1] ext.html Cycles:180 Average:36845.45 Median:33907.65 stddev:53604.33 (158.1%) stddev-sans-first:53686.65 21:57:02 INFO - PID 8620 | Values: 870.9 895.6 911.5 916.7 922.1 39114.6 39725.2 40311.6 40338.4 40384.4 36712.3 36767.6 37652.0 39379.4 39617.2 7859.8 7932.4 7958.4 7968.7 8000.2 7880.8 7897.1 7982.1 7984.2 8017.4 7952.4 7973.0 8063.5 8087.0 8095.3 7965.7 7981.8 8010.2 8014.5 8018.3 7820.4 7868.8 7869.2 7895.2 7900.4 36448.1 36483.0 36731.6 36823.1 36851.6 3497.9 3515.5 3522.0 3523.6 3530.2 30402.8 30469.0 30496.2 30563.8 30627.4 4937.8 5123.9 5170.4 5190.2 5197.1 186331.0 186338.0 186614.0 188291.0 189161.1 46853.0 47077.0 47229.5 47405.3 47450.0 48732.0 48811.3 49132.3 50693.2 52735.5 14713.9 14750.9 14801.6 14836.4 15019.2 12082.2 12108.2 12108.7 12170.5 12201.3 13896.9 14016.4 14268.1 14310.2 14451.6 5609.1 5689.1 5758.2 5795.7 5852.9 993.4 1001.1 1012.0 1012.4 1022.8 22602.5 23155.8 23263.9 23381.2 23739.2 1088.9 1111.9 1124.3 1129.6 1132.7 12035.8 12155.2 12159.3 12279.0 12285.5 240037.0 240114.0 242057.0 242541.0 242943.0 43175.4 43738.5 46158.5 46357.5 46406.0 1301.8 1303.6 1426.0 1428.8 1430.6 27111.3 27550.9 27625.0 27725.6 27841.4 35616.3 35701.8 35822.8 35942.8 36047.8 186379.0 187351.0 187881.0 187893.0 187895.0 32158.7 32922.2 33061.2 33347.0 33354.3 41952.8 41970.0 42084.6 42360.8 43022.8 30457.4 30519.0 30932.7 31033.7 31276.2 29971.4 30204.2 30408.7 30461.8 30585.4 33079.0 33808.2 34007.1 34353.3 34479.1 30032.0 30157.2 30440.5 30618.7 30720.5 22671.4 23142.3 23250.7 23329.8 23599.0 21:57:02 INFO - PID 8620 | 21:57:02 INFO - PID 8620 | [#2] jquery.html Cycles:180 Average:35431.43 Median:21746.13 stddev:80877.37 (371.9%) stddev-sans-first:81061.89 21:57:02 INFO - PID 8620 | Values: 564.9 568.6 575.3 600.1 611.8 22076.7 22765.8 22788.3 22846.3 22919.3 23349.5 24024.8 24447.3 24497.6 24731.9 6479.3 6633.2 6635.4 6675.2 6687.5 6546.7 6620.8 6663.2 6705.5 6709.4 6308.4 6628.0 6637.3 6640.7 6648.4 6603.5 6608.3 6655.3 6658.1 6667.1 5917.8 6149.3 6177.8 6200.3 6211.1 27024.7 27144.3 27300.7 27391.3 27737.6 1977.0 2029.6 2029.9 2033.2 2033.5 18229.4 18768.1 19093.8 19122.0 19126.2 2417.5 2462.2 2474.3 2481.4 2487.9 123168.4 128834.0 136288.3 149013.0 155697.0 20606.7 20921.7 21459.8 21711.0 21930.8 41302.8 41547.8 41552.5 41679.4 41919.0 10763.6 11014.3 11158.2 11181.3 11220.7 8976.7 9055.4 9120.0 9120.2 9146.5 11210.8 11415.5 11434.7 11548.6 11981.2 4060.6 4301.3 4333.5 4428.5 4490.6 959.9 972.8 974.4 975.1 987.0 18178.9 18304.9 18459.5 18640.2 18668.8 1032.6 1033.5 1035.5 1036.8 1039.2 10164.2 10208.9 10273.9 10373.2 10390.1 466519.0 472135.0 474361.0 478564.0 492985.0 120335.0 121372.0 121504.4 123744.0 125534.0 1160.8 1174.1 1222.1 1239.2 1239.2 19411.0 19554.4 19689.9 19769.3 19776.9 60799.9 61445.7 62379.4 62386.4 63178.7 75100.2 75665.9 76051.2 76360.6 77034.6 14038.4 14220.7 14434.7 14442.6 14544.9 21593.7 21668.2 21781.2 21883.0 21930.7 17405.0 17463.8 17541.2 17582.1 17622.0 14694.5 14718.3 14896.7 15230.8 15594.1 16116.4 17881.5 18063.9 18064.5 18141.2 14244.2 14246.7 14259.6 14364.0 14391.4 17474.9 17498.8 17519.8 17651.2 17655.7 21:57:02 INFO - PID 8620 | 21:57:02 INFO - PID 8620 | [#3] mootools.html Cycles:180 Average:9517.32 Median:2598.19 stddev:6795.22 (261.5%) stddev-sans-first:6786.33 21:57:02 INFO - PID 8620 | Values: 1315.5 1373.7 1394.1 1396.2 1398.6 15031.8 15482.2 15537.6 15643.0 15660.0 15112.5 15207.5 15230.5 15282.0 15318.9 2717.2 2817.1 2840.8 2848.4 2849.0 2751.0 2771.0 2785.7 2791.2 2802.6 2616.6 2639.6 2671.4 2676.6 2680.1 2591.8 2599.4 2601.3 2617.0 2618.9 2590.1 2597.0 2604.0 2630.1 2633.9 14950.4 15001.3 15060.5 15162.3 15168.7 7883.7 8119.8 8164.1 8165.7 8167.3 5249.0 5456.6 5497.8 5563.5 5724.7 1903.3 1917.1 1921.0 1952.6 1969.7 23166.1 23861.0 25636.9 27235.2 27256.9 7069.8 7118.3 7160.5 7397.7 7447.0 20317.0 20329.2 20442.6 20543.2 20617.4 8029.5 8163.0 8165.0 8189.3 8227.5 7212.9 7398.3 7484.3 7563.1 7587.6 5496.9 5615.8 5706.7 5754.2 5756.4 1760.2 1799.0 1830.7 1849.3 1865.4 863.7 866.9 872.9 889.3 895.6 7146.7 7395.0 7460.2 7481.9 7568.5 704.0 704.1 734.6 738.0 742.4 4520.3 4563.7 4575.2 4583.2 4600.9 20884.4 21011.4 22221.7 22279.3 22315.7 21059.7 21220.2 21802.5 21949.5 22117.8 11233.3 11260.9 11271.1 11272.5 11281.4 8180.2 8273.2 8274.6 8383.4 8411.5 18211.3 18314.4 19069.4 19328.2 19508.2 19066.0 19357.9 19388.6 19473.1 20014.5 12504.7 12580.9 12643.3 12693.7 12698.6 13895.0 14097.9 14157.5 14183.0 14272.4 11589.5 11888.8 11919.4 12017.6 12211.8 11202.6 11413.8 11483.3 11501.7 11527.0 10187.5 10193.3 10255.5 10330.8 10366.0 7428.9 7439.4 7459.1 7495.5 7592.2 7442.1 7454.3 7530.9 7645.9 7691.7 21:57:02 INFO - PID 8620 | 21:57:02 INFO - PID 8620 | [#4] prototype.html Cycles:180 Average:5514.23 Median:6099.36 stddev:1467.51 (24.1%) stddev-sans-first:1422.73 21:57:02 INFO - PID 8620 | Values: 509.0 526.3 540.7 548.1 548.9 6220.3 6244.2 6249.3 6261.0 6275.4 6080.5 6118.6 6119.6 6133.4 6166.8 5146.6 5148.6 5191.4 5211.0 5214.8 5082.8 5155.1 5163.5 5195.9 5205.3 5024.4 5038.8 5049.2 5065.0 5079.6 4961.2 5050.5 5104.6 5119.3 5132.7 4630.1 4648.7 4651.4 4658.2 4701.6 5913.2 6008.5 6026.9 6052.5 6068.6 5809.8 5831.5 5857.8 5868.8 5884.9 6466.7 6495.4 6524.2 6555.4 6562.4 4881.6 5000.0 5010.6 5144.9 5166.3 6480.5 6548.6 6769.7 6778.5 6808.7 5255.2 5278.6 5317.8 5326.2 5341.7 5437.0 5461.7 5465.4 5484.8 5493.6 5853.4 5903.4 5926.0 5935.8 5940.8 6540.1 6542.0 6592.9 6602.2 6631.6 1873.6 1882.5 1897.5 1901.8 1901.8 1340.2 1405.8 1412.2 1414.8 1425.5 5533.2 5565.4 5668.3 5688.6 5696.5 6432.9 6490.2 6543.0 6576.3 6622.7 5611.0 5638.9 5665.3 5672.1 5678.4 3487.3 3498.3 3501.2 3521.9 3538.5 6368.3 6382.5 6407.6 6427.4 6470.5 6737.2 6762.9 6779.9 6845.0 6870.1 6300.1 6319.9 6369.2 6378.8 6380.7 6570.7 6599.9 6601.6 6615.3 6697.0 6516.6 6521.6 6559.7 6567.9 6607.7 6361.9 6363.2 6386.4 6409.5 6447.6 5251.4 5265.2 5281.8 5322.1 5357.9 6266.5 6490.4 6508.3 6568.9 6589.4 6161.0 6339.3 6430.0 6449.3 6465.4 6355.0 6403.2 6404.2 6430.1 6471.5 6217.2 6218.9 6242.2 6343.7 6381.7 6243.5 6244.8 6351.7 6378.0 6382.2 6118.2 6171.5 6238.3 6241.8 6246.4 21:57:02 INFO - PID 8620 | 21:57:02 INFO - PID 8620 | [#5] yui.html Cycles:180 Average:23797.16 Median:19355.87 stddev:34218.12 (176.8%) stddev-sans-first:34270.99 21:57:02 INFO - PID 8620 | Values: 917.7 924.8 938.8 944.2 952.1 19312.0 20227.3 20330.5 20337.2 20364.6 17207.4 17431.3 17551.9 19399.7 19535.0 953.8 991.6 996.9 997.0 998.1 993.2 1000.4 1001.6 1002.1 1004.0 986.6 1032.7 1036.6 1041.8 1048.4 1020.7 1021.6 1022.5 1023.5 1029.2 713.0 732.7 744.9 745.8 748.5 19971.5 20272.0 20278.1 20310.4 20373.6 21186.5 21251.5 21963.8 21975.0 22050.9 17232.5 17583.4 17601.0 17732.5 17915.7 3825.3 3838.5 3863.3 3864.1 3868.4 132130.3 134816.0 136852.0 136948.0 138144.0 16632.8 16664.2 16713.3 16855.4 16901.6 30024.4 30317.0 30456.2 30790.8 31301.4 8256.0 8310.7 8382.9 8393.3 8417.5 4346.3 4522.7 4525.7 4527.0 4529.5 5345.5 5535.6 5541.5 5569.5 5613.0 978.3 1008.4 1028.4 1031.4 1031.6 659.6 669.7 680.1 687.2 699.8 11867.7 12467.8 12496.6 12528.2 13444.7 676.0 676.2 682.2 684.1 688.8 5712.6 5779.2 5782.0 5810.0 5837.8 118253.4 120097.4 120187.0 121843.4 124125.4 101112.5 101502.5 103860.5 103987.0 104130.5 91279.5 91619.5 91869.0 93470.5 93613.0 10146.2 10312.7 10459.0 10473.2 10828.5 42662.6 43447.3 43603.8 43693.1 43837.6 55482.4 55944.2 56467.4 56811.7 56852.0 14801.5 15135.8 15205.7 15267.4 15311.5 16252.6 16317.8 16528.5 16647.8 16726.7 14945.1 15059.2 15249.3 15313.9 15329.5 14927.6 14997.5 15134.1 15261.6 15279.1 15003.5 15243.7 15256.7 15417.0 15525.9 11970.5 11999.5 12016.4 12132.6 12202.8 11267.6 11282.7 11338.2 11477.7 11496.4 21:57:02 INFO - PID 8620 | -------- Summary: end -------- 21:57:02 INFO - PID 8620 | 21:57:02 INFO - TEST-INFO | 8620: exit 0 21:57:02 INFO - TEST-OK | dromaeo_css | took 1112691ms 21:57:02 INFO - TEST-START | kraken 21:57:02 INFO - Initialising browser for kraken test... 21:57:02 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:50810/getInfo.html -profile /tmp/tmpmPDr9D/profile 21:57:02 INFO - TEST-INFO | started process 9001 (/builds/slave/test/build/application/firefox/firefox http://localhost:50810/getInfo.html) 21:57:08 INFO - TEST-INFO | 9001: exit 0 21:57:08 INFO - Browser initialized. 21:57:08 INFO - Running cycle 1/1 for kraken test... 21:57:08 INFO - Using env: {'DISPLAY': ':0', 21:57:08 INFO - 'HOME': '/home/cltbld', 21:57:08 INFO - 'JSGC_DISABLE_POISONING': '1', 21:57:08 INFO - 'LANG': 'en_US.UTF-8', 21:57:08 INFO - 'LANGUAGE': 'en_US:en', 21:57:08 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 21:57:08 INFO - 'LOGNAME': 'cltbld', 21:57:08 INFO - 'MAIL': '/var/mail/cltbld', 21:57:08 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 21:57:08 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 21:57:08 INFO - 'MOZ_ACCELERATED': '1', 21:57:08 INFO - 'MOZ_CRASHREPORTER': '1', 21:57:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:57:08 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 21:57:08 INFO - 'MOZ_NO_REMOTE': '1', 21:57:08 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 21:57:08 INFO - 'MOZ_WEBRENDER': '1', 21:57:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:57:08 INFO - 'NO_EM_RESTART': '1', 21:57:08 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:57:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:57:08 INFO - 'PWD': '/builds/slave/test', 21:57:08 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 21:57:08 INFO - 'RUST_BACKTRACE': 'full', 21:57:08 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 21:57:08 INFO - 'SHELL': '/bin/bash', 21:57:08 INFO - 'SHLVL': '1', 21:57:08 INFO - 'TERM': 'linux', 21:57:08 INFO - 'TMOUT': '86400', 21:57:08 INFO - 'USER': 'cltbld', 21:57:08 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1506134377.817830-1745771307', 21:57:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:57:08 INFO - '_': '/tools/buildbot/bin/python'} 21:57:08 INFO - TEST-INFO | started process 9247 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpmPDr9D/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 21:57:09 INFO - PID 9247 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 21:57:09 INFO - PID 9247 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 21:57:09 INFO - PID 9247 | 21:57:09 INFO - PID 9247 | (/builds/slave/test/build/application/firefox/firefox:9307): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 21:57:09 INFO - PID 9247 | 21:57:20 INFO - PID 9247 | RSS: Main: 198361088 21:57:20 INFO - PID 9247 | 21:57:22 INFO - PID 9247 | Cycle 1(1): loaded http://localhost:50810/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:50810/tests/kraken/driver.html?testName=audio-beat-detection) 21:57:23 INFO - PID 9247 | RSS: Main: 196202496 21:57:23 INFO - PID 9247 | 21:57:26 INFO - PID 9247 | Cycle 1(1): loaded http://localhost:50810/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:50810/tests/kraken/driver.html?testName=audio-dft) 21:57:27 INFO - PID 9247 | RSS: Main: 197074944 21:57:27 INFO - PID 9247 | 21:57:30 INFO - PID 9247 | Cycle 1(1): loaded http://localhost:50810/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:50810/tests/kraken/driver.html?testName=audio-fft) 21:57:30 INFO - PID 9247 | RSS: Main: 194805760 21:57:30 INFO - PID 9247 | 21:57:32 INFO - PID 9247 | Cycle 1(1): loaded http://localhost:50810/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:50810/tests/kraken/driver.html?testName=audio-oscillator) 21:57:33 INFO - PID 9247 | RSS: Main: 193359872 21:57:33 INFO - PID 9247 | 21:57:35 INFO - PID 9247 | Cycle 1(1): loaded http://localhost:50810/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:50810/tests/kraken/driver.html?testName=imaging-gaussian-blur) 21:57:36 INFO - PID 9247 | RSS: Main: 195330048 21:57:36 INFO - PID 9247 | 21:57:39 INFO - PID 9247 | Cycle 1(1): loaded http://localhost:50810/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:50810/tests/kraken/driver.html?testName=imaging-darkroom) 21:57:40 INFO - PID 9247 | RSS: Main: 198373376 21:57:40 INFO - PID 9247 | 21:57:43 INFO - PID 9247 | Cycle 1(1): loaded http://localhost:50810/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:50810/tests/kraken/driver.html?testName=imaging-desaturate) 21:57:44 INFO - PID 9247 | RSS: Main: 197505024 21:57:44 INFO - PID 9247 | 21:57:47 INFO - PID 9247 | Cycle 1(1): loaded http://localhost:50810/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:50810/tests/kraken/driver.html?testName=json-parse-financial) 21:57:48 INFO - PID 9247 | RSS: Main: 197627904 21:57:48 INFO - PID 9247 | 21:57:49 INFO - PID 9247 | Cycle 1(1): loaded http://localhost:50810/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:50810/tests/kraken/driver.html?testName=json-stringify-tinderbox) 21:57:50 INFO - PID 9247 | RSS: Main: 197296128 21:57:50 INFO - PID 9247 | 21:57:52 INFO - PID 9247 | Cycle 1(1): loaded http://localhost:50810/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:50810/tests/kraken/driver.html?testName=stanford-crypto-aes) 21:57:52 INFO - PID 9247 | RSS: Main: 194060288 21:57:52 INFO - PID 9247 | 21:57:54 INFO - PID 9247 | Cycle 1(1): loaded http://localhost:50810/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:50810/tests/kraken/driver.html?testName=stanford-crypto-ccm) 21:57:55 INFO - PID 9247 | RSS: Main: 193892352 21:57:55 INFO - PID 9247 | 21:57:58 INFO - PID 9247 | Cycle 1(1): loaded http://localhost:50810/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:50810/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 21:57:58 INFO - PID 9247 | RSS: Main: 194879488 21:57:58 INFO - PID 9247 | 21:58:01 INFO - PID 9247 | Cycle 1(1): loaded http://localhost:50810/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:50810/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 21:58:02 INFO - PID 9247 | RSS: Main: 194539520 21:58:02 INFO - PID 9247 | 21:58:04 INFO - PID 9247 | Cycle 1(1): loaded http://localhost:50810/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:50810/tests/kraken/driver.html?testName=ai-astar) 21:58:04 INFO - PID 9247 | RSS: Main: 197320704 21:58:04 INFO - PID 9247 | 21:58:04 INFO - PID 9247 | __start_tp_report 21:58:04 INFO - PID 9247 | _x_x_mozilla_page_load 21:58:04 INFO - PID 9247 | _x_x_mozilla_page_load_details 21:58:04 INFO - PID 9247 | |i|pagename|runs| 21:58:04 INFO - PID 9247 | |0;ai-astar;114;107;108;114;119;107;107;109;107;117 21:58:04 INFO - PID 9247 | |1;audio-beat-detection;118;116;118;115;127;141;124;125;117;119 21:58:04 INFO - PID 9247 | |2;audio-dft;164;168;162;162;166;167;163;165;163;166 21:58:04 INFO - PID 9247 | |3;audio-fft;78;78;80;76;74;81;78;75;84;76 21:58:04 INFO - PID 9247 | |4;audio-oscillator;79;81;79;81;82;78;80;81;84;80 21:58:04 INFO - PID 9247 | |5;imaging-gaussian-blur;118;111;115;110;115;113;114;115;124;114 21:58:04 INFO - PID 9247 | |6;imaging-darkroom;148;149;148;149;157;151;147;148;149;150 21:58:04 INFO - PID 9247 | |7;imaging-desaturate;95;95;100;94;99;96;95;98;103;105 21:58:04 INFO - PID 9247 | |8;json-parse-financial;53;66;51;58;51;58;53;54;55;54 21:58:04 INFO - PID 9247 | |9;json-stringify-tinderbox;38;40;49;37;38;43;38;44;37;38 21:58:04 INFO - PID 9247 | |10;stanford-crypto-aes;79;79;82;75;81;78;80;77;74;82 21:58:04 INFO - PID 9247 | |11;stanford-crypto-ccm;121;113;112;122;117;115;114;117;114;115 21:58:04 INFO - PID 9247 | |12;stanford-crypto-pbkdf2;172;172;172;171;173;168;167;170;176;175 21:58:04 INFO - PID 9247 | |13;stanford-crypto-sha256-iterative;69;64;68;64;64;75;65;63;73;63 21:58:04 INFO - PID 9247 | __end_tp_report 21:58:04 INFO - PID 9247 | __start_cc_report 21:58:04 INFO - PID 9247 | _x_x_mozilla_cycle_collect,746 21:58:04 INFO - PID 9247 | __end_cc_report 21:58:04 INFO - PID 9247 | __startTimestamp1506142684377__endTimestamp 21:58:04 INFO - PID 9247 | 21:58:04 INFO - PID 9247 | ------- Summary: start ------- 21:58:04 INFO - PID 9247 | Number of tests: 14 21:58:04 INFO - PID 9247 | 21:58:04 INFO - PID 9247 | [#0] ai-astar Cycles:10 Average:110.90 Median:111.50 stddev:4.65 (4.2%) stddev-sans-first:4.80 21:58:04 INFO - PID 9247 | Values: 114.0 107.0 108.0 114.0 119.0 107.0 107.0 109.0 107.0 117.0 21:58:04 INFO - PID 9247 | 21:58:04 INFO - PID 9247 | [#1] audio-beat-detection Cycles:10 Average:122.00 Median:121.50 stddev:7.82 (6.4%) stddev-sans-first:8.16 21:58:04 INFO - PID 9247 | Values: 118.0 116.0 118.0 115.0 127.0 141.0 124.0 125.0 117.0 119.0 21:58:04 INFO - PID 9247 | 21:58:04 INFO - PID 9247 | [#2] audio-dft Cycles:10 Average:164.60 Median:165.50 stddev:2.12 (1.3%) stddev-sans-first:2.24 21:58:04 INFO - PID 9247 | Values: 164.0 168.0 162.0 162.0 166.0 167.0 163.0 165.0 163.0 166.0 21:58:04 INFO - PID 9247 | 21:58:04 INFO - PID 9247 | [#3] audio-fft Cycles:10 Average:78.00 Median:78.00 stddev:3.02 (3.9%) stddev-sans-first:3.20 21:58:04 INFO - PID 9247 | Values: 78.0 78.0 80.0 76.0 74.0 81.0 78.0 75.0 84.0 76.0 21:58:04 INFO - PID 9247 | 21:58:04 INFO - PID 9247 | [#4] audio-oscillator Cycles:10 Average:80.50 Median:81.00 stddev:1.72 (2.1%) stddev-sans-first:1.73 21:58:04 INFO - PID 9247 | Values: 79.0 81.0 79.0 81.0 82.0 78.0 80.0 81.0 84.0 80.0 21:58:04 INFO - PID 9247 | 21:58:04 INFO - PID 9247 | [#5] imaging-gaussian-blur Cycles:10 Average:114.90 Median:115.00 stddev:3.90 (3.4%) stddev-sans-first:3.97 21:58:04 INFO - PID 9247 | Values: 118.0 111.0 115.0 110.0 115.0 113.0 114.0 115.0 124.0 114.0 21:58:04 INFO - PID 9247 | 21:58:04 INFO - PID 9247 | [#6] imaging-darkroom Cycles:10 Average:149.60 Median:149.00 stddev:2.84 (1.9%) stddev-sans-first:2.95 21:58:04 INFO - PID 9247 | Values: 148.0 149.0 148.0 149.0 157.0 151.0 147.0 148.0 149.0 150.0 21:58:04 INFO - PID 9247 | 21:58:04 INFO - PID 9247 | [#7] imaging-desaturate Cycles:10 Average:98.00 Median:95.00 stddev:3.74 (3.9%) stddev-sans-first:3.81 21:58:04 INFO - PID 9247 | Values: 95.0 95.0 100.0 94.0 99.0 96.0 95.0 98.0 103.0 105.0 21:58:04 INFO - PID 9247 | 21:58:04 INFO - PID 9247 | [#8] json-parse-financial Cycles:10 Average:55.30 Median:54.50 stddev:4.47 (8.2%) stddev-sans-first:4.67 21:58:04 INFO - PID 9247 | Values: 53.0 66.0 51.0 58.0 51.0 58.0 53.0 54.0 55.0 54.0 21:58:04 INFO - PID 9247 | 21:58:04 INFO - PID 9247 | [#9] json-stringify-tinderbox Cycles:10 Average:40.20 Median:39.00 stddev:3.94 (10.1%) stddev-sans-first:4.10 21:58:04 INFO - PID 9247 | Values: 38.0 40.0 49.0 37.0 38.0 43.0 38.0 44.0 37.0 38.0 21:58:04 INFO - PID 9247 | 21:58:04 INFO - PID 9247 | [#10] stanford-crypto-aes Cycles:10 Average:78.70 Median:79.50 stddev:2.75 (3.5%) stddev-sans-first:2.92 21:58:04 INFO - PID 9247 | Values: 79.0 79.0 82.0 75.0 81.0 78.0 80.0 77.0 74.0 82.0 21:58:04 INFO - PID 9247 | 21:58:04 INFO - PID 9247 | [#11] stanford-crypto-ccm Cycles:10 Average:116.00 Median:116.00 stddev:3.30 (2.8%) stddev-sans-first:2.96 21:58:04 INFO - PID 9247 | Values: 121.0 113.0 112.0 122.0 117.0 115.0 114.0 117.0 114.0 115.0 21:58:04 INFO - PID 9247 | 21:58:04 INFO - PID 9247 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:171.60 Median:172.00 stddev:2.80 (1.6%) stddev-sans-first:2.96 21:58:04 INFO - PID 9247 | Values: 172.0 172.0 172.0 171.0 173.0 168.0 167.0 170.0 176.0 175.0 21:58:04 INFO - PID 9247 | 21:58:04 INFO - PID 9247 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.80 Median:66.50 stddev:4.32 (6.5%) stddev-sans-first:4.50 21:58:04 INFO - PID 9247 | Values: 69.0 64.0 68.0 64.0 64.0 75.0 65.0 63.0 73.0 63.0 21:58:04 INFO - PID 9247 | -------- Summary: end -------- 21:58:04 INFO - PID 9247 | 21:58:04 INFO - PID 9247 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 21:58:05 INFO - TEST-INFO | 9247: exit 0 21:58:05 INFO - TEST-OK | kraken | took 62297ms 21:58:05 INFO - SUITE-END | took 1174s 21:58:05 INFO - Completed test suite (00:19:35) 21:58:05 INFO - javascript benchmark 21:58:05 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8545.750375926444, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3126.0309304115713, 3135.0908236611385, 3165.8100513969157, 3189.1867573768686, 3191.21815155287, 6172.5061995040405, 6240.596650297183, 6279.309275979645, 6358.936410635893, 6360.300366959631, 6207.937920620792, 6231.0, 6266.46735027522, 6299.685015749217, 6302.810915672526, 5639.605227634067, 5766.221560089376, 5788.363280039192, 5798.65208087514, 5805.216295800076, 5758.42415758425, 5791.652500849941, 5812.622179558315, 5878.911816322737, 5916.201312822757, 5795.217645617851, 5819.476247137757, 5821.621594596359, 5833.591648584601, 5848.4736373726355, 86.05317682050833, 5669.603127781057, 5709.343425506078, 5746.885062298772, 5850.736716847752, 5731.598788084836, 5745.482906538432, 5755.8561035974435, 5809.506191973687, 5830.32951210612, 6209.534284928648, 6237.968810155966, 6248.343923887972, 6296.181496405438, 6344.873102537969, 6643.0, 6665.800025999228, 6675.899861502081, 6695.2969938156275, 6738.494612904052, 6702.329767023308, 6747.696353664097, 6758.628275444848, 6816.113905192343, 6830.214525329601, 6045.697715114271, 6145.385461453819, 6152.169457123276, 6158.692065396757, 6294.244690637107, 6769.627670478072, 6811.761588344434, 6828.7268509259075, 6853.623050732158, 6878.65606719662, 6515.73937042513, 6520.347965203442, 6526.118973938553, 6531.673416329165, 6683.966580167068, 6640.369164929324, 6717.294684058202, 6724.361185687352, 6739.494537909676, 6742.595444273359, 6568.113304703899, 6622.337766223339, 6713.4964877634375, 6714.060031595581, 6714.261431242559, 6514.706838192293, 6600.933990660128, 6624.735010599522, 6638.933610663928, 6721.563098398588, 5231.3199284092825, 5231.738413079407, 5247.081760691864, 5250.028744682246, 5260.28986086881, 4553.203189441834, 4620.607248383858, 4626.653001024936, 4628.375169352162, 4675.719456832601, 5722.456366645162, 5743.4543718346695, 5785.971070144622, 5814.098814683731, 5820.854478638068, 6580.144581204413, 6665.200175978914, 6677.399034086956, 6711.63086030273, 6747.426468750113, 5732.942670573241, 5839.795607153729, 5861.1501332307425, 5875.294964604275, 5877.941220587825, 5774.769009239583, 5801.129830525455, 5833.300003999547, 5850.5027072699295, 5855.648661080264, 6486.189226346707, 6493.577917435446, 6582.111414959015, 6589.0, 6600.141981542369, 6692.732290708317, 6710.899336509956, 6800.93199068003, 6803.4557235422035, 6806.149231346082, 6325.652089135142, 6377.23473183221, 6405.679716014274, 6472.223333200046, 6473.190851143607, 6442.935570644234, 6508.9349106508325, 6556.803295901131, 6570.5729127606555, 6644.501662375438, 6749.156355455568, 6758.5269031167345, 6767.323267673193, 6783.152105986752, 6794.93205067943, 6457.483401327789, 6461.483081353575, 6474.093626892333, 6555.147830781968, 6570.080188773671, 6337.207849018872, 6449.6130232186215, 6473.287938326686, 6536.869262614816, 6555.80332590023, 6271.090691849748, 6323.810285691437, 6337.0, 6337.302896681454, 6352.714127864349, 6509.121268628864, 6604.108445359815, 6604.339566043357, 6611.975143852711, 6637.435817955527, 6327.240731112296, 6361.2048493938255, 6397.104405383343, 6418.29398766126, 6422.550421470452], "value": 6062.584586974156, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [870.9069738822569, 895.6193617712471, 911.5351170902837, 916.6516723645018, 922.0502882031506, 39114.60885391145, 39725.20549589006, 40311.59688403119, 40338.39492407609, 40384.39423408651, 36712.26575468489, 36767.63232367676, 37652.0, 39379.40930886032, 39617.20765584687, 7859.764207073783, 7932.405069619773, 7958.4031197660115, 7968.681252749912, 8000.239977202156, 7880.842383152333, 7897.091834439027, 7982.121966583672, 7984.24149705777, 8017.438779285452, 7952.443328966975, 7972.963514743075, 8063.516189028648, 8087.029556453233, 8095.311898488636, 7965.6813727450835, 7981.800454988643, 8010.158933311976, 8014.519128852229, 8018.278354948054, 7820.413468989792, 7868.803279917991, 7869.2130786921425, 7895.210478952116, 7900.446968712193, 36448.08879778, 36483.0, 36731.63268367309, 36823.07942301437, 36851.631483685356, 3497.9475307870403, 3515.54297941266, 3522.0, 3523.6476352364816, 3530.2409981853766, 30402.84798575993, 30469.0, 30496.237594060105, 30563.847180764176, 30627.387452251052, 4937.7777999990085, 5123.871903202413, 5170.379554453452, 5190.221466779975, 5197.090509160882, 186331.0, 186338.0, 186614.0, 188291.0, 189161.0541947295, 46853.0, 47077.0, 47229.5277047232, 47405.288920666215, 47450.0, 48732.0, 48811.267830982564, 49132.263016054785, 50693.23960140601, 52735.472645273825, 14713.852861471463, 14750.852491475163, 14801.555953321418, 14836.406543738345, 15019.24903754808, 12082.154249202647, 12108.21296615717, 12108.69728256783, 12170.513179472722, 12201.267923924594, 13896.930515347358, 14016.439342426189, 14268.14391136535, 14310.212938288287, 14451.638709032148, 5609.1305847593685, 5689.118186681071, 5758.165066065397, 5795.681237531976, 5852.94147058524, 993.3791380387258, 1001.1490233301781, 1012.0164477623939, 1012.4128005756644, 1022.846573014039, 22602.547949041254, 23155.768442315697, 23263.88368058149, 23381.181658641864, 23739.1691290804, 1088.8693356797078, 1111.855458790352, 1124.2860783402598, 1129.5594718059963, 1132.7451323452158, 12035.759284814254, 12155.209911355734, 12159.331236782062, 12279.017678585871, 12285.508579656713, 240037.0, 240114.0, 242057.0, 242541.0, 242943.0, 43175.35236971448, 43738.46915357947, 46158.538414616094, 46357.536424636, 46406.0, 1301.8242537257538, 1303.6414985878769, 1425.9732992245567, 1428.7641190370255, 1430.6065831896312, 27111.322216944733, 27550.862245688644, 27625.0, 27725.584116237867, 27841.44317113687, 35616.287674245854, 35701.82149089238, 35822.8208858954, 35942.8202858984, 36047.81976090103, 186379.0, 187351.0, 187881.0, 187893.0, 187895.0, 32158.67841321557, 32922.17694557655, 33061.17347066342, 33347.0, 33354.33291334111, 41952.790236048626, 41970.0, 42084.57915420806, 42360.78819605882, 43022.784886075366, 30457.390852183275, 30519.0, 30932.69067309298, 31033.68966310398, 31276.218094547818, 29971.400571988874, 30204.24489387783, 30408.695913041473, 30461.847690761406, 30585.388292234475, 33079.0, 33808.15479613029, 34007.149821253675, 34353.31293374168, 34479.13802154966, 30032.0, 30157.246068848453, 30440.543391848696, 30618.693813061585, 30720.539191912587, 22671.43321416912, 23142.305730828102, 23250.65124023175, 23329.76670233276, 23599.0], "value": 16898.669276621276, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [564.9491545760883, 568.5991376079864, 575.2722805650855, 600.1058422949804, 611.7919907231542, 22076.668849967264, 22765.772342276574, 22788.31635050947, 22846.31461056167, 22919.312420627368, 23349.533009339804, 24024.75975240247, 24447.26658200257, 24497.632535511937, 24731.876340618317, 6479.287278399361, 6633.170853643294, 6635.43598794103, 6675.165604299463, 6687.498437617198, 6546.672666366662, 6620.841352763271, 6663.167104111986, 6705.530612857102, 6709.396154346108, 6308.4322410983, 6627.966860165717, 6637.303083176269, 6640.7343706251695, 6648.434883034948, 6603.504737144734, 6608.2730899601, 6655.3344665533305, 6658.10115634388, 6667.099941507907, 5917.822465326047, 6149.3235744068115, 6177.81466556004, 6200.3179650238435, 6211.068339749074, 27024.729752702613, 27144.321391965357, 27300.72699273002, 27391.315217119532, 27737.58393624097, 1976.980230197694, 2029.6042271757012, 2029.8680585761879, 2033.2070492507933, 2033.4712974626555, 18229.361972330906, 18768.061596920237, 19093.809061909342, 19122.0, 19126.23495060196, 2417.504411595636, 2462.22439931421, 2474.3319303788057, 2481.3796550862285, 2487.8507289562685, 123168.38415807864, 128834.0, 136288.31855840757, 149013.0, 155696.99999999886, 20606.690899636516, 20921.686174707393, 21459.78540214609, 21711.02300396486, 21930.84625113097, 41302.79348603238, 41547.7922610385, 41552.506849589125, 41679.374809377885, 41919.0, 10763.569457221623, 11014.339139651647, 11158.218924675195, 11181.273217240854, 11220.66338009861, 8976.730698079067, 9055.411398259092, 9119.99360140768, 9120.224780893566, 9146.451212927246, 11210.775784484265, 11415.48630311638, 11434.656960291204, 11548.595799147157, 11981.161318707607, 4060.573639767782, 4301.290287102593, 4333.501647310537, 4428.490723566831, 4490.595846373842, 959.8608201810698, 972.8394814855611, 974.444566597047, 975.059067999384, 986.9851952220722, 18178.90910545465, 18304.908475457803, 18459.538511537317, 18640.16119274636, 18668.81331186698, 1032.6282538286212, 1033.452270296744, 1035.5236591167995, 1036.8341065429495, 1039.1686650679426, 10164.237682173867, 10208.948955255324, 10273.948630256951, 10373.170146388271, 10390.116840068527, 466519.0, 472135.0, 474361.0, 478564.0, 492985.0, 120335.0, 121372.0, 121504.39247803704, 123744.0, 125534.0, 1160.785254727878, 1174.0959461214816, 1222.0712258683286, 1239.1821397877538, 1239.231676360662, 19411.0, 19554.413367598994, 19689.901550492155, 19769.308074217333, 19776.90111549433, 60799.87200447963, 61445.692771535854, 62379.37620623736, 62386.376136238054, 63178.684106579174, 75100.24899750932, 75665.93310297582, 76051.23948760441, 76360.61819690866, 77034.6148269255, 14038.438462461387, 14220.715585688022, 14434.711305774035, 14442.566722998326, 14544.85455145435, 21593.676094858274, 21668.241611544152, 21781.23765668194, 21883.0, 21930.671039934732, 17405.0, 17463.825361746727, 17541.210645521238, 17582.120893954994, 17622.0, 14694.485693000694, 14718.26408679583, 14896.702065958403, 15230.77153842671, 15594.064356138668, 16116.435924742578, 17881.46355609334, 18063.909680451514, 18064.548386290448, 18141.183646735666, 14244.216568088854, 14246.715065698836, 14259.572212833631, 14364.0, 14391.424343026161, 17474.91262543679, 17498.82501174972, 17519.82480175182, 17651.205695743978, 17655.735163972295], "value": 12458.23327930801, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1315.4869600855666, 1373.6771858613224, 1394.1147371419145, 1396.197186617694, 1398.6433459467835, 15031.849681503196, 15482.225888705576, 15537.611559711013, 15643.0, 15659.999999999985, 15112.546623601282, 15207.46773862915, 15230.543083707507, 15282.0, 15318.923405382957, 2717.184844546633, 2817.1407720645193, 2840.8295502269825, 2848.387596666716, 2848.971510284902, 2750.9587356189572, 2770.9584356234573, 2785.679646840619, 2791.1626512046405, 2802.579613058037, 2616.5682662360687, 2639.6304517367585, 2671.3855813162986, 2676.598510223472, 2680.1021657744677, 2591.8185726999022, 2599.4151315953854, 2601.2846467221475, 2617.0, 2618.9476210475873, 2590.080521414908, 2596.974030259711, 2603.9869800650877, 2630.118910165097, 2633.9069286246186, 14950.40198392063, 15001.3249403776, 15060.548183554401, 15162.31769570372, 15168.696626067527, 7883.684652613889, 8119.837603247902, 8164.101948785629, 8165.67337306507, 8167.305779008791, 5248.9737551312, 5456.590755693299, 5497.7570867575905, 5563.5271001964475, 5724.742386592572, 1903.3148066695978, 1917.1181256621971, 1920.9615807683908, 1952.5704345044066, 1969.6946973219174, 23166.073357065696, 23861.0, 25636.87181564099, 27235.1829445115, 27256.863715681295, 7069.752558660422, 7118.28817118284, 7160.498765086394, 7397.741079062207, 7447.0, 20317.0, 20329.18683252683, 20442.591148176954, 20543.178272869216, 20617.381478555668, 8029.518228906284, 8163.0, 8165.0, 8189.262966333059, 8227.54748488827, 7212.8918066228, 7398.297161769623, 7484.251574842472, 7563.130240022468, 7587.58268295238, 5496.890062198733, 5615.775368985276, 5706.686132262766, 5754.223179870748, 5756.424357564294, 1760.2079064421062, 1798.9550261243573, 1830.6796310645584, 1849.306510058728, 1865.3957596467028, 863.7192912303401, 866.9393142480092, 872.9301655867515, 889.2663552569156, 895.6283142495921, 7146.749863754743, 7395.0, 7460.216677248919, 7481.8877716834295, 7568.470207085561, 703.9876082293997, 704.084689903124, 734.5812886654662, 738.0405472885335, 742.3652776875801, 4520.276755719069, 4563.748993805372, 4575.176468235683, 4583.175028494837, 4600.907981840412, 20884.37346879596, 21011.36965891026, 22221.666674999888, 22279.331620051424, 22315.665265021038, 21059.68410473844, 21220.151193952068, 21802.454938626342, 21949.451263718536, 22117.778822211894, 11233.326000439836, 11260.887391125983, 11271.09831213501, 11272.549098035986, 11281.435928203557, 8180.222878826502, 8273.172682731678, 8274.627641756257, 8383.371247156365, 8411.495310281403, 18211.271549137888, 18314.358997435025, 19069.427917162506, 19328.226870925006, 19508.21967121299, 19066.0, 19357.90321048386, 19388.61222775565, 19473.123709432824, 20014.49963750918, 12504.687382815502, 12580.87419125797, 12643.304618246084, 12693.682657933625, 12698.555550555686, 13895.0, 14097.859021409655, 14157.504487343305, 14183.0, 14272.429102835771, 11589.536418543164, 11888.821667675165, 11919.404029798648, 12017.63947081589, 12211.816822747844, 11202.551897923991, 11413.828792567952, 11483.25358851672, 11501.712457188638, 11527.0, 10187.471879218176, 10193.337433066827, 10255.538500767632, 10330.793384132125, 10366.0, 7428.851422971402, 7439.40484761207, 7459.142198647225, 7495.475316727777, 7592.240775922363, 7442.1069471661585, 7454.291842274975, 7530.94566760643, 7645.923540764521, 7691.730789422343], "value": 6743.287378313149, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [508.9847304580864, 526.2816255810818, 540.7377421950355, 548.1175307754514, 548.934127904651, 6220.253569571646, 6244.157038799766, 6249.343818899013, 6260.966940454831, 6275.435210831024, 6080.513558915287, 6118.571699981003, 6119.602225855328, 6133.447989680928, 6166.814995550129, 5146.6140039497095, 5148.639595228336, 5191.351081114861, 5210.973945130269, 5214.843554693365, 5082.771275292621, 5155.123628983082, 5163.457836927125, 5195.896082078356, 5205.297284866551, 5024.422191447994, 5038.765502451886, 5049.242613607951, 5064.974675126619, 5079.6190285728535, 4961.206207006898, 5050.4697006814495, 5104.591632669396, 5119.334486516747, 5132.666376685504, 4630.124799456225, 4648.651351148677, 4651.441826980784, 4658.161530924433, 4701.553352431513, 5913.231279933581, 6008.519318454513, 6026.879462410771, 6052.521134360585, 6068.6358818471035, 5809.796657117023, 5831.5334773218465, 5857.824265272048, 5868.828533442654, 5884.881442680631, 6466.708998095097, 6495.382938620824, 6524.217093948709, 6555.3772391622715, 6562.409383155539, 4881.633877459205, 4999.975000125012, 5010.624203184776, 5144.948550514522, 5166.276721259027, 6480.546361754679, 6548.639824809681, 6769.72921083161, 6778.525503214777, 6808.6936087875665, 5255.211718242294, 5278.5689301954035, 5317.7872885084935, 5326.201069839555, 5341.732913354317, 5437.0, 5461.726913654381, 5465.415029641439, 5484.7531861066345, 5493.615446918678, 5853.385394533512, 5903.3801450847905, 5925.970370148208, 5935.792247271324, 5940.762369505258, 6540.149780528501, 6541.967290163519, 6592.868142637216, 6602.1610707396285, 6631.635260060646, 1873.597176607036, 1882.4917272336393, 1897.535103899526, 1901.7812951510477, 1901.8098190180872, 1340.162398500937, 1405.7539930512119, 1412.180935057664, 1414.8115582910405, 1425.5438259756986, 5533.170024496278, 5565.415631358649, 5668.348139963957, 5688.601797874109, 5696.458836410535, 6432.935670643234, 6490.156279683611, 6542.953127499577, 6576.342365763481, 6622.668866556653, 5611.0, 5638.915416268759, 5665.291838520185, 5672.092465205547, 5678.4037676044245, 3487.267673788482, 3498.3003399320237, 3501.1772233525103, 3521.876734314338, 3538.5046093546925, 6368.319285325282, 6382.521310901701, 6407.551471396958, 6427.357264273535, 6470.4823614110755, 6737.225219099768, 6762.932370676329, 6779.864402711918, 6845.0, 6870.141232345957, 6300.1494798202575, 6319.894018546736, 6369.2484566744515, 6378.77674281398, 6380.680965951684, 6570.737170513126, 6599.934000660124, 6601.636909969998, 6615.338466153346, 6696.966515167393, 6516.588862121045, 6521.64130972801, 6559.672016399257, 6567.916293811659, 6607.702653380706, 6361.90457143134, 6363.204599425072, 6386.43532334581, 6409.4872410206135, 6447.550560450137, 5251.396089449762, 5265.21021846726, 5281.841544753664, 5322.09524380864, 5357.892842143213, 6266.467350275278, 6490.448311893353, 6508.316626754123, 6568.868622627616, 6589.4069533742195, 6160.952638051453, 6339.334369891095, 6429.98796125624, 6449.258335291502, 6465.418112369721, 6355.014972679242, 6403.199600049994, 6404.199475065617, 6430.070978706276, 6471.543902622061, 6217.160683307696, 6218.875622487615, 6242.157308763441, 6343.746250149942, 6381.680915954276, 6243.469305109116, 6244.782267457802, 6351.68241587928, 6377.96811015942, 6382.170317858649, 6118.204633397808, 6171.537134714825, 6238.344973777688, 6241.78153764616, 6246.437820596168], "value": 5097.718183046386, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [917.7338571814174, 924.7503174142981, 938.7653086728318, 944.2446043165465, 952.1002652493393, 19312.034398280084, 20227.292044778435, 20330.491737706565, 20337.186512539498, 20364.59270814583, 17207.397741079065, 17431.302747890102, 17551.91224043881, 19399.709004364944, 19535.0, 953.7997020625677, 991.5984026469291, 996.8803743550784, 997.0, 998.0518507417946, 993.1558175550796, 1000.3597697473587, 1001.6344034427418, 1002.0931057393035, 1003.9899601004006, 986.5905649155628, 1032.6798692405341, 1036.5750042482584, 1041.8437234414823, 1048.4024106259435, 1020.6886899495651, 1021.6475316015963, 1022.4529876516028, 1023.5291765787747, 1029.2075102171323, 712.9536580122275, 732.706917233111, 744.9031625888654, 745.8433728916934, 748.4947660329256, 19971.50071248216, 20272.0, 20278.087486063163, 20310.390688279374, 20373.592528149355, 21186.470338241514, 21251.46871328229, 21963.780362196492, 21975.011124499277, 22050.88974555117, 17232.48302550913, 17583.384581539714, 17601.0, 17732.468025959242, 17915.73126403105, 3825.3114439400897, 3838.5201849768778, 3863.323918314312, 3864.0726225705907, 3868.400397938324, 132130.3393483036, 134816.0, 136852.0, 136948.0, 138143.99999999898, 16632.750508742378, 16664.16679166037, 16713.331466741194, 16855.410060647817, 16901.577460563585, 30024.399512009637, 30317.0, 30456.238594035327, 30790.846045769627, 31301.373972520876, 8256.0, 8310.667573297, 8382.87425688615, 8393.328533717287, 8417.452865563719, 4346.304591265382, 4522.66080043998, 4525.683202175882, 4527.026689261825, 4529.479109902338, 5345.545628621533, 5535.640183388067, 5541.473560011793, 5569.470900264469, 5612.971935140298, 978.3347323819728, 1008.3647302199714, 1028.3521381529738, 1031.4275577054657, 1031.63377001165, 659.61412573644, 669.6584741781629, 680.0784936411092, 687.1994126842272, 699.7900629811037, 11867.703307417383, 12467.812982805266, 12496.625101246977, 12528.185667931555, 13444.731105378034, 676.0394854920769, 676.2088356622763, 682.1950098883266, 684.1004079635263, 688.8209065642969, 5712.6286791358425, 5779.190913272145, 5781.959247335436, 5810.0, 5837.795677151279, 118253.40873295578, 120097.39951300362, 120187.0, 121843.39078304551, 124125.37937310255, 101112.49443752882, 101502.49248753709, 103860.48069759602, 103987.0, 104130.4793476043, 91279.54360228157, 91619.54190229006, 91869.0, 93470.53264733632, 93613.0, 10146.188304935735, 10312.690619281433, 10459.006394392825, 10473.162147028068, 10828.5127169276, 42662.57337426586, 43447.348289775044, 43603.781981089895, 43693.126137476436, 43837.56162438335, 55482.44517554773, 55944.160837586656, 56467.43532564622, 56811.71594142003, 56852.0, 14801.481948131765, 15135.772963405338, 15205.695886081996, 15267.3893044277, 15311.46409875649, 16252.593685157493, 16317.836821632107, 16528.504144875675, 16647.833521665114, 16726.749098763285, 14945.103293802407, 15059.1717455541, 15249.31378088011, 15313.923430382776, 15329.46346877854, 14927.552173434815, 14997.475088371855, 15134.091954482767, 15261.6184595386, 15279.083255004734, 15003.549893503212, 15243.695126097195, 15256.694866102394, 15417.0, 15525.922370388076, 11970.461329240032, 11999.460024298753, 12016.399180041139, 12132.636020919386, 12202.81695774582, 11267.605633802777, 11282.661520154408, 11338.206325557132, 11477.655670329903, 11496.425178740863], "value": 8876.896533967983, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1447.1, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 107.0, 108.0, 114.0, 119.0, 107.0, 107.0, 109.0, 107.0, 117.0], "value": 110.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 116.0, 118.0, 115.0, 127.0, 141.0, 124.0, 125.0, 117.0, 119.0], "value": 122.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 168.0, 162.0, 162.0, 166.0, 167.0, 163.0, 165.0, 163.0, 166.0], "value": 164.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 78.0, 80.0, 76.0, 74.0, 81.0, 78.0, 75.0, 84.0, 76.0], "value": 78.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 81.0, 79.0, 81.0, 82.0, 78.0, 80.0, 81.0, 84.0, 80.0], "value": 80.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 111.0, 115.0, 110.0, 115.0, 113.0, 114.0, 115.0, 124.0, 114.0], "value": 114.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 149.0, 148.0, 149.0, 157.0, 151.0, 147.0, 148.0, 149.0, 150.0], "value": 149.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 100.0, 94.0, 99.0, 96.0, 95.0, 98.0, 103.0, 105.0], "value": 98.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 66.0, 51.0, 58.0, 51.0, 58.0, 53.0, 54.0, 55.0, 54.0], "value": 55.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [38.0, 40.0, 49.0, 37.0, 38.0, 43.0, 38.0, 44.0, 37.0, 38.0], "value": 40.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 79.0, 82.0, 75.0, 81.0, 78.0, 80.0, 77.0, 74.0, 82.0], "value": 78.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [121.0, 113.0, 112.0, 122.0, 117.0, 115.0, 114.0, 117.0, 114.0, 115.0], "value": 116.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 172.0, 172.0, 171.0, 173.0, 168.0, 167.0, 170.0, 176.0, 175.0], "value": 171.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 64.0, 68.0, 64.0, 64.0, 75.0, 65.0, 63.0, 73.0, 63.0], "value": 66.8, "unit": "score"}]}]} 21:58:05 INFO - Return code: 0 21:58:05 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 21:58:05 INFO - # TBPL SUCCESS # 21:58:05 INFO - Running post-action listener: _package_coverage_data 21:58:05 INFO - Running post-action listener: _resource_record_post_action 21:58:05 INFO - [mozharness: 2017-09-23 04:58:05.663327Z] Finished run-tests step (success) 21:58:05 INFO - Running post-run listener: _resource_record_post_run 21:58:05 INFO - Total resource usage - Wall time: 1193s; CPU: 13.0%; Read bytes: 8634368; Write bytes: 338591744; Read time: 4500; Write time: 268104 21:58:05 INFO - TinderboxPrint: CPU usage
13.3% 21:58:05 INFO - TinderboxPrint: I/O read bytes / time
8,634,368 / 4,500 21:58:05 INFO - TinderboxPrint: I/O write bytes / time
338,591,744 / 268,104 21:58:05 INFO - TinderboxPrint: CPU idle
8,244.2 (86.7%) 21:58:05 INFO - TinderboxPrint: CPU user
1,200.8 (12.6%) 21:58:05 INFO - TinderboxPrint: Swap in / out
0 / 0 21:58:05 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 13463552; Read time: 0; Write time: 1344 21:58:05 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 21:58:05 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 8630272; Write bytes: 324661248; Read time: 4484; Write time: 266756 21:58:06 INFO - Running post-run listener: _upload_blobber_files 21:58:06 INFO - Blob upload gear active. 21:58:06 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 21:58:06 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 21:58:06 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'] 21:58:06 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 21:58:06 INFO - (blobuploader) - INFO - Open directory for files ... 21:58:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 21:58:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:58:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:58:07 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 21:58:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:58:07 INFO - (blobuploader) - INFO - Done attempting. 21:58:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 21:58:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:58:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:58:14 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 21:58:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:58:14 INFO - (blobuploader) - INFO - Done attempting. 21:58:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 21:58:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:58:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:58:15 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 21:58:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:58:15 INFO - (blobuploader) - INFO - Done attempting. 21:58:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 21:58:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:58:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:58:16 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 21:58:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:58:16 INFO - (blobuploader) - INFO - Done attempting. 21:58:16 INFO - (blobuploader) - INFO - Iteration through files over. 21:58:16 INFO - Return code: 0 21:58:16 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 21:58:16 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 21:58:16 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b87051044ace353b98a0f81dfbfb87a3f476eacc2e59273631a28769ffa85cbec261dca5e22d02b42d2d53024bff7802e7f4c8041ccd831e4b0b7e27f06245da", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a4147180dbc725fac30da66f3bd9a062e77565f78d2dd8b6564734a5ba77fe26f7ebd91f150beb8f26fc459e3187161b2c8fa3a6bc31ab163059c61ffa1df335", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/42099f6a5c2bf5b2e1f1b247c5a169980e79162bf8bb59b09539364ccb3b94f66d30621f6fadefc0d7903089d9fdb10860fc5161fc704a1757ec99bdc6f996d1", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/35a4fa977a8866f74c20163ec3affe265539d6338ecae3ed6c421479c65232d0a05f5e521f0e66e915c0903fc34e4bf4b8fb47f05fbaef7255825e0a51db2bd6"} 21:58:16 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 21:58:16 INFO - Writing to file /builds/slave/test/properties/blobber_files 21:58:16 INFO - Contents: 21:58:16 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b87051044ace353b98a0f81dfbfb87a3f476eacc2e59273631a28769ffa85cbec261dca5e22d02b42d2d53024bff7802e7f4c8041ccd831e4b0b7e27f06245da", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a4147180dbc725fac30da66f3bd9a062e77565f78d2dd8b6564734a5ba77fe26f7ebd91f150beb8f26fc459e3187161b2c8fa3a6bc31ab163059c61ffa1df335", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/42099f6a5c2bf5b2e1f1b247c5a169980e79162bf8bb59b09539364ccb3b94f66d30621f6fadefc0d7903089d9fdb10860fc5161fc704a1757ec99bdc6f996d1", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/35a4fa977a8866f74c20163ec3affe265539d6338ecae3ed6c421479c65232d0a05f5e521f0e66e915c0903fc34e4bf4b8fb47f05fbaef7255825e0a51db2bd6"} 21:58:16 INFO - Running post-run listener: copy_logs_to_upload_dir 21:58:16 INFO - Copying logs to upload dir... 21:58:16 INFO - mkdir: /builds/slave/test/build/upload/logs 21:58:16 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1244.825139 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 44 secs) (at 2017-09-22 21:58:16.620643) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-22 21:58:16.623914) ========= 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=db0483c52d987ecb441a263b000001ad-1506134377.817830-1745771307 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b87051044ace353b98a0f81dfbfb87a3f476eacc2e59273631a28769ffa85cbec261dca5e22d02b42d2d53024bff7802e7f4c8041ccd831e4b0b7e27f06245da", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a4147180dbc725fac30da66f3bd9a062e77565f78d2dd8b6564734a5ba77fe26f7ebd91f150beb8f26fc459e3187161b2c8fa3a6bc31ab163059c61ffa1df335", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/42099f6a5c2bf5b2e1f1b247c5a169980e79162bf8bb59b09539364ccb3b94f66d30621f6fadefc0d7903089d9fdb10860fc5161fc704a1757ec99bdc6f996d1", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/35a4fa977a8866f74c20163ec3affe265539d6338ecae3ed6c421479c65232d0a05f5e521f0e66e915c0903fc34e4bf4b8fb47f05fbaef7255825e0a51db2bd6"} build_url:https://queue.taskcluster.net/v1/task/VxRwKolgS2WkH9phigpZaw/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011234 build_url: 'https://queue.taskcluster.net/v1/task/VxRwKolgS2WkH9phigpZaw/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b87051044ace353b98a0f81dfbfb87a3f476eacc2e59273631a28769ffa85cbec261dca5e22d02b42d2d53024bff7802e7f4c8041ccd831e4b0b7e27f06245da", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a4147180dbc725fac30da66f3bd9a062e77565f78d2dd8b6564734a5ba77fe26f7ebd91f150beb8f26fc459e3187161b2c8fa3a6bc31ab163059c61ffa1df335", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/42099f6a5c2bf5b2e1f1b247c5a169980e79162bf8bb59b09539364ccb3b94f66d30621f6fadefc0d7903089d9fdb10860fc5161fc704a1757ec99bdc6f996d1", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/35a4fa977a8866f74c20163ec3affe265539d6338ecae3ed6c421479c65232d0a05f5e521f0e66e915c0903fc34e4bf4b8fb47f05fbaef7255825e0a51db2bd6"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-22 21:58:16.652033) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-22 21:58:16.652364) ========= 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=db0483c52d987ecb441a263b000001ad-1506134377.817830-1745771307 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004524 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-22 21:58:16.734458) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-22 21:58:16.734767) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-22 21:58:16.735080) ========= ========= Total master_lag: 0.21 =========