builder: mozilla-central_ubuntu64_hw_qr_test-dromaeojs-e10s slave: talos-linux64-ix-052 starttime: 1506008534.72 results: success (0) revision: 835a92b19e3d7666890153a84bc635bc43a44cb2 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-21 08:42:14.721037) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-21 08:42:14.721485) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-21 08:42:14.721772) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-21 08:42:14.922593) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-21 08:42:14.922900) ========= 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=826874ccc97bd7bf541b4875000001d5-1506006346.323149-1094679459 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-21 08:42:14-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 68.9M=0s 2017-09-21 08:42:14 (68.9 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.085981 ========= master_lag: 0.05 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-21 08:42:15.057589) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-21 08:42:15.057924) ========= 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=826874ccc97bd7bf541b4875000001d5-1506006346.323149-1094679459 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015332 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-21 08:42:15.119789) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-21 08:42:15.120091) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 835a92b19e3d7666890153a84bc635bc43a44cb2 --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 835a92b19e3d7666890153a84bc635bc43a44cb2 --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=826874ccc97bd7bf541b4875000001d5-1506006346.323149-1094679459 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-21 08:42:15,002 truncating revision to first 12 chars 2017-09-21 08:42:15,002 Setting DEBUG logging. 2017-09-21 08:42:15,002 attempt 1/10 2017-09-21 08:42:15,002 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/835a92b19e3d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-21 08:42:15,633 unpacking tar archive at: mozilla-central-835a92b19e3d/testing/mozharness/ program finished with exit code 0 elapsedTime=0.900783 ========= master_lag: 0.05 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-21 08:42:16.067464) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-21 08:42:16.067743) ========= script_repo_revision: 835a92b19e3d7666890153a84bc635bc43a44cb2 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-21 08:42:16.068095) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-21 08:42:16.068351) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-21 08:42:16.132293) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 46 secs) (at 2017-09-21 08:42:16.135574) ========= /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=826874ccc97bd7bf541b4875000001d5-1506006346.323149-1094679459 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 08:42:16 INFO - MultiFileLogger online at 20170921 08:42:16 in /builds/slave/test 08:42:16 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 08:42:16 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:42:16 INFO - {'append_to_log': False, 08:42:16 INFO - 'base_work_dir': '/builds/slave/test', 08:42:16 INFO - 'blob_upload_branch': 'Firefox', 08:42:16 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:42:16 INFO - 'branch': 'Firefox', 08:42:16 INFO - 'buildbot_json_path': 'buildprops.json', 08:42:16 INFO - 'code_coverage': False, 08:42:16 INFO - 'config_files': ('talos/linux_config.py',), 08:42:16 INFO - 'default_actions': ('clobber', 08:42:16 INFO - 'read-buildbot-config', 08:42:16 INFO - 'download-and-extract', 08:42:16 INFO - 'populate-webroot', 08:42:16 INFO - 'create-virtualenv', 08:42:16 INFO - 'install', 08:42:16 INFO - 'setup-mitmproxy', 08:42:16 INFO - 'run-tests'), 08:42:16 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:42:16 INFO - 'disable_ccov_upload': False, 08:42:16 INFO - 'disable_stylo': False, 08:42:16 INFO - 'download_minidump_stackwalk': True, 08:42:16 INFO - 'download_symbols': 'ondemand', 08:42:16 INFO - 'enable_stylo': False, 08:42:16 INFO - 'enable_webrender': False, 08:42:16 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 08:42:16 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:42:16 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:42:16 INFO - 'gecko_profile': False, 08:42:16 INFO - 'gecko_profile_interval': 0, 08:42:16 INFO - 'installer_path': 'installer.exe', 08:42:16 INFO - 'log_level': 'info', 08:42:16 INFO - 'log_name': 'talos', 08:42:16 INFO - 'log_to_console': True, 08:42:16 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 08:42:16 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 08:42:16 INFO - 'opt_config_files': (), 08:42:16 INFO - 'pip_index': False, 08:42:16 INFO - 'suite': 'dromaeojs-e10s', 08:42:16 INFO - 'system_bits': '32', 08:42:16 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:42:16 INFO - 'title': 'talos-linux64-ix-052', 08:42:16 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:42:16 INFO - 'use_talos_json': True, 08:42:16 INFO - 'verify': 'False', 08:42:16 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:42:16 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:42:16 INFO - 'work_dir': 'build'} 08:42:16 INFO - [mozharness: 2017-09-21 15:42:16.162271Z] Running clobber step. 08:42:16 INFO - Running pre-action listener: _resource_record_pre_action 08:42:16 INFO - Running main action method: clobber 08:42:16 INFO - rmtree: /builds/slave/test/build 08:42:16 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:42:17 INFO - Running post-action listener: _resource_record_post_action 08:42:17 INFO - [mozharness: 2017-09-21 15:42:17.165904Z] Finished clobber step (success) 08:42:17 INFO - [mozharness: 2017-09-21 15:42:17.166002Z] Running read-buildbot-config step. 08:42:17 INFO - Running pre-action listener: _resource_record_pre_action 08:42:17 INFO - Running main action method: read_buildbot_config 08:42:17 INFO - Using buildbot properties: 08:42:17 INFO - { 08:42:17 INFO - "project": "", 08:42:17 INFO - "product": "firefox", 08:42:17 INFO - "who": "jlorenzo@mozilla.com", 08:42:17 INFO - "installer_path": "public/build/target.tar.bz2", 08:42:17 INFO - "builddir": "mozilla-central_ubuntu64_hw_qr_test-dromaeojs-e10s", 08:42:17 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 08:42:17 INFO - "buildername": "Ubuntu HW 12.04 x64 qr mozilla-central talos dromaeojs-e10s", 08:42:17 INFO - "stage_platform": "linux64-qr", 08:42:17 INFO - "basedir": "/builds/slave/test", 08:42:17 INFO - "buildnumber": 88, 08:42:17 INFO - "platform": "ubuntu64_hw_qr", 08:42:17 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 08:42:17 INFO - "slavebuilddir": "test", 08:42:17 INFO - "taskId": "DVfMgg5oRHmvIdx_jF7Yxg", 08:42:17 INFO - "branch": "mozilla-central", 08:42:17 INFO - "script_repo_revision": "production", 08:42:17 INFO - "revision": "835a92b19e3d7666890153a84bc635bc43a44cb2", 08:42:17 INFO - "slavename": "talos-linux64-ix-052", 08:42:17 INFO - "repo_path": "mozilla-central" 08:42:17 INFO - } 08:42:17 INFO - Finding installer, test and symbols from parent task. 08:42:17 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DVfMgg5oRHmvIdx_jF7Yxg'}, attempt #1 08:42:17 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DVfMgg5oRHmvIdx_jF7Yxg'}, attempt #1 08:42:18 INFO - Task dependencies: NkyEVKxfQa-s6GWN1Ap4sg 08:42:18 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NkyEVKxfQa-s6GWN1Ap4sg'}, attempt #1 08:42:18 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/NkyEVKxfQa-s6GWN1Ap4sg/artifacts/public/build/target.tar.bz2 08:42:18 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/NkyEVKxfQa-s6GWN1Ap4sg/artifacts/public/build/target.test_packages.json 08:42:18 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/NkyEVKxfQa-s6GWN1Ap4sg/artifacts/public/build/target.crashreporter-symbols.zip 08:42:18 INFO - Running post-action listener: _resource_record_post_action 08:42:18 INFO - [mozharness: 2017-09-21 15:42:18.510948Z] Finished read-buildbot-config step (success) 08:42:18 INFO - [mozharness: 2017-09-21 15:42:18.511165Z] Running download-and-extract step. 08:42:18 INFO - Running pre-action listener: _resource_record_pre_action 08:42:18 INFO - Running main action method: download_and_extract 08:42:18 INFO - mkdir: /builds/slave/test/build/tests 08:42:18 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')]} 08:42:18 INFO - trying https://queue.taskcluster.net/v1/task/NkyEVKxfQa-s6GWN1Ap4sg/artifacts/public/build/target.test_packages.json 08:42:18 INFO - Downloading https://queue.taskcluster.net/v1/task/NkyEVKxfQa-s6GWN1Ap4sg/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 08:42:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NkyEVKxfQa-s6GWN1Ap4sg/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 08:42:19 INFO - Downloaded 989 bytes. 08:42:19 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 08:42:19 INFO - Using the following test package requirements: 08:42:19 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 08:42:19 INFO - u'common': [u'target.common.tests.zip'], 08:42:19 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 08:42:19 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 08:42:19 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 08:42:19 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 08:42:19 INFO - u'mozbase': [u'target.common.tests.zip'], 08:42:19 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 08:42:19 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 08:42:19 INFO - u'web-platform': [u'target.common.tests.zip', 08:42:19 INFO - u'target.web-platform.tests.tar.gz'], 08:42:19 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 08:42:19 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 08:42:19 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/NkyEVKxfQa-s6GWN1Ap4sg/artifacts/public/build/target.common.tests.zip 08:42:19 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NkyEVKxfQa-s6GWN1Ap4sg/artifacts/public/build/target.common.tests.zip'}, attempt #1 08:42:19 INFO - Fetch https://queue.taskcluster.net/v1/task/NkyEVKxfQa-s6GWN1Ap4sg/artifacts/public/build/target.common.tests.zip into memory 08:42:21 INFO - Content-Length response header: 54741106 08:42:21 INFO - Bytes received: 54741106 08:42:31 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/NkyEVKxfQa-s6GWN1Ap4sg/artifacts/public/build/target.talos.tests.zip 08:42:31 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NkyEVKxfQa-s6GWN1Ap4sg/artifacts/public/build/target.talos.tests.zip'}, attempt #1 08:42:31 INFO - Fetch https://queue.taskcluster.net/v1/task/NkyEVKxfQa-s6GWN1Ap4sg/artifacts/public/build/target.talos.tests.zip into memory 08:42:33 INFO - Content-Length response header: 14509184 08:42:33 INFO - Bytes received: 14509184 08:42:33 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')]} 08:42:33 INFO - trying https://queue.taskcluster.net/v1/task/NkyEVKxfQa-s6GWN1Ap4sg/artifacts/public/build/target.tar.bz2 08:42:33 INFO - Downloading https://queue.taskcluster.net/v1/task/NkyEVKxfQa-s6GWN1Ap4sg/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 08:42:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NkyEVKxfQa-s6GWN1Ap4sg/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 08:42:36 INFO - Downloaded 61627500 bytes. 08:42:36 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/NkyEVKxfQa-s6GWN1Ap4sg/artifacts/public/build/target.tar.bz2 08:42:36 INFO - mkdir: /builds/slave/test/properties 08:42:36 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:42:36 INFO - Writing to file /builds/slave/test/properties/build_url 08:42:36 INFO - Contents: 08:42:36 INFO - build_url:https://queue.taskcluster.net/v1/task/NkyEVKxfQa-s6GWN1Ap4sg/artifacts/public/build/target.tar.bz2 08:42:36 INFO - Running post-action listener: _resource_record_post_action 08:42:36 INFO - Running post-action listener: find_tests_for_verification 08:42:36 INFO - Running post-action listener: set_extra_try_arguments 08:42:36 INFO - [mozharness: 2017-09-21 15:42:36.600921Z] Finished download-and-extract step (success) 08:42:36 INFO - [mozharness: 2017-09-21 15:42:36.601018Z] Running populate-webroot step. 08:42:36 INFO - Running pre-action listener: _resource_record_pre_action 08:42:36 INFO - Running main action method: populate_webroot 08:42:36 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:42:36 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 08:42:36 INFO - 'tests': ['tresize', 08:42:36 INFO - 'tcanvasmark']}, 08:42:36 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:42:36 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 08:42:36 INFO - 'tests': ['dromaeo_css', 08:42:36 INFO - 'kraken']}, 08:42:36 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 08:42:36 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:42:36 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 08:42:36 INFO - 'talos_options': ['--disable-stylo'], 08:42:36 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:42:36 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 08:42:36 INFO - 'tests': ['damp', 'tps']}, 08:42:36 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 08:42:36 INFO - 'talos_options': ['--disable-stylo'], 08:42:36 INFO - 'tests': ['damp', 'tps']}, 08:42:36 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:42:36 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 08:42:36 INFO - 'tests': ['dromaeo_dom']}, 08:42:36 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 08:42:36 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 08:42:36 INFO - 'tests': ['basic_compositor_video', 08:42:36 INFO - 'glvideo']}, 08:42:36 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 08:42:36 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 08:42:36 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 08:42:36 INFO - 'talos_options': ['--disable-stylo'], 08:42:36 INFO - 'tests': ['ts_paint_webext', 08:42:36 INFO - 'tp5o_webext']}, 08:42:36 INFO - 'other-e10s': {'tests': ['a11yr', 08:42:36 INFO - 'ts_paint', 08:42:36 INFO - 'tpaint', 08:42:36 INFO - 'sessionrestore', 08:42:36 INFO - 'sessionrestore_many_windows', 08:42:36 INFO - 'sessionrestore_no_auto_restore', 08:42:36 INFO - 'tabpaint']}, 08:42:36 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 08:42:36 INFO - 'tests': ['a11yr', 08:42:36 INFO - 'ts_paint', 08:42:36 INFO - 'tpaint', 08:42:36 INFO - 'sessionrestore', 08:42:36 INFO - 'sessionrestore_many_windows', 08:42:36 INFO - 'sessionrestore_no_auto_restore', 08:42:36 INFO - 'tabpaint']}, 08:42:36 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 08:42:36 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']}, 08:42:36 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 08:42:36 INFO - 'tests': ['perf_reftest_singletons']}, 08:42:36 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 08:42:36 INFO - 'tests': ['bloom_basic']}, 08:42:36 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:42:36 INFO - 'tsvgr_opacity', 08:42:36 INFO - 'tart', 08:42:36 INFO - 'tscrollx', 08:42:36 INFO - 'tsvg_static']}, 08:42:36 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 08:42:36 INFO - 'tests': ['tsvgx', 08:42:36 INFO - 'tsvgr_opacity', 08:42:36 INFO - 'tart', 08:42:36 INFO - 'tscrollx', 08:42:36 INFO - 'tsvg_static']}, 08:42:36 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 08:42:36 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 08:42:36 INFO - 'talos_options': ['--disable-stylo'], 08:42:36 INFO - 'tests': ['tp5o']}, 08:42:36 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 08:42:36 INFO - 'talos_options': ['--mitmproxy', 08:42:36 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 08:42:36 INFO - '--firstNonBlankPaint'], 08:42:36 INFO - 'tests': ['tp6_google', 08:42:36 INFO - 'tp6_youtube', 08:42:36 INFO - 'tp6_amazon', 08:42:36 INFO - 'tp6_facebook']}, 08:42:36 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 08:42:36 INFO - 'talos_options': ['--disable-stylo', 08:42:36 INFO - '--mitmproxy', 08:42:36 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 08:42:36 INFO - '--firstNonBlankPaint'], 08:42:36 INFO - 'tests': ['tp6_google', 08:42:36 INFO - 'tp6_youtube', 08:42:36 INFO - 'tp6_amazon', 08:42:36 INFO - 'tp6_facebook']}, 08:42:36 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 08:42:36 INFO - 'talos_options': ['--stylo-threads=1', 08:42:36 INFO - '--mitmproxy', 08:42:36 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 08:42:36 INFO - '--firstNonBlankPaint'], 08:42:36 INFO - 'tests': ['tp6_google', 08:42:36 INFO - 'tp6_youtube', 08:42:36 INFO - 'tp6_amazon', 08:42:36 INFO - 'tp6_facebook']}, 08:42:36 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 08:42:36 INFO - 'talos_options': ['--xperf_path', 08:42:36 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:42:36 INFO - 'tests': ['tp5n']}, 08:42:36 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 08:42:36 INFO - 'talos_options': ['--disable-stylo', 08:42:36 INFO - '--xperf_path', 08:42:36 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:42:36 INFO - 'tests': ['tp5n']}}} 08:42:36 INFO - Running post-action listener: _resource_record_post_action 08:42:36 INFO - [mozharness: 2017-09-21 15:42:36.615942Z] Finished populate-webroot step (success) 08:42:36 INFO - [mozharness: 2017-09-21 15:42:36.616031Z] Running create-virtualenv step. 08:42:36 INFO - Running pre-action listener: _resource_record_pre_action 08:42:36 INFO - Running main action method: create_virtualenv 08:42:36 INFO - Creating virtualenv /builds/slave/test/build/venv 08:42:36 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 08:42:36 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 08:42:36 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 08:42:36 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:42:37 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:42:37 INFO - Installing distribute.............................................................................................................................................................................................done. 08:42:37 INFO - Installing pip...............done. 08:42:37 INFO - Return code: 0 08:42:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:42:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:42:37 INFO - Reading from file tmpfile_stdout 08:42:37 INFO - Output received: 08:42:37 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 08:42:37 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:42:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:42:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:42:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:42:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:42:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xaf01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcb9a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe5ca60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe60090>, '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 0xe60d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe5af90>, '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': '826874ccc97bd7bf541b4875000001d5-1506006346.323149-1094679459', '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 08:42:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 08:42:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 08:42:37 INFO - Using env: {'DISPLAY': ':0', 08:42:37 INFO - 'HOME': '/home/cltbld', 08:42:37 INFO - 'LANG': 'en_US.UTF-8', 08:42:37 INFO - 'LANGUAGE': 'en_US:en', 08:42:37 INFO - 'LOGNAME': 'cltbld', 08:42:37 INFO - 'MAIL': '/var/mail/cltbld', 08:42:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:42:37 INFO - 'MOZ_NO_REMOTE': '1', 08:42:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:42:37 INFO - 'NO_EM_RESTART': '1', 08:42:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:42:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:42:37 INFO - 'PWD': '/builds/slave/test', 08:42:37 INFO - 'SHELL': '/bin/bash', 08:42:37 INFO - 'SHLVL': '1', 08:42:37 INFO - 'TERM': 'linux', 08:42:37 INFO - 'TMOUT': '86400', 08:42:37 INFO - 'USER': 'cltbld', 08:42:37 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1506006346.323149-1094679459', 08:42:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:42:37 INFO - '_': '/tools/buildbot/bin/python'} 08:42:38 INFO - Ignoring indexes: http://pypi.python.org/simple/ 08:42:38 INFO - Downloading/unpacking pip>=1.5 08:42:38 INFO - Running setup.py egg_info for package pip 08:42:38 INFO - warning: no files found matching 'pip/cacert.pem' 08:42:38 INFO - warning: no files found matching '*.html' under directory 'docs' 08:42:38 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 08:42:38 INFO - no previously-included directories found matching 'docs/_build/_sources' 08:42:38 INFO - Installing collected packages: pip 08:42:38 INFO - Found existing installation: pip 1.1 08:42:38 INFO - Uninstalling pip: 08:42:38 INFO - Successfully uninstalled pip 08:42:38 INFO - Running setup.py install for pip 08:42:38 INFO - warning: no files found matching 'pip/cacert.pem' 08:42:38 INFO - warning: no files found matching '*.html' under directory 'docs' 08:42:38 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 08:42:38 INFO - no previously-included directories found matching 'docs/_build/_sources' 08:42:38 INFO - Installing pip script to /builds/slave/test/build/venv/bin 08:42:38 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 08:42:38 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 08:42:38 INFO - Successfully installed pip 08:42:38 INFO - Cleaning up... 08:42:38 INFO - Return code: 0 08:42:38 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:42:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:42:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:42:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:42:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:42:38 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 0xaf01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcb9a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe5ca60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe60090>, '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 0xe60d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe5af90>, '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': '826874ccc97bd7bf541b4875000001d5-1506006346.323149-1094679459', '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 08:42:38 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 08:42:38 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 08:42:38 INFO - Using env: {'DISPLAY': ':0', 08:42:38 INFO - 'HOME': '/home/cltbld', 08:42:38 INFO - 'LANG': 'en_US.UTF-8', 08:42:38 INFO - 'LANGUAGE': 'en_US:en', 08:42:38 INFO - 'LOGNAME': 'cltbld', 08:42:38 INFO - 'MAIL': '/var/mail/cltbld', 08:42:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:42:38 INFO - 'MOZ_NO_REMOTE': '1', 08:42:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:42:38 INFO - 'NO_EM_RESTART': '1', 08:42:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:42:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:42:38 INFO - 'PWD': '/builds/slave/test', 08:42:38 INFO - 'SHELL': '/bin/bash', 08:42:38 INFO - 'SHLVL': '1', 08:42:38 INFO - 'TERM': 'linux', 08:42:38 INFO - 'TMOUT': '86400', 08:42:38 INFO - 'USER': 'cltbld', 08:42:38 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1506006346.323149-1094679459', 08:42:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:42:38 INFO - '_': '/tools/buildbot/bin/python'} 08:42:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:42:39 INFO - Downloading/unpacking psutil>=3.1.1 08:42:39 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 08:42:39 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 08:42:39 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 08:42:39 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 08:42:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:42:40 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:42:40 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:42:40 INFO - Installing collected packages: psutil 08:42:40 INFO - Running setup.py install for psutil 08:42:40 INFO - building 'psutil._psutil_linux' extension 08:42:40 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 08:42:40 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 08:42:40 INFO - building 'psutil._psutil_posix' extension 08:42:40 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 08:42:40 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 08:42:40 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:42:40 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:42:40 INFO - Successfully installed psutil 08:42:40 INFO - Cleaning up... 08:42:40 INFO - Return code: 0 08:42:40 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:42:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:42:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:42:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:42:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:42:40 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xaf01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcb9a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe5ca60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe60090>, '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 0xe60d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe5af90>, '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': '826874ccc97bd7bf541b4875000001d5-1506006346.323149-1094679459', '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 08:42:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 08:42:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 08:42:40 INFO - Using env: {'DISPLAY': ':0', 08:42:40 INFO - 'HOME': '/home/cltbld', 08:42:40 INFO - 'LANG': 'en_US.UTF-8', 08:42:40 INFO - 'LANGUAGE': 'en_US:en', 08:42:40 INFO - 'LOGNAME': 'cltbld', 08:42:40 INFO - 'MAIL': '/var/mail/cltbld', 08:42:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:42:40 INFO - 'MOZ_NO_REMOTE': '1', 08:42:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:42:40 INFO - 'NO_EM_RESTART': '1', 08:42:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:42:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:42:40 INFO - 'PWD': '/builds/slave/test', 08:42:40 INFO - 'SHELL': '/bin/bash', 08:42:40 INFO - 'SHLVL': '1', 08:42:40 INFO - 'TERM': 'linux', 08:42:40 INFO - 'TMOUT': '86400', 08:42:40 INFO - 'USER': 'cltbld', 08:42:40 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1506006346.323149-1094679459', 08:42:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:42:40 INFO - '_': '/tools/buildbot/bin/python'} 08:42:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:42:41 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:42:41 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 08:42:41 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 08:42:41 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 08:42:41 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 08:42:42 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:42:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:42:42 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:42:42 INFO - Installing collected packages: mozsystemmonitor 08:42:42 INFO - Running setup.py install for mozsystemmonitor 08:42:42 INFO - Successfully installed mozsystemmonitor 08:42:42 INFO - Cleaning up... 08:42:43 INFO - Return code: 0 08:42:43 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:42:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:42:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:42:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:42:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:42:43 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0xaf01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcb9a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe5ca60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe60090>, '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 0xe60d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe5af90>, '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': '826874ccc97bd7bf541b4875000001d5-1506006346.323149-1094679459', '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 08:42:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 08:42:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 08:42:43 INFO - Using env: {'DISPLAY': ':0', 08:42:43 INFO - 'HOME': '/home/cltbld', 08:42:43 INFO - 'LANG': 'en_US.UTF-8', 08:42:43 INFO - 'LANGUAGE': 'en_US:en', 08:42:43 INFO - 'LOGNAME': 'cltbld', 08:42:43 INFO - 'MAIL': '/var/mail/cltbld', 08:42:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:42:43 INFO - 'MOZ_NO_REMOTE': '1', 08:42:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:42:43 INFO - 'NO_EM_RESTART': '1', 08:42:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:42:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:42:43 INFO - 'PWD': '/builds/slave/test', 08:42:43 INFO - 'SHELL': '/bin/bash', 08:42:43 INFO - 'SHLVL': '1', 08:42:43 INFO - 'TERM': 'linux', 08:42:43 INFO - 'TMOUT': '86400', 08:42:43 INFO - 'USER': 'cltbld', 08:42:43 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1506006346.323149-1094679459', 08:42:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:42:43 INFO - '_': '/tools/buildbot/bin/python'} 08:42:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:42:43 INFO - Downloading/unpacking jsonschema==2.5.1 08:42:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:42:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:42:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:42:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:42:44 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:42:44 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:42:44 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 08:42:44 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 08:42:44 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 08:42:44 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 08:42:44 INFO - Downloading functools32-3.2.3-2.tar.gz 08:42:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:42:44 INFO - warning: no files found matching '*.txt' 08:42:44 INFO - no previously-included directories found matching 'build' 08:42:44 INFO - no previously-included directories found matching 'dist' 08:42:44 INFO - no previously-included directories found matching '.git*' 08:42:44 INFO - Installing collected packages: jsonschema, functools32 08:42:45 INFO - Running setup.py install for functools32 08:42:45 INFO - warning: no files found matching '*.txt' 08:42:45 INFO - no previously-included directories found matching 'build' 08:42:45 INFO - no previously-included directories found matching 'dist' 08:42:45 INFO - no previously-included directories found matching '.git*' 08:42:45 INFO - Successfully installed jsonschema functools32 08:42:45 INFO - Cleaning up... 08:42:45 INFO - Return code: 0 08:42:45 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:42:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:42:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:42:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:42:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:42:45 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0xaf01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcb9a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe5ca60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe60090>, '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 0xe60d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe5af90>, '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': '826874ccc97bd7bf541b4875000001d5-1506006346.323149-1094679459', '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 08:42:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 08:42:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 08:42:45 INFO - Using env: {'DISPLAY': ':0', 08:42:45 INFO - 'HOME': '/home/cltbld', 08:42:45 INFO - 'LANG': 'en_US.UTF-8', 08:42:45 INFO - 'LANGUAGE': 'en_US:en', 08:42:45 INFO - 'LOGNAME': 'cltbld', 08:42:45 INFO - 'MAIL': '/var/mail/cltbld', 08:42:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:42:45 INFO - 'MOZ_NO_REMOTE': '1', 08:42:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:42:45 INFO - 'NO_EM_RESTART': '1', 08:42:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:42:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:42:45 INFO - 'PWD': '/builds/slave/test', 08:42:45 INFO - 'SHELL': '/bin/bash', 08:42:45 INFO - 'SHLVL': '1', 08:42:45 INFO - 'TERM': 'linux', 08:42:45 INFO - 'TMOUT': '86400', 08:42:45 INFO - 'USER': 'cltbld', 08:42:45 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1506006346.323149-1094679459', 08:42:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:42:45 INFO - '_': '/tools/buildbot/bin/python'} 08:42:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:42:45 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:42:45 INFO - Cleaning up... 08:42:45 INFO - Return code: 0 08:42:45 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:42:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:42:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:42:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:42:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:42:45 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0xaf01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcb9a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe5ca60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe60090>, '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 0xe60d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe5af90>, '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': '826874ccc97bd7bf541b4875000001d5-1506006346.323149-1094679459', '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 08:42:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 08:42:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 08:42:45 INFO - Using env: {'DISPLAY': ':0', 08:42:45 INFO - 'HOME': '/home/cltbld', 08:42:45 INFO - 'LANG': 'en_US.UTF-8', 08:42:45 INFO - 'LANGUAGE': 'en_US:en', 08:42:45 INFO - 'LOGNAME': 'cltbld', 08:42:45 INFO - 'MAIL': '/var/mail/cltbld', 08:42:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:42:45 INFO - 'MOZ_NO_REMOTE': '1', 08:42:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:42:45 INFO - 'NO_EM_RESTART': '1', 08:42:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:42:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:42:45 INFO - 'PWD': '/builds/slave/test', 08:42:45 INFO - 'SHELL': '/bin/bash', 08:42:45 INFO - 'SHLVL': '1', 08:42:45 INFO - 'TERM': 'linux', 08:42:45 INFO - 'TMOUT': '86400', 08:42:45 INFO - 'USER': 'cltbld', 08:42:45 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1506006346.323149-1094679459', 08:42:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:42:45 INFO - '_': '/tools/buildbot/bin/python'} 08:42:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:42:45 INFO - Downloading/unpacking blobuploader==1.2.4 08:42:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:42:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:42:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:42:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:42:47 INFO - Downloading blobuploader-1.2.4.tar.gz 08:42:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:42:47 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:42:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:42:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:42:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:42:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:42:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:42:47 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:42:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:42:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:42:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:42:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:42:47 INFO - Downloading docopt-0.6.1.tar.gz 08:42:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:42:47 INFO - Installing collected packages: blobuploader, requests, docopt 08:42:47 INFO - Running setup.py install for blobuploader 08:42:47 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:42:47 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:42:47 INFO - Running setup.py install for requests 08:42:48 INFO - Running setup.py install for docopt 08:42:48 INFO - Successfully installed blobuploader requests docopt 08:42:48 INFO - Cleaning up... 08:42:48 INFO - Return code: 0 08:42:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:42:48 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')]} 08:42:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:42:48 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')]} 08:42:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:42:48 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 0xaf01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcb9a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe5ca60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe60090>, '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 0xe60d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe5af90>, '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': '826874ccc97bd7bf541b4875000001d5-1506006346.323149-1094679459', '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 08:42:48 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 08:42:48 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 08:42:48 INFO - Using env: {'DISPLAY': ':0', 08:42:48 INFO - 'HOME': '/home/cltbld', 08:42:48 INFO - 'LANG': 'en_US.UTF-8', 08:42:48 INFO - 'LANGUAGE': 'en_US:en', 08:42:48 INFO - 'LOGNAME': 'cltbld', 08:42:48 INFO - 'MAIL': '/var/mail/cltbld', 08:42:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:42:48 INFO - 'MOZ_NO_REMOTE': '1', 08:42:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:42:48 INFO - 'NO_EM_RESTART': '1', 08:42:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:42:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:42:48 INFO - 'PWD': '/builds/slave/test', 08:42:48 INFO - 'SHELL': '/bin/bash', 08:42:48 INFO - 'SHLVL': '1', 08:42:48 INFO - 'TERM': 'linux', 08:42:48 INFO - 'TMOUT': '86400', 08:42:48 INFO - 'USER': 'cltbld', 08:42:48 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1506006346.323149-1094679459', 08:42:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:42:48 INFO - '_': '/tools/buildbot/bin/python'} 08:42:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:42:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:42:48 INFO - Running setup.py (path:/tmp/pip-fAAgwV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:42:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:42:48 INFO - Running setup.py (path:/tmp/pip-DIWlr_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:42:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:42:48 INFO - Running setup.py (path:/tmp/pip-jVlhK7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:42:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:42:48 INFO - Running setup.py (path:/tmp/pip-gsGWgL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:42:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:42:48 INFO - Running setup.py (path:/tmp/pip-CBki7G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:42:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:42:48 INFO - Running setup.py (path:/tmp/pip-d3zzhR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:42:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:42:49 INFO - Running setup.py (path:/tmp/pip-DV52yE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:42:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:42:49 INFO - Running setup.py (path:/tmp/pip-cnSedH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:42:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:42:49 INFO - Running setup.py (path:/tmp/pip-BiJQo1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:42:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:42:49 INFO - Running setup.py (path:/tmp/pip-Jwzufm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:42:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:42:49 INFO - Running setup.py (path:/tmp/pip-RhN6_8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:42:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:42:49 INFO - Running setup.py (path:/tmp/pip-Npia71-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:42:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:42:49 INFO - Running setup.py (path:/tmp/pip-sQxMhX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:42:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:42:49 INFO - Running setup.py (path:/tmp/pip-SGEay2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:42:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:42:49 INFO - Running setup.py (path:/tmp/pip-QATSWv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:42:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:42:49 INFO - Running setup.py (path:/tmp/pip-q8IMwZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:42:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:42:49 INFO - Running setup.py (path:/tmp/pip-qleOYJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:42:49 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:42:49 INFO - Running setup.py install for manifestparser 08:42:49 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:42:49 INFO - Running setup.py install for mozcrash 08:42:50 INFO - Running setup.py install for mozdebug 08:42:50 INFO - Running setup.py install for mozdevice 08:42:50 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:42:50 INFO - Running setup.py install for mozfile 08:42:50 INFO - Running setup.py install for mozhttpd 08:42:50 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:42:50 INFO - Running setup.py install for mozinfo 08:42:50 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:42:50 INFO - Running setup.py install for mozInstall 08:42:50 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:42:50 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:42:50 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:42:50 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:42:50 INFO - Running setup.py install for mozleak 08:42:50 INFO - Running setup.py install for mozlog 08:42:50 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:42:50 INFO - Running setup.py install for moznetwork 08:42:50 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:42:50 INFO - Running setup.py install for mozprocess 08:42:51 INFO - Running setup.py install for mozprofile 08:42:51 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:42:51 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:42:51 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:42:51 INFO - Running setup.py install for mozrunner 08:42:51 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:42:51 INFO - Running setup.py install for mozscreenshot 08:42:51 INFO - Running setup.py install for moztest 08:42:51 INFO - Running setup.py install for mozversion 08:42:51 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:42:51 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:42:51 INFO - Cleaning up... 08:42:51 INFO - Return code: 0 08:42:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:42:51 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')]} 08:42:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:42:51 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')]} 08:42:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:42:51 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 0xaf01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcb9a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe5ca60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe60090>, '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 0xe60d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe5af90>, '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': '826874ccc97bd7bf541b4875000001d5-1506006346.323149-1094679459', '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 08:42:51 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 08:42:51 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 08:42:51 INFO - Using env: {'DISPLAY': ':0', 08:42:51 INFO - 'HOME': '/home/cltbld', 08:42:51 INFO - 'LANG': 'en_US.UTF-8', 08:42:51 INFO - 'LANGUAGE': 'en_US:en', 08:42:51 INFO - 'LOGNAME': 'cltbld', 08:42:51 INFO - 'MAIL': '/var/mail/cltbld', 08:42:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:42:51 INFO - 'MOZ_NO_REMOTE': '1', 08:42:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:42:51 INFO - 'NO_EM_RESTART': '1', 08:42:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:42:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:42:51 INFO - 'PWD': '/builds/slave/test', 08:42:51 INFO - 'SHELL': '/bin/bash', 08:42:51 INFO - 'SHLVL': '1', 08:42:51 INFO - 'TERM': 'linux', 08:42:51 INFO - 'TMOUT': '86400', 08:42:51 INFO - 'USER': 'cltbld', 08:42:51 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1506006346.323149-1094679459', 08:42:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:42:51 INFO - '_': '/tools/buildbot/bin/python'} 08:42:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:42:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:42:51 INFO - Running setup.py (path:/tmp/pip-uepL1p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:42:51 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)) 08:42:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:42:51 INFO - Running setup.py (path:/tmp/pip-vYjgmP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:42:51 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)) 08:42:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:42:51 INFO - Running setup.py (path:/tmp/pip-N8rhXl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:42:51 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)) 08:42:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:42:51 INFO - Running setup.py (path:/tmp/pip-c6VwRa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:42:52 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)) 08:42:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:42:52 INFO - Running setup.py (path:/tmp/pip-2GaFid-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:42:52 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)) 08:42:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:42:52 INFO - Running setup.py (path:/tmp/pip-bfppts-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:42:52 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)) 08:42:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:42:52 INFO - Running setup.py (path:/tmp/pip-TeTfCF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:42:52 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)) 08:42:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:42:52 INFO - Running setup.py (path:/tmp/pip-i2NZWN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:42:52 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)) 08:42:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:42:52 INFO - Running setup.py (path:/tmp/pip-auA96O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:42:52 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)) 08:42:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:42:52 INFO - Running setup.py (path:/tmp/pip-THI5nI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:42:52 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)) 08:42:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:42:52 INFO - Running setup.py (path:/tmp/pip-u8DZTN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:42:52 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)) 08:42:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:42:52 INFO - Running setup.py (path:/tmp/pip-fg0kNv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:42:52 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)) 08:42:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:42:52 INFO - Running setup.py (path:/tmp/pip-9CfcAp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:42:52 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)) 08:42:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:42:52 INFO - Running setup.py (path:/tmp/pip-u4h7Ax-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:42:52 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)) 08:42:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:42:52 INFO - Running setup.py (path:/tmp/pip-WDe0Tf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:42:52 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)) 08:42:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:42:52 INFO - Running setup.py (path:/tmp/pip-ZHXJFF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:42:52 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)) 08:42:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:42:52 INFO - Running setup.py (path:/tmp/pip-LmWMxW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:42:52 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)) 08:42:52 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)) 08:42:52 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)) 08:42:52 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)) 08:42:52 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)) 08:42:52 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)) 08:42:52 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)) 08:42:52 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:42:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:42:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:42:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:42:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:42:54 INFO - Downloading blessings-1.6.tar.gz 08:42:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:42:54 INFO - Installing collected packages: blessings 08:42:54 INFO - Running setup.py install for blessings 08:42:54 INFO - Successfully installed blessings 08:42:54 INFO - Cleaning up... 08:42:54 INFO - Return code: 0 08:42:54 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:42:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:42:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:42:54 INFO - Reading from file tmpfile_stdout 08:42:54 INFO - Current package versions: 08:42:54 INFO - argparse == 1.2.1 08:42:54 INFO - blessings == 1.6 08:42:54 INFO - blobuploader == 1.2.4 08:42:54 INFO - docopt == 0.6.1 08:42:54 INFO - functools32 == 3.2.3-2 08:42:54 INFO - jsonschema == 2.5.1 08:42:54 INFO - manifestparser == 1.1 08:42:54 INFO - mozInstall == 1.13 08:42:54 INFO - mozcrash == 1.0 08:42:54 INFO - mozdebug == 0.1 08:42:54 INFO - mozdevice == 0.51 08:42:54 INFO - mozfile == 1.2 08:42:54 INFO - mozhttpd == 0.7 08:42:54 INFO - mozinfo == 0.10 08:42:54 INFO - mozleak == 0.1 08:42:54 INFO - mozlog == 3.5 08:42:54 INFO - moznetwork == 0.27 08:42:54 INFO - mozprocess == 0.25 08:42:54 INFO - mozprofile == 0.28 08:42:54 INFO - mozrunner == 6.13 08:42:54 INFO - mozscreenshot == 0.1 08:42:54 INFO - mozsystemmonitor == 0.3 08:42:54 INFO - moztest == 0.8 08:42:54 INFO - mozversion == 1.4 08:42:54 INFO - psutil == 3.1.1 08:42:54 INFO - requests == 1.2.3 08:42:54 INFO - wsgiref == 0.1.2 08:42:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:42:54 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')]} 08:42:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:42:54 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')]} 08:42:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:42:54 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 0xaf01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcb9a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe5ca60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe60090>, '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 0xe60d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe5af90>, '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': '826874ccc97bd7bf541b4875000001d5-1506006346.323149-1094679459', '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 08:42:54 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 08:42:54 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 08:42:54 INFO - Using env: {'DISPLAY': ':0', 08:42:54 INFO - 'HOME': '/home/cltbld', 08:42:54 INFO - 'LANG': 'en_US.UTF-8', 08:42:54 INFO - 'LANGUAGE': 'en_US:en', 08:42:54 INFO - 'LOGNAME': 'cltbld', 08:42:54 INFO - 'MAIL': '/var/mail/cltbld', 08:42:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:42:54 INFO - 'MOZ_NO_REMOTE': '1', 08:42:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:42:54 INFO - 'NO_EM_RESTART': '1', 08:42:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:42:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:42:54 INFO - 'PWD': '/builds/slave/test', 08:42:54 INFO - 'SHELL': '/bin/bash', 08:42:54 INFO - 'SHLVL': '1', 08:42:54 INFO - 'TERM': 'linux', 08:42:54 INFO - 'TMOUT': '86400', 08:42:54 INFO - 'USER': 'cltbld', 08:42:54 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1506006346.323149-1094679459', 08:42:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:42:54 INFO - '_': '/tools/buildbot/bin/python'} 08:42:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:42:54 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)) 08:42:54 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)) 08:42:54 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)) 08:42:54 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)) 08:42:54 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)) 08:42:54 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)) 08:42:54 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)) 08:42:54 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)) 08:42:54 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)) 08:42:54 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 08:42:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:42:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:42:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:42:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:42:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 08:42:55 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)) 08:42:55 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)) 08:42:55 INFO - Installing collected packages: simplejson 08:42:55 INFO - Running setup.py install for simplejson 08:42:56 INFO - building 'simplejson._speedups' extension 08:42:56 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 08:42:56 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 08:42:56 INFO - Successfully installed simplejson 08:42:56 INFO - Cleaning up... 08:42:56 INFO - Return code: 0 08:42:56 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:42:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:42:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:42:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:42:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:42:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xaf01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcb9a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe5ca60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe60090>, '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 0xe60d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe5af90>, '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': '826874ccc97bd7bf541b4875000001d5-1506006346.323149-1094679459', '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 08:42:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 08:42:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 08:42:56 INFO - Using env: {'DISPLAY': ':0', 08:42:56 INFO - 'HOME': '/home/cltbld', 08:42:56 INFO - 'LANG': 'en_US.UTF-8', 08:42:56 INFO - 'LANGUAGE': 'en_US:en', 08:42:56 INFO - 'LOGNAME': 'cltbld', 08:42:56 INFO - 'MAIL': '/var/mail/cltbld', 08:42:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:42:56 INFO - 'MOZ_NO_REMOTE': '1', 08:42:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:42:56 INFO - 'NO_EM_RESTART': '1', 08:42:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:42:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:42:56 INFO - 'PWD': '/builds/slave/test', 08:42:56 INFO - 'SHELL': '/bin/bash', 08:42:56 INFO - 'SHLVL': '1', 08:42:56 INFO - 'TERM': 'linux', 08:42:56 INFO - 'TMOUT': '86400', 08:42:56 INFO - 'USER': 'cltbld', 08:42:56 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1506006346.323149-1094679459', 08:42:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:42:56 INFO - '_': '/tools/buildbot/bin/python'} 08:42:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:42:57 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:42:57 INFO - Cleaning up... 08:42:57 INFO - Return code: 0 08:42:57 INFO - Running post-action listener: _resource_record_post_action 08:42:57 INFO - Running post-action listener: _start_resource_monitoring 08:42:57 INFO - Starting resource monitoring. 08:42:57 INFO - [mozharness: 2017-09-21 15:42:57.247878Z] Finished create-virtualenv step (success) 08:42:57 INFO - [mozharness: 2017-09-21 15:42:57.248388Z] Running install step. 08:42:57 INFO - Running pre-action listener: _resource_record_pre_action 08:42:57 INFO - Running main action method: install 08:42:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:42:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:42:57 INFO - Reading from file tmpfile_stdout 08:42:57 INFO - Detecting whether we're running mozinstall >=1.0... 08:42:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:42:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:42:57 INFO - Reading from file tmpfile_stdout 08:42:57 INFO - Output received: 08:42:57 INFO - Usage: mozinstall [options] installer 08:42:57 INFO - Options: 08:42:57 INFO - -h, --help show this help message and exit 08:42:57 INFO - -d DEST, --destination=DEST 08:42:57 INFO - Directory to install application into. [default: 08:42:57 INFO - "/builds/slave/test"] 08:42:57 INFO - --app=APP Application being installed. [default: firefox] 08:42:57 INFO - mkdir: /builds/slave/test/build/application 08:42:57 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'] 08:42:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 08:43:15 INFO - Reading from file tmpfile_stdout 08:43:15 INFO - Output received: 08:43:15 INFO - /builds/slave/test/build/application/firefox/firefox 08:43:15 INFO - Running post-action listener: _resource_record_post_action 08:43:15 INFO - [mozharness: 2017-09-21 15:43:15.379477Z] Finished install step (success) 08:43:15 INFO - [mozharness: 2017-09-21 15:43:15.379818Z] Running setup-mitmproxy step. 08:43:15 INFO - Running pre-action listener: _resource_record_pre_action 08:43:15 INFO - Running main action method: setup_mitmproxy 08:43:15 INFO - Skipping: mitmproxy is not required 08:43:15 INFO - Running post-action listener: _resource_record_post_action 08:43:15 INFO - [mozharness: 2017-09-21 15:43:15.381515Z] Finished setup-mitmproxy step (success) 08:43:15 INFO - [mozharness: 2017-09-21 15:43:15.381828Z] Running run-tests step. 08:43:15 INFO - Running pre-action listener: _resource_record_pre_action 08:43:15 INFO - Running pre-action listener: _set_gcov_prefix 08:43:15 INFO - Running main action method: run_tests 08:43:15 WARNING - Try message not found. 08:43:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:43:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:43:15 INFO - Python 2.7.3 08:43:15 INFO - Return code: 0 08:43:15 INFO - grabbing minidump binary from tooltool 08:43:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:43:15 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 0xe60090>, '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 0xe60d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe5af90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:43:15 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 08:43:15 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 08:43:15 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 08:43:15 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:43:15 INFO - Return code: 0 08:43:15 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 08:43:15 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:43:15 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:43:15 INFO - ENV: RUST_BACKTRACE is now full 08:43:15 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 08:43:15 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:43:15 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/NkyEVKxfQa-s6GWN1Ap4sg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-052', '--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 08:43:15 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/NkyEVKxfQa-s6GWN1Ap4sg/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-052 --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 08:43:15 INFO - Using env: {'DISPLAY': ':0', 08:43:15 INFO - 'HOME': '/home/cltbld', 08:43:15 INFO - 'LANG': 'en_US.UTF-8', 08:43:15 INFO - 'LANGUAGE': 'en_US:en', 08:43:15 INFO - 'LOGNAME': 'cltbld', 08:43:15 INFO - 'MAIL': '/var/mail/cltbld', 08:43:15 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:43:15 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 08:43:15 INFO - 'MOZ_ACCELERATED': '1', 08:43:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:43:15 INFO - 'MOZ_NO_REMOTE': '1', 08:43:15 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:43:15 INFO - 'MOZ_WEBRENDER': '1', 08:43:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:43:15 INFO - 'NO_EM_RESTART': '1', 08:43:15 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:43:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:43:15 INFO - 'PWD': '/builds/slave/test', 08:43:15 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:43:15 INFO - 'RUST_BACKTRACE': 'full', 08:43:15 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 08:43:15 INFO - 'SHELL': '/bin/bash', 08:43:15 INFO - 'SHLVL': '1', 08:43:15 INFO - 'TERM': 'linux', 08:43:15 INFO - 'TMOUT': '86400', 08:43:15 INFO - 'USER': 'cltbld', 08:43:15 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1506006346.323149-1094679459', 08:43:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:43:15 INFO - '_': '/tools/buildbot/bin/python'} 08:43:15 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/NkyEVKxfQa-s6GWN1Ap4sg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-052', '--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 08:43:15 INFO - mozversion application_buildid: 20170921144305 08:43:15 INFO - mozversion application_changeset: 835a92b19e3d7666890153a84bc635bc43a44cb2 08:43:15 INFO - mozversion application_display_name: Nightly 08:43:15 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:43:15 INFO - mozversion application_name: Firefox 08:43:15 INFO - mozversion application_remotingname: firefox 08:43:15 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 08:43:15 INFO - mozversion application_vendor: Mozilla 08:43:15 INFO - mozversion application_version: 58.0a1 08:43:15 INFO - mozversion platform_buildid: 20170921144305 08:43:15 INFO - mozversion platform_changeset: 835a92b19e3d7666890153a84bc635bc43a44cb2 08:43:15 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 08:43:15 INFO - mozversion platform_version: 58.0a1 08:43:15 INFO - using testdate: 1506008595 08:43:15 INFO - actual date: 1506008595 08:43:15 INFO - starting webserver on 'localhost:36718' 08:43:15 INFO - SUITE-START | Running 2 tests 08:43:15 INFO - TEST-START | dromaeo_css 08:43:15 INFO - Initialising browser for dromaeo_css test... 08:43:15 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:36718/getInfo.html -profile /tmp/tmpnwOwP4/profile 08:43:15 INFO - TEST-INFO | started process 3330 (/builds/slave/test/build/application/firefox/firefox http://localhost:36718/getInfo.html) 08:43:21 INFO - TEST-INFO | 3330: exit 0 08:43:21 INFO - Browser initialized. 08:43:21 INFO - Running cycle 1/1 for dromaeo_css test... 08:43:21 INFO - Using env: {'DISPLAY': ':0', 08:43:21 INFO - 'HOME': '/home/cltbld', 08:43:21 INFO - 'JSGC_DISABLE_POISONING': '1', 08:43:21 INFO - 'LANG': 'en_US.UTF-8', 08:43:21 INFO - 'LANGUAGE': 'en_US:en', 08:43:21 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 08:43:21 INFO - 'LOGNAME': 'cltbld', 08:43:21 INFO - 'MAIL': '/var/mail/cltbld', 08:43:21 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:43:21 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 08:43:21 INFO - 'MOZ_ACCELERATED': '1', 08:43:21 INFO - 'MOZ_CRASHREPORTER': '1', 08:43:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:43:21 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 08:43:21 INFO - 'MOZ_NO_REMOTE': '1', 08:43:21 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:43:21 INFO - 'MOZ_WEBRENDER': '1', 08:43:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:43:21 INFO - 'NO_EM_RESTART': '1', 08:43:21 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:43:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:43:21 INFO - 'PWD': '/builds/slave/test', 08:43:21 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:43:21 INFO - 'RUST_BACKTRACE': 'full', 08:43:21 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 08:43:21 INFO - 'SHELL': '/bin/bash', 08:43:21 INFO - 'SHLVL': '1', 08:43:21 INFO - 'TERM': 'linux', 08:43:21 INFO - 'TMOUT': '86400', 08:43:21 INFO - 'USER': 'cltbld', 08:43:21 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1506006346.323149-1094679459', 08:43:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:43:21 INFO - '_': '/tools/buildbot/bin/python'} 08:43:21 INFO - TEST-INFO | started process 3578 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpnwOwP4/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 08:43:22 INFO - PID 3578 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 08:43:22 INFO - PID 3578 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 08:43:22 INFO - PID 3578 | 08:43:22 INFO - PID 3578 | (/builds/slave/test/build/application/firefox/firefox:3637): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 08:43:22 INFO - PID 3578 | 08:43:33 INFO - PID 3578 | RSS: Main: 192290816 08:43:33 INFO - PID 3578 | 08:46:31 INFO - PID 3578 | Cycle 1(1): loaded http://localhost:36718/tests/dromaeo/cssquery-dojo.html (next: http://localhost:36718/tests/dromaeo/cssquery-ext.html) 08:46:32 INFO - PID 3578 | RSS: Main: 193683456 08:46:32 INFO - PID 3578 | 08:49:34 INFO - PID 3578 | Cycle 1(1): loaded http://localhost:36718/tests/dromaeo/cssquery-ext.html (next: http://localhost:36718/tests/dromaeo/cssquery-jquery.html) 08:49:35 INFO - PID 3578 | RSS: Main: 188772352 08:49:35 INFO - PID 3578 | 08:52:37 INFO - PID 3578 | Cycle 1(1): loaded http://localhost:36718/tests/dromaeo/cssquery-jquery.html (next: http://localhost:36718/tests/dromaeo/cssquery-mootools.html) 08:52:38 INFO - PID 3578 | RSS: Main: 193843200 08:52:38 INFO - PID 3578 | 08:55:40 INFO - PID 3578 | Cycle 1(1): loaded http://localhost:36718/tests/dromaeo/cssquery-mootools.html (next: http://localhost:36718/tests/dromaeo/cssquery-prototype.html) 08:55:40 INFO - PID 3578 | RSS: Main: 192143360 08:55:40 INFO - PID 3578 | 08:58:44 INFO - PID 3578 | Cycle 1(1): loaded http://localhost:36718/tests/dromaeo/cssquery-prototype.html (next: http://localhost:36718/tests/dromaeo/cssquery-yui.html) 08:58:45 INFO - PID 3578 | RSS: Main: 193359872 08:58:45 INFO - PID 3578 | 09:01:47 INFO - PID 3578 | Cycle 1(1): loaded http://localhost:36718/tests/dromaeo/cssquery-yui.html (next: http://localhost:36718/tests/dromaeo/cssquery-dojo.html) 09:01:47 INFO - PID 3578 | RSS: Main: 196321280 09:01:47 INFO - PID 3578 | 09:01:47 INFO - PID 3578 | __start_tp_report 09:01:47 INFO - PID 3578 | _x_x_mozilla_page_load 09:01:47 INFO - PID 3578 | _x_x_mozilla_page_load_details 09:01:47 INFO - PID 3578 | |i|pagename|runs| 09:01:47 INFO - PID 3578 | |0;dojo.html;3154.3375891062874;3161.7786754927165;3181.347823696141;3185.362927414518;3210.2134976930643;6219.968900155504;6351.936480635192;6415.9037614435765;6438.903416448757;6490.156279683646;6307.653079080649;6328.082428047942;6415.582987105847;6465.90301145482;6490.188726409199;5788.652680839142;5821.359650438449;5838.737256823453;5942.346341902409;5971.10433434986;5820.796272130477;5913.852153696149;5939.168516407706;5940.079287710412;5983.132445795379;5763.971180144114;5788.131780232957;5825.359210486843;5894.793682221123;5911.379305172959;5855.502282306009;5863.061910094364;5882.852928676774;5891.027980383232;5899.115132730082;5752.137179423078;5815.505682017034;5838.678872662002;5850.5612079094235;5892.705364731789;6344.206974128234;6349.8412539686415;6410.647414392207;6437.678116094224;6466.321036291169;6603.14159159311;6707.228668703113;6755.189377274709;6812.897806532906;6816.182058153054;6867.2102708188695;6884.896726549106;6909.619970901598;6944.51388402812;6949.027136200936;6252.499800016033;6253.218347706536;6316.936830631681;6325.051242313643;6331.33520980295;7055.682494287769;7068.399186069241;7088.751893683696;7096.858062838714;7145.499815013003;6579.671016449159;6620.768273110418;6689.933100668931;6766.526343156031;6797.898031529531;6688.063671086052;6709.899351509731;6771.153605799275;6793.286704896013;6860;6780.423663988518;6797.966010169917;6804.795856124324;6805.795826125224;6810.353016463428;6793.150856142982;6812.48906332027;6816.625085620339;6831.4534837212905;6893.448524118058;5339.492748188916;5339.652922560022;5401.7299135043095;5416.295881535375;5433.538149257279;4546.977265113654;4634.513376095529;4651.67438279324;4688.812447502061;4729.196036673705;5827;5837;5868.618539794899;5871.266091738533;5885.2349194605285;6711.362420570038;6843.247242803288;6843.281455447206;6866.965665171742;6905.240423553405;5727.4845263926445;5819.039858423397;5825.213596164548;5842.1236814477315;5887.411258874078;5692.402297758758;5829.708514574254;5840.532757379485;5902.8819423611285;5904.4685978261305;6548.9017664735065;6567.671616419256;6570.474362051024;6616.669166541654;6622.801315960529;6774.898376524455;6843.418309443753;6851.07510486088;6860.279670634512;6866.073080134118;6525.021246813016;6527.249366322743;6589.7364105435245;6615.6030638161865;6689.933100668931;6493.870122597427;6699.832504187434;6748.898766518604;6853.451723861979;6862.142232220973;6703.262641109376;6760.053592497153;6769.323067693388;6782.525223234326;6804.55770374924;6484.448821850196;6539.967300163468;6553.705083271359;6555.672216389257;6593.241777195684;6654.334566543307;6668.166479190101;6668.533202675766;6673.799786006428;6709.7316107355155;6495.350464953467;6512.413882750575;6544.214694236532;6567.605943643397;6599.373059559334;6536.738530458729;6632.436242919406;6712.328767123444;6761.3238676132;6771.525993180627;6376.681165941777;6424.775132870327;6429.54993150475;6450.516211284079;6516.446102081376 09:01:47 INFO - PID 3578 | |1;ext.html;861.3152543727736;867.0938868882017;872.136584781067;877.1579283887468;895.6641259527677;39410.408843867364;40741.796291018574;41142.38286425702;41196.79401602992;41324.380134297935;37014;37048.07379815506;37217.44173837395;37238.62761372385;37438.438423423606;7056.294370562941;7177.031100801377;7226.674799634029;7227.927720722778;7237.782866513987;7147.2138064812825;7193.85612287754;7204.0634717486655;7219.0976127984;7240.0587923569865;7144.499885008052;7738.883916741253;8023.358131349507;8026.959865200666;8104.716334928279;7931;7978.042634883822;7981.48120372174;7988.920110798876;7989.280964713145;7873;7904.723334683316;7923.811428285746;7977.441579089466;7992.160823113548;37445.438318425244;38973;38984.41523377151;39251.41122883159;39291.21417571661;3481.738869584766;3528.100334414708;3532.0816587687127;3538.044727923472;3541.752077354586;31957.201069973205;32176.839115804505;32197.51703724446;32268.031959041266;32269.6773032269;5265.86835329116;5273.1035723927025;5284.735763211863;5313.787448502056;5359;177626;179372.9999999987;180687;181164;181299;48189;48283.03433931372;48331.27503087457;48350.516494835305;48530;50597.74701126544;50637;50749.49250507448;50797.74601126971;50811.49188508068;14704.632384190267;14855.554333370017;15071.09573425598;15076.698466030837;15085.622859428602;11994.64016079519;12007.399630018464;12026.15816892809;12112.212706174247;12138.08964327679;14076.155430674193;14095.85904140966;14211.64470888236;14393.568192954228;14406.42374305016;5580.246666700024;5613.326400831927;5642.774289028393;5747.109198074305;5824.194499705105;993.4337427666161;1006.6325791086201;1019.8623185869961;1021.1269364693229;1031.4687935713114;22970;22996.310110696704;23018.424539386648;23056.19303324409;23590.764092358855;1083.5936523803573;1090.6291860767378;1100.6863044032573;1110.8889111088988;1118.2675347647305;12220.694482637828;12269;12292.139550231397;12432.502699891904;12459.314737689332;223039;223599;223869;224340;224819;43522.129557408676;44497.77751111224;52654.21018684723;52869.2069618956;52956.47043529515;1379.379279324308;1405.6134562995053;1414.6463384153963;1419.815423994874;1421.3745951781184;27496;27550.44899102047;27855.582166267926;27913.441731164858;28061.71938280673;35384;35412.291754165286;35428;35611.10972225715;35695.82152089223;178797;178936;179547;180675;181382;32557.34885302328;33538.83230583832;33801.15497112592;33867.830660846535;34015.829920850236;42446.575534244264;43229.56770432255;43276.78361608172;43395;43561.56438435575;31518.684813151576;31853.36293274168;32046.519302210017;32373.676263237066;32571.511427329082;31856.36287274288;31867.521987170676;31886.6811331893;31922.20194495156;32181.678183217868;34454.13864653404;35488.46767298441;35616.643833561335;35660.46509302415;36217.27565448729;30414.39171216519;31041.844790776806;31099.378012440076;31170.22074448157;31871;22939.655905161104;23015;23353;23362.065517379113;23448 09:01:47 INFO - PID 3578 | |2;jquery.html;582.6620560075155;586.9325027621825;593.3058321763539;596.0261616807738;603.6709993053786;21567;22391.888040559796;22392.88803555984;22444;22561.210357637487;23757.406064848386;24049;24851.50296994059;25005.24984250471;25257.74742252577;6439.195100612424;6587.110740050104;6607.372299631527;6644.734210631593;6670.566413183152;6430.710618022177;6564.343565643445;6590.538662293618;6625.73497060117;6625.933740662604;6283.937160628381;6507.772227972022;6535.57518761279;6584.8024559263295;6587.93412065878;6582.8683426331445;6630.6021638701595;6632.867342653168;6633.369829866155;6633.601983880959;6106.419890110433;6137.78517751881;6142.832861756252;6155.784547540859;6159.87680246397;27479.17562473129;27553.586696199574;28781;28839.56740648892;28973;2036.0735865181455;2048.9077991490417;2064.556120434099;2066.927657531979;2073.6060148571723;19809.10859011348;19970.70043949342;19971.600567988706;20057.79942200574;20086.899565502077;2378.1200955646523;2387.689600351943;2432.7567243275707;2434.440078781872;2488.713797913245;121328;126905;135033;142060.28969855086;143061.28469357692;14685.559433216808;14726.190059546614;14768.335424905905;14910.70178596422;14984.250787460584;22971.88514057419;22979.42551436194;23275.650865237036;23280.185193518144;23356.18253361124;9646.758831029281;9797.216222702167;9803.313768036312;9826.06652368024;10087.831116309882;8910;8967.461952282883;8990.145936136056;9030;9088.363814533051;9224.123708247706;9257.583408746623;9378.296627752945;9416.340856140005;9421;2615.9476810463684;2693;2737.5619900816173;2744.108164846433;2845.374017716099;929.702495201529;947.8436058050482;968.0319680319681;968.0609808485758;970.9320347575602;18597.628047438975;18825.81174188268;18930.148143333583;19073;19079.332223372116;1014.2342531388754;1018.8013337399136;1022.4325499347784;1030.3508789462592;1033.4625994482826;10497.265191436527;10513.894861051445;10534.73663158412;10600.628977985734;10724.088452481627;436031;454699;455723;455840;463944;109917;110314.44842775895;110872;111637.44181279204;112104.43947780372;1177.0583533173426;1187.5724739093919;1191.118572256524;1194.313269869811;1196.6589521986018;16772.664546709242;17017.91491042537;17079.829201707824;17157.57106072358;17599.912000439916;53913;54047;54759.726201368736;54911.72544137254;55934.72032639811;67075.66462167658;68489.65755171192;69022;69266;70839.64580177066;14090.506832260822;14308;14479.348429320495;14511.274436278354;14642.853571464575;21624.67562986588;22003.33989980303;22060.448488787908;22120.778792212517;22153.224637137624;17660.735088973932;17684.115794210495;17690.823091768918;17836.821631783518;17873;15975;16094.919525402298;16347.918260408622;16511.669766604362;16900.323987040872;17661.470155895342;17891.642167156846;17964.281428743227;18149.364772232908;18173.818261817214;14292.14247145133;14292.213928234047;14356;14359;14382.28088595587;16307;17411.7388239174;17468.650626987645;17664.646707066044;17730.468085957444 09:01:47 INFO - PID 3578 | |3;mootools.html;1317.1965101288215;1361.14248023745;1367.7469668111394;1373.4300265389857;1377.063596754208;14862.851371486282;15692.843071569281;15704.979176353529;15774.68450630988;15791.684166316667;15294.694106117871;15447.922760386182;15459.381624734997;15459.381624735026;15464.84535154648;2700.6759188897263;2805.3126983888974;2826.222788733094;2829.660440747113;2835.277004363895;2720.469508445854;2778.472090302846;2791.8185317954267;2793.9022134225306;2801.243664210662;2673.5321318769234;2706.3910620110414;2836.446892855894;2844.104107206234;2855.6430446194227;2740.068376751904;2763;2774.2648198227484;2776.54187059135;2778.5554311310193;2685.9597106043425;2714.6878109017516;2719;2726.509228338898;2728.945421091587;12413.882750552493;12844.422001009883;12926.676833079155;14500.347484363123;15013.624659383493;7918.643661035209;8348.290395316406;8410.705625303084;8416.242538171595;8437.156284371575;5328.44051374604;5606.383297837235;5630.15547667849;5640.407757185478;5683.602147849652;1948.5615736459324;1949.707543868417;1992.930247441347;2015.7177995080701;2035.3893831850385;27848;27882.3029424264;28011.299717507023;28017.159485215478;28051;6821;7002.194747603989;7079.1505019398;7203.351698347174;7266.091738532684;17649.7352539712;17700.9114954427;17729.46811595654;17744.29022839098;17773.20020599076;8170.673173073011;8282.296004839656;8298.419110662197;8305.543195124204;8326.833463330699;7155.89266161008;7304.488685791944;7464.328210461084;7547.320741133214;7658.234176582298;5218.165093585084;5438.755256013489;5439.864003399946;5465.07093794056;5469.15228139639;1563.3902777916621;1573.6065983504125;1599.8240193578697;1609.6378314879294;1615.4830454254761;819.266756253162;835.4527784301292;850.9361797865184;855.4354126276628;859.2481578618708;8135.877961830577;8242.752717418487;8268.297194738394;8321.292690121287;8352.916470835335;950.8383574792302;975.8194733974237;979.0552117206929;984.4831463481586;989.2679417231341;4861.246506791453;5018.949810501921;5107;5151.8196863109615;5158.484151584811;21133;22134;22541.211057612905;22741.88629056844;22841;21903.780962190172;21996.780032199793;22625.88687056587;22698.205562805222;22974;11213.719657008476;11444.427778611036;11471.483783229774;11567.76864462706;11596.594119205787;8040.195980401913;8193.139720329518;8194.50832950025;8260.669573217005;8272.545010024507;18956.810431895505;19376.806231938062;19403.8059619402;19759;20102.69845952341;18775.71836422482;19902.104405301507;20223;20494.69257961102;20699.896500518003;11197.384143872165;11336.829947550628;11418.200725949104;11439;11454.255473394203;13651;13959.302034898012;13965.301734913011;14009.43962241499;14087.225202613954;11008.834867477155;11065.446727663424;11091.389973551532;11108.333499990027;11108.944455277671;11176.720581985515;11206.775864482828;11208.607698730506;11300.604478843201;11399.373034483184;10169.9491502542;10214.131798797185;10295.3822770634;10311.742206444898;10397.272190946562;7160.355567998905;7227.819304517429;7239.348458638748;7318.414526837733;7393.927880457196;6779.762708305186;6895.241523432318;6953.339432754083;6959.756408525677;7051 09:01:47 INFO - PID 3578 | |4;prototype.html;491.27782160224456;514.25176368384;539.9784008639655;540.4568408749208;545.5962587685111;6264.874702505953;6288.937110628893;6321.273053598838;6363.872722545546;6442.420182183602;6176.320604733476;6208.906866397007;6262.780802671908;6273.2472103347545;6279.717412716427;5208.60404214062;5225.712585807779;5264.526192642661;5274.4198138204765;5312.618719133034;5155.639105262633;5193.143131383317;5231.73841307935;5236.73816309183;5255.737213139347;4868.902621947559;4984.4018717753925;5021.497850214986;5049.2678561608545;5061.16490779021;4962.741952411644;5021.899562008739;5040.6471546991725;5046.470120637336;5068.569171620398;4378.8029538670835;4527.86416407505;4537.954620453786;4538.682292239545;4543.295789152654;6040.818775436744;6060.696965151769;6133.907991380132;6137.7851775187655;6196.96901515494;5645.774169033234;5670.886582268373;5696.23100881384;5717.113847353667;5762.510186634141;6554.934450655528;6577.440917522016;6604.6697665117035;6606.8017959461295;6635.601863888182;4641.141388843041;4901.975490122562;4903.63222758291;4927.6550641455115;4939.308496810413;6669.566478178951;6782.8304292392595;6883.386222233367;6897.689603967783;6911.7926462206215;4999.750012499433;5001.224810154432;5025.120603894304;5049.697018178921;5066.214736715815;5035.974820125949;5055.469175736568;5073.239014147907;5075.847724568269;5126.384833819965;5699.145128230799;5714.314282286152;5759.6832174229985;5799.405163579965;5804.361520232771;6572.474202063823;6635.4359879410795;6651.800445986628;6678.762614524188;6681.231658359254;1755.5786611213216;1765.2233017472272;1769.5576105973507;1776.8845025073329;1785.259117466237;1168;1228.9201201921846;1240.8635050144476;1248.3134276148264;1263.886250237483;5505.284313039359;5604.551635869121;5610.410906854721;5616.522595579339;5653.236813030188;6586.50601204902;6605.240397354366;6721.22705888829;6729.803343411841;6749.831254218586;5543.833684989457;5598.692071936082;5601.350878770692;5661.377248502661;5694.1458781183155;3417.9487307690406;3450.240946991658;3471.264367816092;3497.527833742412;3540.0795793093466;6347.143785296635;6372.617642941438;6435.742570297136;6466.967665161644;6521.184851893513;6748.358905903931;6754.966225168842;6792.966035169891;6830.419414349736;6965.616891071039;6427.517936154807;6488.870222595616;6512.73949042033;6536.607803531803;6547.377999090078;6648.736740019381;6682.164729408824;6741.662916854236;6759.695813688494;6805.251422343439;6497.8700425992165;6611.603303801788;6699.296573859675;6713.966430167818;6726.3520437493125;6373.776917807854;6492.805215843343;6550.508711846726;6610.305917878553;6649.534532582673;5035.571976382049;5048.949510504995;5055.898882022412;5095.261187127771;5139.6145289102715;6364.681765911593;6571.277159512354;6599.406053455212;6847.21257055445;6914.377706006282;6252.968735156295;6467.870642587216;6479.967600161969;6520.315366886598;6533.060122944599;6366.4588509977175;6432.581882177644;6491;6504.837379065372;6505.7072431741635;6326.177596912372;6395.776147834804;6409.166808314889;6449.451796597342;6470.838229044311;6529.4776417885505;6552.868942621216;6602.141721576164;6606.240282367589;6615.66921653925;6323.1463752394675;6371.96814015927;6410.839729006812;6430.742770289136;6437.517186210959 09:01:47 INFO - PID 3578 | |5;yui.html;908.513945039404;914.9130832570904;927.2767241551592;932.7901222225;934.177923427384;19581.216751329946;20353.18587256512;20487;20633;20725.585488290242;16211.756823647655;16440.177991100456;16532.83467165328;16552.917235413806;16591.336346546155;950.6102497975814;959.9376040557377;960.2749923807515;962.0475729028282;963.1283688262134;978.7993461340408;980.4019548075668;981.0532835813439;981.3817295104075;982.4252812104892;1003.0671475527756;1005.9849102263472;1018.0430395428274;1024.4416792847917;1046.6075221791828;1028.4754775064696;1029.078974317985;1030.2993964104405;1032.189731061121;1032.8554002439664;740.7111226621622;745.8694728422504;746.8020974441778;751.6579956119953;752.4695089961564;18970.905145474324;19003.42989710297;20528.58942821135;20887.37337879866;21134;21890.89054554733;22253.44366390837;22561;22673.433164170863;22682.886585567132;17669.911650441794;18008.189631466616;18020.36928707502;18063.638727225512;18199.36302229429;3863.690904727615;3878.5151856017997;3912.080661044652;3913.4521167036637;3915.6671682906986;107153;123991;148556.2572187132;149482.99999999892;151152.2442387792;17592;17924;18076.276948922157;18165.636687266444;18168.45494635163;31777.84111079476;31857.8407107963;32080;32085.19787005297;32353;8710.782230444289;8883.289336853037;8978.461292322481;9160.81678366429;9197.862032069525;4155.8961025974595;4223.007593215591;4228.70399072062;4253.106847562047;4266.807993640232;5737;5773.336066352424;5856.355800861902;5858.414158584193;5858.853528661817;1081.0702795595782;1082.5236895765995;1102.9081209602437;1105.795427845851;1110.2062025651699;635.3646353646353;660.38254232292;664.4319107163402;675.4360109308782;678.5657179405184;11000.889991100146;11345.432728363548;11396.202265841477;11590.362530060927;11817.881821181678;684.910961574992;693.6982412650514;701.5229643842134;709.3474003916409;709.8438343564408;5423.864403389947;5458.344998600114;5557.277553917965;5578.330600327987;5661.122526008393;126191;127126;128136.35931820281;129811.70188297996;131945;106572;106944;110416.4479177599;112190.43904780423;113877;96438;97000;97356.51321743487;97697.51151244341;100656.49671751594;11704.70738231551;12006.39968001579;12084.093692973238;12215.389230538616;12268.447919843808;44209.33685994777;44286;45634;46285.611431657104;46471.302930456746;57335.426645734675;58656.70671646614;58814.705926470095;59218.703906480194;59829;18779;18932.716009260148;18985.620287594447;19057.90471047636;19247;20697.068631911297;21061.473463163544;21071.789282106984;21217.469563261042;21600.13599456004;16969.406070787463;17487.912560437115;17553.912230438767;17583.120843958008;17774;18743.250269989047;18876;18935.71596426027;19001.334953276568;19070.046497674783;16769;17126.486205413858;17352.65294694124;17358;17511.474655760347;13411.664708382368;13599.86400135986;13739.656508587364;13783.793243101562;14062.648433789238;13126.212427254395;13166.802497962346;13311.935045196167;13325;13369 09:01:47 INFO - PID 3578 | __end_tp_report 09:01:47 INFO - PID 3578 | __start_cc_report 09:01:47 INFO - PID 3578 | _x_x_mozilla_cycle_collect,289 09:01:47 INFO - PID 3578 | __end_cc_report 09:01:47 INFO - PID 3578 | __startTimestamp1506009707359__endTimestamp 09:01:47 INFO - PID 3578 | 09:01:47 INFO - PID 3578 | ------- Summary: start ------- 09:01:47 INFO - PID 3578 | Number of tests: 6 09:01:47 INFO - PID 3578 | 09:01:47 INFO - PID 3578 | [#0] dojo.html Cycles:165 Average:6260.67 Median:6512.41 stddev:753.61 (11.6%) stddev-sans-first:715.44 09:01:47 INFO - PID 3578 | Values: 3154.3 3161.8 3181.3 3185.4 3210.2 6220.0 6351.9 6415.9 6438.9 6490.2 6307.7 6328.1 6415.6 6465.9 6490.2 5788.7 5821.4 5838.7 5942.3 5971.1 5820.8 5913.9 5939.2 5940.1 5983.1 5764.0 5788.1 5825.4 5894.8 5911.4 5855.5 5863.1 5882.9 5891.0 5899.1 5752.1 5815.5 5838.7 5850.6 5892.7 6344.2 6349.8 6410.6 6437.7 6466.3 6603.1 6707.2 6755.2 6812.9 6816.2 6867.2 6884.9 6909.6 6944.5 6949.0 6252.5 6253.2 6316.9 6325.1 6331.3 7055.7 7068.4 7088.8 7096.9 7145.5 6579.7 6620.8 6689.9 6766.5 6797.9 6688.1 6709.9 6771.2 6793.3 6860.0 6780.4 6798.0 6804.8 6805.8 6810.4 6793.2 6812.5 6816.6 6831.5 6893.4 5339.5 5339.7 5401.7 5416.3 5433.5 4547.0 4634.5 4651.7 4688.8 4729.2 5827.0 5837.0 5868.6 5871.3 5885.2 6711.4 6843.2 6843.3 6867.0 6905.2 5727.5 5819.0 5825.2 5842.1 5887.4 5692.4 5829.7 5840.5 5902.9 5904.5 6548.9 6567.7 6570.5 6616.7 6622.8 6774.9 6843.4 6851.1 6860.3 6866.1 6525.0 6527.2 6589.7 6615.6 6689.9 6493.9 6699.8 6748.9 6853.5 6862.1 6703.3 6760.1 6769.3 6782.5 6804.6 6484.4 6540.0 6553.7 6555.7 6593.2 6654.3 6668.2 6668.5 6673.8 6709.7 6495.4 6512.4 6544.2 6567.6 6599.4 6536.7 6632.4 6712.3 6761.3 6771.5 6376.7 6424.8 6429.5 6450.5 6516.4 09:01:47 INFO - PID 3578 | 09:01:47 INFO - PID 3578 | [#1] ext.html Cycles:180 Average:36352.39 Median:3504.92 stddev:50624.63 (1444.4%) stddev-sans-first:50696.50 09:01:47 INFO - PID 3578 | Values: 861.3 867.1 872.1 877.2 895.7 39410.4 40741.8 41142.4 41196.8 41324.4 37014.0 37048.1 37217.4 37238.6 37438.4 7056.3 7177.0 7226.7 7227.9 7237.8 7147.2 7193.9 7204.1 7219.1 7240.1 7144.5 7738.9 8023.4 8027.0 8104.7 7931.0 7978.0 7981.5 7988.9 7989.3 7873.0 7904.7 7923.8 7977.4 7992.2 37445.4 38973.0 38984.4 39251.4 39291.2 3481.7 3528.1 3532.1 3538.0 3541.8 31957.2 32176.8 32197.5 32268.0 32269.7 5265.9 5273.1 5284.7 5313.8 5359.0 177626.0 179373.0 180687.0 181164.0 181299.0 48189.0 48283.0 48331.3 48350.5 48530.0 50597.7 50637.0 50749.5 50797.7 50811.5 14704.6 14855.6 15071.1 15076.7 15085.6 11994.6 12007.4 12026.2 12112.2 12138.1 14076.2 14095.9 14211.6 14393.6 14406.4 5580.2 5613.3 5642.8 5747.1 5824.2 993.4 1006.6 1019.9 1021.1 1031.5 22970.0 22996.3 23018.4 23056.2 23590.8 1083.6 1090.6 1100.7 1110.9 1118.3 12220.7 12269.0 12292.1 12432.5 12459.3 223039.0 223599.0 223869.0 224340.0 224819.0 43522.1 44497.8 52654.2 52869.2 52956.5 1379.4 1405.6 1414.6 1419.8 1421.4 27496.0 27550.4 27855.6 27913.4 28061.7 35384.0 35412.3 35428.0 35611.1 35695.8 178797.0 178936.0 179547.0 180675.0 181382.0 32557.3 33538.8 33801.2 33867.8 34015.8 42446.6 43229.6 43276.8 43395.0 43561.6 31518.7 31853.4 32046.5 32373.7 32571.5 31856.4 31867.5 31886.7 31922.2 32181.7 34454.1 35488.5 35616.6 35660.5 36217.3 30414.4 31041.8 31099.4 31170.2 31871.0 22939.7 23015.0 23353.0 23362.1 23448.0 09:01:47 INFO - PID 3578 | 09:01:47 INFO - PID 3578 | [#2] jquery.html Cycles:180 Average:33138.64 Median:22766.55 stddev:76663.98 (336.7%) stddev-sans-first:76840.07 09:01:47 INFO - PID 3578 | Values: 582.7 586.9 593.3 596.0 603.7 21567.0 22391.9 22392.9 22444.0 22561.2 23757.4 24049.0 24851.5 25005.2 25257.7 6439.2 6587.1 6607.4 6644.7 6670.6 6430.7 6564.3 6590.5 6625.7 6625.9 6283.9 6507.8 6535.6 6584.8 6587.9 6582.9 6630.6 6632.9 6633.4 6633.6 6106.4 6137.8 6142.8 6155.8 6159.9 27479.2 27553.6 28781.0 28839.6 28973.0 2036.1 2048.9 2064.6 2066.9 2073.6 19809.1 19970.7 19971.6 20057.8 20086.9 2378.1 2387.7 2432.8 2434.4 2488.7 121328.0 126905.0 135033.0 142060.3 143061.3 14685.6 14726.2 14768.3 14910.7 14984.3 22971.9 22979.4 23275.7 23280.2 23356.2 9646.8 9797.2 9803.3 9826.1 10087.8 8910.0 8967.5 8990.1 9030.0 9088.4 9224.1 9257.6 9378.3 9416.3 9421.0 2615.9 2693.0 2737.6 2744.1 2845.4 929.7 947.8 968.0 968.1 970.9 18597.6 18825.8 18930.1 19073.0 19079.3 1014.2 1018.8 1022.4 1030.4 1033.5 10497.3 10513.9 10534.7 10600.6 10724.1 436031.0 454699.0 455723.0 455840.0 463944.0 109917.0 110314.4 110872.0 111637.4 112104.4 1177.1 1187.6 1191.1 1194.3 1196.7 16772.7 17017.9 17079.8 17157.6 17599.9 53913.0 54047.0 54759.7 54911.7 55934.7 67075.7 68489.7 69022.0 69266.0 70839.6 14090.5 14308.0 14479.3 14511.3 14642.9 21624.7 22003.3 22060.4 22120.8 22153.2 17660.7 17684.1 17690.8 17836.8 17873.0 15975.0 16094.9 16347.9 16511.7 16900.3 17661.5 17891.6 17964.3 18149.4 18173.8 14292.1 14292.2 14356.0 14359.0 14382.3 16307.0 17411.7 17468.7 17664.6 17730.5 09:01:47 INFO - PID 3578 | 09:01:47 INFO - PID 3578 | [#3] mootools.html Cycles:180 Average:9506.23 Median:2719.73 stddev:6918.02 (254.4%) stddev-sans-first:6910.07 09:01:47 INFO - PID 3578 | Values: 1317.2 1361.1 1367.7 1373.4 1377.1 14862.9 15692.8 15705.0 15774.7 15791.7 15294.7 15447.9 15459.4 15459.4 15464.8 2700.7 2805.3 2826.2 2829.7 2835.3 2720.5 2778.5 2791.8 2793.9 2801.2 2673.5 2706.4 2836.4 2844.1 2855.6 2740.1 2763.0 2774.3 2776.5 2778.6 2686.0 2714.7 2719.0 2726.5 2728.9 12413.9 12844.4 12926.7 14500.3 15013.6 7918.6 8348.3 8410.7 8416.2 8437.2 5328.4 5606.4 5630.2 5640.4 5683.6 1948.6 1949.7 1992.9 2015.7 2035.4 27848.0 27882.3 28011.3 28017.2 28051.0 6821.0 7002.2 7079.2 7203.4 7266.1 17649.7 17700.9 17729.5 17744.3 17773.2 8170.7 8282.3 8298.4 8305.5 8326.8 7155.9 7304.5 7464.3 7547.3 7658.2 5218.2 5438.8 5439.9 5465.1 5469.2 1563.4 1573.6 1599.8 1609.6 1615.5 819.3 835.5 850.9 855.4 859.2 8135.9 8242.8 8268.3 8321.3 8352.9 950.8 975.8 979.1 984.5 989.3 4861.2 5018.9 5107.0 5151.8 5158.5 21133.0 22134.0 22541.2 22741.9 22841.0 21903.8 21996.8 22625.9 22698.2 22974.0 11213.7 11444.4 11471.5 11567.8 11596.6 8040.2 8193.1 8194.5 8260.7 8272.5 18956.8 19376.8 19403.8 19759.0 20102.7 18775.7 19902.1 20223.0 20494.7 20699.9 11197.4 11336.8 11418.2 11439.0 11454.3 13651.0 13959.3 13965.3 14009.4 14087.2 11008.8 11065.4 11091.4 11108.3 11108.9 11176.7 11206.8 11208.6 11300.6 11399.4 10169.9 10214.1 10295.4 10311.7 10397.3 7160.4 7227.8 7239.3 7318.4 7393.9 6779.8 6895.2 6953.3 6959.8 7051.0 09:01:47 INFO - PID 3578 | 09:01:47 INFO - PID 3578 | [#4] prototype.html Cycles:180 Average:5521.47 Median:6202.94 stddev:1537.31 (24.8%) stddev-sans-first:1494.54 09:01:47 INFO - PID 3578 | Values: 491.3 514.3 540.0 540.5 545.6 6264.9 6288.9 6321.3 6363.9 6442.4 6176.3 6208.9 6262.8 6273.2 6279.7 5208.6 5225.7 5264.5 5274.4 5312.6 5155.6 5193.1 5231.7 5236.7 5255.7 4868.9 4984.4 5021.5 5049.3 5061.2 4962.7 5021.9 5040.6 5046.5 5068.6 4378.8 4527.9 4538.0 4538.7 4543.3 6040.8 6060.7 6133.9 6137.8 6197.0 5645.8 5670.9 5696.2 5717.1 5762.5 6554.9 6577.4 6604.7 6606.8 6635.6 4641.1 4902.0 4903.6 4927.7 4939.3 6669.6 6782.8 6883.4 6897.7 6911.8 4999.8 5001.2 5025.1 5049.7 5066.2 5036.0 5055.5 5073.2 5075.8 5126.4 5699.1 5714.3 5759.7 5799.4 5804.4 6572.5 6635.4 6651.8 6678.8 6681.2 1755.6 1765.2 1769.6 1776.9 1785.3 1168.0 1228.9 1240.9 1248.3 1263.9 5505.3 5604.6 5610.4 5616.5 5653.2 6586.5 6605.2 6721.2 6729.8 6749.8 5543.8 5598.7 5601.4 5661.4 5694.1 3417.9 3450.2 3471.3 3497.5 3540.1 6347.1 6372.6 6435.7 6467.0 6521.2 6748.4 6755.0 6793.0 6830.4 6965.6 6427.5 6488.9 6512.7 6536.6 6547.4 6648.7 6682.2 6741.7 6759.7 6805.3 6497.9 6611.6 6699.3 6714.0 6726.4 6373.8 6492.8 6550.5 6610.3 6649.5 5035.6 5048.9 5055.9 5095.3 5139.6 6364.7 6571.3 6599.4 6847.2 6914.4 6253.0 6467.9 6480.0 6520.3 6533.1 6366.5 6432.6 6491.0 6504.8 6505.7 6326.2 6395.8 6409.2 6449.5 6470.8 6529.5 6552.9 6602.1 6606.2 6615.7 6323.1 6372.0 6410.8 6430.7 6437.5 09:01:47 INFO - PID 3578 | 09:01:47 INFO - PID 3578 | [#5] yui.html Cycles:180 Average:25134.26 Median:20507.79 stddev:35724.77 (174.2%) stddev-sans-first:35778.67 09:01:47 INFO - PID 3578 | Values: 908.5 914.9 927.3 932.8 934.2 19581.2 20353.2 20487.0 20633.0 20725.6 16211.8 16440.2 16532.8 16552.9 16591.3 950.6 959.9 960.3 962.0 963.1 978.8 980.4 981.1 981.4 982.4 1003.1 1006.0 1018.0 1024.4 1046.6 1028.5 1029.1 1030.3 1032.2 1032.9 740.7 745.9 746.8 751.7 752.5 18970.9 19003.4 20528.6 20887.4 21134.0 21890.9 22253.4 22561.0 22673.4 22682.9 17669.9 18008.2 18020.4 18063.6 18199.4 3863.7 3878.5 3912.1 3913.5 3915.7 107153.0 123991.0 148556.3 149483.0 151152.2 17592.0 17924.0 18076.3 18165.6 18168.5 31777.8 31857.8 32080.0 32085.2 32353.0 8710.8 8883.3 8978.5 9160.8 9197.9 4155.9 4223.0 4228.7 4253.1 4266.8 5737.0 5773.3 5856.4 5858.4 5858.9 1081.1 1082.5 1102.9 1105.8 1110.2 635.4 660.4 664.4 675.4 678.6 11000.9 11345.4 11396.2 11590.4 11817.9 684.9 693.7 701.5 709.3 709.8 5423.9 5458.3 5557.3 5578.3 5661.1 126191.0 127126.0 128136.4 129811.7 131945.0 106572.0 106944.0 110416.4 112190.4 113877.0 96438.0 97000.0 97356.5 97697.5 100656.5 11704.7 12006.4 12084.1 12215.4 12268.4 44209.3 44286.0 45634.0 46285.6 46471.3 57335.4 58656.7 58814.7 59218.7 59829.0 18779.0 18932.7 18985.6 19057.9 19247.0 20697.1 21061.5 21071.8 21217.5 21600.1 16969.4 17487.9 17553.9 17583.1 17774.0 18743.3 18876.0 18935.7 19001.3 19070.0 16769.0 17126.5 17352.7 17358.0 17511.5 13411.7 13599.9 13739.7 13783.8 14062.6 13126.2 13166.8 13311.9 13325.0 13369.0 09:01:47 INFO - PID 3578 | -------- Summary: end -------- 09:01:47 INFO - PID 3578 | 09:01:48 INFO - TEST-INFO | 3578: exit 0 09:01:48 INFO - TEST-OK | dromaeo_css | took 1112408ms 09:01:48 INFO - TEST-START | kraken 09:01:48 INFO - Initialising browser for kraken test... 09:01:48 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:36718/getInfo.html -profile /tmp/tmp3_Mnhz/profile 09:01:48 INFO - TEST-INFO | started process 3962 (/builds/slave/test/build/application/firefox/firefox http://localhost:36718/getInfo.html) 09:01:53 INFO - TEST-INFO | 3962: exit 0 09:01:53 INFO - Browser initialized. 09:01:53 INFO - Running cycle 1/1 for kraken test... 09:01:53 INFO - Using env: {'DISPLAY': ':0', 09:01:53 INFO - 'HOME': '/home/cltbld', 09:01:53 INFO - 'JSGC_DISABLE_POISONING': '1', 09:01:53 INFO - 'LANG': 'en_US.UTF-8', 09:01:53 INFO - 'LANGUAGE': 'en_US:en', 09:01:53 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 09:01:53 INFO - 'LOGNAME': 'cltbld', 09:01:53 INFO - 'MAIL': '/var/mail/cltbld', 09:01:53 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:01:53 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:01:53 INFO - 'MOZ_ACCELERATED': '1', 09:01:53 INFO - 'MOZ_CRASHREPORTER': '1', 09:01:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:01:53 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 09:01:53 INFO - 'MOZ_NO_REMOTE': '1', 09:01:53 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:01:53 INFO - 'MOZ_WEBRENDER': '1', 09:01:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:01:53 INFO - 'NO_EM_RESTART': '1', 09:01:53 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:01:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:01:53 INFO - 'PWD': '/builds/slave/test', 09:01:53 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:01:53 INFO - 'RUST_BACKTRACE': 'full', 09:01:53 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 09:01:53 INFO - 'SHELL': '/bin/bash', 09:01:53 INFO - 'SHLVL': '1', 09:01:53 INFO - 'TERM': 'linux', 09:01:53 INFO - 'TMOUT': '86400', 09:01:53 INFO - 'USER': 'cltbld', 09:01:53 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1506006346.323149-1094679459', 09:01:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:01:53 INFO - '_': '/tools/buildbot/bin/python'} 09:01:53 INFO - TEST-INFO | started process 4207 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp3_Mnhz/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 09:01:54 INFO - PID 4207 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 09:01:54 INFO - PID 4207 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 09:01:54 INFO - PID 4207 | 09:01:54 INFO - PID 4207 | (/builds/slave/test/build/application/firefox/firefox:4265): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 09:01:54 INFO - PID 4207 | 09:02:05 INFO - PID 4207 | RSS: Main: 188522496 09:02:05 INFO - PID 4207 | 09:02:07 INFO - PID 4207 | Cycle 1(1): loaded http://localhost:36718/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:36718/tests/kraken/driver.html?testName=audio-beat-detection) 09:02:08 INFO - PID 4207 | RSS: Main: 186589184 09:02:08 INFO - PID 4207 | 09:02:11 INFO - PID 4207 | Cycle 1(1): loaded http://localhost:36718/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:36718/tests/kraken/driver.html?testName=audio-dft) 09:02:12 INFO - PID 4207 | RSS: Main: 187076608 09:02:12 INFO - PID 4207 | 09:02:15 INFO - PID 4207 | Cycle 1(1): loaded http://localhost:36718/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:36718/tests/kraken/driver.html?testName=audio-fft) 09:02:15 INFO - PID 4207 | RSS: Main: 183988224 09:02:15 INFO - PID 4207 | 09:02:18 INFO - PID 4207 | Cycle 1(1): loaded http://localhost:36718/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:36718/tests/kraken/driver.html?testName=audio-oscillator) 09:02:18 INFO - PID 4207 | RSS: Main: 184795136 09:02:18 INFO - PID 4207 | 09:02:20 INFO - PID 4207 | Cycle 1(1): loaded http://localhost:36718/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:36718/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:02:21 INFO - PID 4207 | RSS: Main: 184184832 09:02:21 INFO - PID 4207 | 09:02:24 INFO - PID 4207 | Cycle 1(1): loaded http://localhost:36718/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:36718/tests/kraken/driver.html?testName=imaging-darkroom) 09:02:25 INFO - PID 4207 | RSS: Main: 187318272 09:02:25 INFO - PID 4207 | 09:02:28 INFO - PID 4207 | Cycle 1(1): loaded http://localhost:36718/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:36718/tests/kraken/driver.html?testName=imaging-desaturate) 09:02:29 INFO - PID 4207 | RSS: Main: 187346944 09:02:29 INFO - PID 4207 | 09:02:32 INFO - PID 4207 | Cycle 1(1): loaded http://localhost:36718/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:36718/tests/kraken/driver.html?testName=json-parse-financial) 09:02:33 INFO - PID 4207 | RSS: Main: 187101184 09:02:33 INFO - PID 4207 | 09:02:34 INFO - PID 4207 | Cycle 1(1): loaded http://localhost:36718/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:36718/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:02:35 INFO - PID 4207 | RSS: Main: 187777024 09:02:35 INFO - PID 4207 | 09:02:37 INFO - PID 4207 | Cycle 1(1): loaded http://localhost:36718/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:36718/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:02:37 INFO - PID 4207 | RSS: Main: 188297216 09:02:37 INFO - PID 4207 | 09:02:39 INFO - PID 4207 | Cycle 1(1): loaded http://localhost:36718/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:36718/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:02:40 INFO - PID 4207 | RSS: Main: 188256256 09:02:40 INFO - PID 4207 | 09:02:43 INFO - PID 4207 | Cycle 1(1): loaded http://localhost:36718/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:36718/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:02:43 INFO - PID 4207 | RSS: Main: 188358656 09:02:43 INFO - PID 4207 | 09:02:46 INFO - PID 4207 | Cycle 1(1): loaded http://localhost:36718/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:36718/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:02:47 INFO - PID 4207 | RSS: Main: 186769408 09:02:47 INFO - PID 4207 | 09:02:49 INFO - PID 4207 | Cycle 1(1): loaded http://localhost:36718/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:36718/tests/kraken/driver.html?testName=ai-astar) 09:02:49 INFO - PID 4207 | RSS: Main: 189886464 09:02:49 INFO - PID 4207 | 09:02:49 INFO - PID 4207 | __start_tp_report 09:02:49 INFO - PID 4207 | _x_x_mozilla_page_load 09:02:49 INFO - PID 4207 | _x_x_mozilla_page_load_details 09:02:49 INFO - PID 4207 | |i|pagename|runs| 09:02:49 INFO - PID 4207 | |0;ai-astar;112;106;106;107;117;111;116;107;114;110 09:02:49 INFO - PID 4207 | |1;audio-beat-detection;126;126;119;117;120;128;118;124;116;119 09:02:49 INFO - PID 4207 | |2;audio-dft;171;169;169;163;161;175;166;164;166;165 09:02:49 INFO - PID 4207 | |3;audio-fft;80;80;84;76;78;84;88;80;74;74 09:02:49 INFO - PID 4207 | |4;audio-oscillator;84;80;90;81;78;81;81;79;87;80 09:02:49 INFO - PID 4207 | |5;imaging-gaussian-blur;115;116;118;116;114;110;116;114;117;111 09:02:49 INFO - PID 4207 | |6;imaging-darkroom;151;148;147;151;150;151;151;150;154;155 09:02:49 INFO - PID 4207 | |7;imaging-desaturate;101;101;94;96;95;96;94;98;94;96 09:02:49 INFO - PID 4207 | |8;json-parse-financial;55;63;53;60;53;54;56;54;53;59 09:02:49 INFO - PID 4207 | |9;json-stringify-tinderbox;43;41;41;43;38;48;38;43;39;38 09:02:49 INFO - PID 4207 | |10;stanford-crypto-aes;73;73;75;76;74;73;74;84;80;78 09:02:49 INFO - PID 4207 | |11;stanford-crypto-ccm;117;117;122;122;113;117;114;112;120;130 09:02:49 INFO - PID 4207 | |12;stanford-crypto-pbkdf2;173;181;177;169;174;169;169;167;176;172 09:02:49 INFO - PID 4207 | |13;stanford-crypto-sha256-iterative;67;67;65;67;65;65;73;79;67;64 09:02:49 INFO - PID 4207 | __end_tp_report 09:02:49 INFO - PID 4207 | __start_cc_report 09:02:49 INFO - PID 4207 | _x_x_mozilla_cycle_collect,729 09:02:49 INFO - PID 4207 | __end_cc_report 09:02:49 INFO - PID 4207 | __startTimestamp1506009769532__endTimestamp 09:02:49 INFO - PID 4207 | 09:02:49 INFO - PID 4207 | ------- Summary: start ------- 09:02:49 INFO - PID 4207 | Number of tests: 14 09:02:49 INFO - PID 4207 | 09:02:49 INFO - PID 4207 | [#0] ai-astar Cycles:10 Average:110.60 Median:111.50 stddev:4.12 (3.7%) stddev-sans-first:4.33 09:02:49 INFO - PID 4207 | Values: 112.0 106.0 106.0 107.0 117.0 111.0 116.0 107.0 114.0 110.0 09:02:49 INFO - PID 4207 | 09:02:49 INFO - PID 4207 | [#1] audio-beat-detection Cycles:10 Average:121.30 Median:122.00 stddev:4.30 (3.5%) stddev-sans-first:4.21 09:02:49 INFO - PID 4207 | Values: 126.0 126.0 119.0 117.0 120.0 128.0 118.0 124.0 116.0 119.0 09:02:49 INFO - PID 4207 | 09:02:49 INFO - PID 4207 | [#2] audio-dft Cycles:10 Average:166.90 Median:167.50 stddev:4.15 (2.5%) stddev-sans-first:4.13 09:02:49 INFO - PID 4207 | Values: 171.0 169.0 169.0 163.0 161.0 175.0 166.0 164.0 166.0 165.0 09:02:49 INFO - PID 4207 | 09:02:49 INFO - PID 4207 | [#3] audio-fft Cycles:10 Average:79.80 Median:80.00 stddev:4.57 (5.7%) stddev-sans-first:4.84 09:02:49 INFO - PID 4207 | Values: 80.0 80.0 84.0 76.0 78.0 84.0 88.0 80.0 74.0 74.0 09:02:49 INFO - PID 4207 | 09:02:49 INFO - PID 4207 | [#4] audio-oscillator Cycles:10 Average:82.10 Median:81.00 stddev:3.78 (4.7%) stddev-sans-first:3.95 09:02:49 INFO - PID 4207 | Values: 84.0 80.0 90.0 81.0 78.0 81.0 81.0 79.0 87.0 80.0 09:02:49 INFO - PID 4207 | 09:02:49 INFO - PID 4207 | [#5] imaging-gaussian-blur Cycles:10 Average:114.70 Median:116.00 stddev:2.54 (2.2%) stddev-sans-first:2.69 09:02:49 INFO - PID 4207 | Values: 115.0 116.0 118.0 116.0 114.0 110.0 116.0 114.0 117.0 111.0 09:02:49 INFO - PID 4207 | 09:02:49 INFO - PID 4207 | [#6] imaging-darkroom Cycles:10 Average:150.80 Median:151.00 stddev:2.39 (1.6%) stddev-sans-first:2.54 09:02:49 INFO - PID 4207 | Values: 151.0 148.0 147.0 151.0 150.0 151.0 151.0 150.0 154.0 155.0 09:02:49 INFO - PID 4207 | 09:02:49 INFO - PID 4207 | [#7] imaging-desaturate Cycles:10 Average:96.50 Median:95.50 stddev:2.68 (2.8%) stddev-sans-first:2.29 09:02:49 INFO - PID 4207 | Values: 101.0 101.0 94.0 96.0 95.0 96.0 94.0 98.0 94.0 96.0 09:02:49 INFO - PID 4207 | 09:02:49 INFO - PID 4207 | [#8] json-parse-financial Cycles:10 Average:56.00 Median:55.50 stddev:3.50 (6.3%) stddev-sans-first:3.69 09:02:49 INFO - PID 4207 | Values: 55.0 63.0 53.0 60.0 53.0 54.0 56.0 54.0 53.0 59.0 09:02:49 INFO - PID 4207 | 09:02:49 INFO - PID 4207 | [#9] json-stringify-tinderbox Cycles:10 Average:41.20 Median:42.00 stddev:3.19 (7.6%) stddev-sans-first:3.32 09:02:49 INFO - PID 4207 | Values: 43.0 41.0 41.0 43.0 38.0 48.0 38.0 43.0 39.0 38.0 09:02:49 INFO - PID 4207 | 09:02:49 INFO - PID 4207 | [#10] stanford-crypto-aes Cycles:10 Average:76.00 Median:75.50 stddev:3.65 (4.8%) stddev-sans-first:3.71 09:02:49 INFO - PID 4207 | Values: 73.0 73.0 75.0 76.0 74.0 73.0 74.0 84.0 80.0 78.0 09:02:49 INFO - PID 4207 | 09:02:49 INFO - PID 4207 | [#11] stanford-crypto-ccm Cycles:10 Average:118.40 Median:118.50 stddev:5.36 (4.5%) stddev-sans-first:5.66 09:02:49 INFO - PID 4207 | Values: 117.0 117.0 122.0 122.0 113.0 117.0 114.0 112.0 120.0 130.0 09:02:49 INFO - PID 4207 | 09:02:49 INFO - PID 4207 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:172.70 Median:173.50 stddev:4.40 (2.5%) stddev-sans-first:4.66 09:02:49 INFO - PID 4207 | Values: 173.0 181.0 177.0 169.0 174.0 169.0 169.0 167.0 176.0 172.0 09:02:49 INFO - PID 4207 | 09:02:49 INFO - PID 4207 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.90 Median:67.00 stddev:4.63 (6.9%) stddev-sans-first:4.90 09:02:49 INFO - PID 4207 | Values: 67.0 67.0 65.0 67.0 65.0 65.0 73.0 79.0 67.0 64.0 09:02:49 INFO - PID 4207 | -------- Summary: end -------- 09:02:49 INFO - PID 4207 | 09:02:50 INFO - PID 4207 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 09:02:50 INFO - TEST-INFO | 4207: exit 0 09:02:50 INFO - TEST-OK | kraken | took 62355ms 09:02:50 INFO - SUITE-END | took 1174s 09:02:50 INFO - Completed test suite (00:19:34) 09:02:50 INFO - javascript benchmark 09:02:50 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8563.877126067902, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3154.3375891062874, 3161.7786754927165, 3181.347823696141, 3185.362927414518, 3210.2134976930643, 6219.968900155504, 6351.936480635192, 6415.9037614435765, 6438.903416448757, 6490.156279683646, 6307.653079080649, 6328.082428047942, 6415.582987105847, 6465.90301145482, 6490.188726409199, 5788.652680839142, 5821.359650438449, 5838.737256823453, 5942.346341902409, 5971.10433434986, 5820.796272130477, 5913.852153696149, 5939.168516407706, 5940.079287710412, 5983.132445795379, 5763.971180144114, 5788.131780232957, 5825.359210486843, 5894.793682221123, 5911.379305172959, 5855.502282306009, 5863.061910094364, 5882.852928676774, 5891.027980383232, 5899.115132730082, 5752.137179423078, 5815.505682017034, 5838.678872662002, 5850.5612079094235, 5892.705364731789, 6344.206974128234, 6349.8412539686415, 6410.647414392207, 6437.678116094224, 6466.321036291169, 6603.14159159311, 6707.228668703113, 6755.189377274709, 6812.897806532906, 6816.182058153054, 6867.2102708188695, 6884.896726549106, 6909.619970901598, 6944.51388402812, 6949.027136200936, 6252.499800016033, 6253.218347706536, 6316.936830631681, 6325.051242313643, 6331.33520980295, 7055.682494287769, 7068.399186069241, 7088.751893683696, 7096.858062838714, 7145.499815013003, 6579.671016449159, 6620.768273110418, 6689.933100668931, 6766.526343156031, 6797.898031529531, 6688.063671086052, 6709.899351509731, 6771.153605799275, 6793.286704896013, 6860.0, 6780.423663988518, 6797.966010169917, 6804.795856124324, 6805.795826125224, 6810.353016463428, 6793.150856142982, 6812.48906332027, 6816.625085620339, 6831.4534837212905, 6893.448524118058, 5339.492748188916, 5339.652922560022, 5401.7299135043095, 5416.295881535375, 5433.538149257279, 4546.977265113654, 4634.513376095529, 4651.67438279324, 4688.812447502061, 4729.196036673705, 5827.0, 5837.0, 5868.618539794899, 5871.266091738533, 5885.2349194605285, 6711.362420570038, 6843.247242803288, 6843.281455447206, 6866.965665171742, 6905.240423553405, 5727.4845263926445, 5819.039858423397, 5825.213596164548, 5842.1236814477315, 5887.411258874078, 5692.402297758758, 5829.708514574254, 5840.532757379485, 5902.8819423611285, 5904.4685978261305, 6548.9017664735065, 6567.671616419256, 6570.474362051024, 6616.669166541654, 6622.801315960529, 6774.898376524455, 6843.418309443753, 6851.07510486088, 6860.279670634512, 6866.073080134118, 6525.021246813016, 6527.249366322743, 6589.7364105435245, 6615.6030638161865, 6689.933100668931, 6493.870122597427, 6699.832504187434, 6748.898766518604, 6853.451723861979, 6862.142232220973, 6703.262641109376, 6760.053592497153, 6769.323067693388, 6782.525223234326, 6804.55770374924, 6484.448821850196, 6539.967300163468, 6553.705083271359, 6555.672216389257, 6593.241777195684, 6654.334566543307, 6668.166479190101, 6668.533202675766, 6673.799786006428, 6709.7316107355155, 6495.350464953467, 6512.413882750575, 6544.214694236532, 6567.605943643397, 6599.373059559334, 6536.738530458729, 6632.436242919406, 6712.328767123444, 6761.3238676132, 6771.525993180627, 6376.681165941777, 6424.775132870327, 6429.54993150475, 6450.516211284079, 6516.446102081376], "value": 6202.818235999898, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [861.3152543727736, 867.0938868882017, 872.136584781067, 877.1579283887468, 895.6641259527677, 39410.408843867364, 40741.796291018574, 41142.38286425702, 41196.79401602992, 41324.380134297935, 37014.0, 37048.07379815506, 37217.44173837395, 37238.62761372385, 37438.438423423606, 7056.294370562941, 7177.031100801377, 7226.674799634029, 7227.927720722778, 7237.782866513987, 7147.2138064812825, 7193.85612287754, 7204.0634717486655, 7219.0976127984, 7240.0587923569865, 7144.499885008052, 7738.883916741253, 8023.358131349507, 8026.959865200666, 8104.716334928279, 7931.0, 7978.042634883822, 7981.48120372174, 7988.920110798876, 7989.280964713145, 7873.0, 7904.723334683316, 7923.811428285746, 7977.441579089466, 7992.160823113548, 37445.438318425244, 38973.0, 38984.41523377151, 39251.41122883159, 39291.21417571661, 3481.738869584766, 3528.100334414708, 3532.0816587687127, 3538.044727923472, 3541.752077354586, 31957.201069973205, 32176.839115804505, 32197.51703724446, 32268.031959041266, 32269.6773032269, 5265.86835329116, 5273.1035723927025, 5284.735763211863, 5313.787448502056, 5359.0, 177626.0, 179372.9999999987, 180687.0, 181164.0, 181299.0, 48189.0, 48283.03433931372, 48331.27503087457, 48350.516494835305, 48530.0, 50597.74701126544, 50637.0, 50749.49250507448, 50797.74601126971, 50811.49188508068, 14704.632384190267, 14855.554333370017, 15071.09573425598, 15076.698466030837, 15085.622859428602, 11994.64016079519, 12007.399630018464, 12026.15816892809, 12112.212706174247, 12138.08964327679, 14076.155430674193, 14095.85904140966, 14211.64470888236, 14393.568192954228, 14406.42374305016, 5580.246666700024, 5613.326400831927, 5642.774289028393, 5747.109198074305, 5824.194499705105, 993.4337427666161, 1006.6325791086201, 1019.8623185869961, 1021.1269364693229, 1031.4687935713114, 22970.0, 22996.310110696704, 23018.424539386648, 23056.19303324409, 23590.764092358855, 1083.5936523803573, 1090.6291860767378, 1100.6863044032573, 1110.8889111088988, 1118.2675347647305, 12220.694482637828, 12269.0, 12292.139550231397, 12432.502699891904, 12459.314737689332, 223039.0, 223599.0, 223869.0, 224340.0, 224819.0, 43522.129557408676, 44497.77751111224, 52654.21018684723, 52869.2069618956, 52956.47043529515, 1379.379279324308, 1405.6134562995053, 1414.6463384153963, 1419.815423994874, 1421.3745951781184, 27496.0, 27550.44899102047, 27855.582166267926, 27913.441731164858, 28061.71938280673, 35384.0, 35412.291754165286, 35428.0, 35611.10972225715, 35695.82152089223, 178797.0, 178936.0, 179547.0, 180675.0, 181382.0, 32557.34885302328, 33538.83230583832, 33801.15497112592, 33867.830660846535, 34015.829920850236, 42446.575534244264, 43229.56770432255, 43276.78361608172, 43395.0, 43561.56438435575, 31518.684813151576, 31853.36293274168, 32046.519302210017, 32373.676263237066, 32571.511427329082, 31856.36287274288, 31867.521987170676, 31886.6811331893, 31922.20194495156, 32181.678183217868, 34454.13864653404, 35488.46767298441, 35616.643833561335, 35660.46509302415, 36217.27565448729, 30414.39171216519, 31041.844790776806, 31099.378012440076, 31170.22074448157, 31871.0, 22939.655905161104, 23015.0, 23353.0, 23362.065517379113, 23448.0], "value": 16930.712710868575, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [582.6620560075155, 586.9325027621825, 593.3058321763539, 596.0261616807738, 603.6709993053786, 21567.0, 22391.888040559796, 22392.88803555984, 22444.0, 22561.210357637487, 23757.406064848386, 24049.0, 24851.50296994059, 25005.24984250471, 25257.74742252577, 6439.195100612424, 6587.110740050104, 6607.372299631527, 6644.734210631593, 6670.566413183152, 6430.710618022177, 6564.343565643445, 6590.538662293618, 6625.73497060117, 6625.933740662604, 6283.937160628381, 6507.772227972022, 6535.57518761279, 6584.8024559263295, 6587.93412065878, 6582.8683426331445, 6630.6021638701595, 6632.867342653168, 6633.369829866155, 6633.601983880959, 6106.419890110433, 6137.78517751881, 6142.832861756252, 6155.784547540859, 6159.87680246397, 27479.17562473129, 27553.586696199574, 28781.0, 28839.56740648892, 28973.0, 2036.0735865181455, 2048.9077991490417, 2064.556120434099, 2066.927657531979, 2073.6060148571723, 19809.10859011348, 19970.70043949342, 19971.600567988706, 20057.79942200574, 20086.899565502077, 2378.1200955646523, 2387.689600351943, 2432.7567243275707, 2434.440078781872, 2488.713797913245, 121328.0, 126905.0, 135033.0, 142060.28969855086, 143061.28469357692, 14685.559433216808, 14726.190059546614, 14768.335424905905, 14910.70178596422, 14984.250787460584, 22971.88514057419, 22979.42551436194, 23275.650865237036, 23280.185193518144, 23356.18253361124, 9646.758831029281, 9797.216222702167, 9803.313768036312, 9826.06652368024, 10087.831116309882, 8910.0, 8967.461952282883, 8990.145936136056, 9030.0, 9088.363814533051, 9224.123708247706, 9257.583408746623, 9378.296627752945, 9416.340856140005, 9421.0, 2615.9476810463684, 2693.0, 2737.5619900816173, 2744.108164846433, 2845.374017716099, 929.702495201529, 947.8436058050482, 968.0319680319681, 968.0609808485758, 970.9320347575602, 18597.628047438975, 18825.81174188268, 18930.148143333583, 19073.0, 19079.332223372116, 1014.2342531388754, 1018.8013337399136, 1022.4325499347784, 1030.3508789462592, 1033.4625994482826, 10497.265191436527, 10513.894861051445, 10534.73663158412, 10600.628977985734, 10724.088452481627, 436031.0, 454699.0, 455723.0, 455840.0, 463944.0, 109917.0, 110314.44842775895, 110872.0, 111637.44181279204, 112104.43947780372, 1177.0583533173426, 1187.5724739093919, 1191.118572256524, 1194.313269869811, 1196.6589521986018, 16772.664546709242, 17017.91491042537, 17079.829201707824, 17157.57106072358, 17599.912000439916, 53913.0, 54047.0, 54759.726201368736, 54911.72544137254, 55934.72032639811, 67075.66462167658, 68489.65755171192, 69022.0, 69266.0, 70839.64580177066, 14090.506832260822, 14308.0, 14479.348429320495, 14511.274436278354, 14642.853571464575, 21624.67562986588, 22003.33989980303, 22060.448488787908, 22120.778792212517, 22153.224637137624, 17660.735088973932, 17684.115794210495, 17690.823091768918, 17836.821631783518, 17873.0, 15975.0, 16094.919525402298, 16347.918260408622, 16511.669766604362, 16900.323987040872, 17661.470155895342, 17891.642167156846, 17964.281428743227, 18149.364772232908, 18173.818261817214, 14292.14247145133, 14292.213928234047, 14356.0, 14359.0, 14382.28088595587, 16307.0, 17411.7388239174, 17468.650626987645, 17664.646707066044, 17730.468085957444], "value": 11755.87525741842, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1317.1965101288215, 1361.14248023745, 1367.7469668111394, 1373.4300265389857, 1377.063596754208, 14862.851371486282, 15692.843071569281, 15704.979176353529, 15774.68450630988, 15791.684166316667, 15294.694106117871, 15447.922760386182, 15459.381624734997, 15459.381624735026, 15464.84535154648, 2700.6759188897263, 2805.3126983888974, 2826.222788733094, 2829.660440747113, 2835.277004363895, 2720.469508445854, 2778.472090302846, 2791.8185317954267, 2793.9022134225306, 2801.243664210662, 2673.5321318769234, 2706.3910620110414, 2836.446892855894, 2844.104107206234, 2855.6430446194227, 2740.068376751904, 2763.0, 2774.2648198227484, 2776.54187059135, 2778.5554311310193, 2685.9597106043425, 2714.6878109017516, 2719.0, 2726.509228338898, 2728.945421091587, 12413.882750552493, 12844.422001009883, 12926.676833079155, 14500.347484363123, 15013.624659383493, 7918.643661035209, 8348.290395316406, 8410.705625303084, 8416.242538171595, 8437.156284371575, 5328.44051374604, 5606.383297837235, 5630.15547667849, 5640.407757185478, 5683.602147849652, 1948.5615736459324, 1949.707543868417, 1992.930247441347, 2015.7177995080701, 2035.3893831850385, 27848.0, 27882.3029424264, 28011.299717507023, 28017.159485215478, 28051.0, 6821.0, 7002.194747603989, 7079.1505019398, 7203.351698347174, 7266.091738532684, 17649.7352539712, 17700.9114954427, 17729.46811595654, 17744.29022839098, 17773.20020599076, 8170.673173073011, 8282.296004839656, 8298.419110662197, 8305.543195124204, 8326.833463330699, 7155.89266161008, 7304.488685791944, 7464.328210461084, 7547.320741133214, 7658.234176582298, 5218.165093585084, 5438.755256013489, 5439.864003399946, 5465.07093794056, 5469.15228139639, 1563.3902777916621, 1573.6065983504125, 1599.8240193578697, 1609.6378314879294, 1615.4830454254761, 819.266756253162, 835.4527784301292, 850.9361797865184, 855.4354126276628, 859.2481578618708, 8135.877961830577, 8242.752717418487, 8268.297194738394, 8321.292690121287, 8352.916470835335, 950.8383574792302, 975.8194733974237, 979.0552117206929, 984.4831463481586, 989.2679417231341, 4861.246506791453, 5018.949810501921, 5107.0, 5151.8196863109615, 5158.484151584811, 21133.0, 22134.0, 22541.211057612905, 22741.88629056844, 22841.0, 21903.780962190172, 21996.780032199793, 22625.88687056587, 22698.205562805222, 22974.0, 11213.719657008476, 11444.427778611036, 11471.483783229774, 11567.76864462706, 11596.594119205787, 8040.195980401913, 8193.139720329518, 8194.50832950025, 8260.669573217005, 8272.545010024507, 18956.810431895505, 19376.806231938062, 19403.8059619402, 19759.0, 20102.69845952341, 18775.71836422482, 19902.104405301507, 20223.0, 20494.69257961102, 20699.896500518003, 11197.384143872165, 11336.829947550628, 11418.200725949104, 11439.0, 11454.255473394203, 13651.0, 13959.302034898012, 13965.301734913011, 14009.43962241499, 14087.225202613954, 11008.834867477155, 11065.446727663424, 11091.389973551532, 11108.333499990027, 11108.944455277671, 11176.720581985515, 11206.775864482828, 11208.607698730506, 11300.604478843201, 11399.373034483184, 10169.9491502542, 10214.131798797185, 10295.3822770634, 10311.742206444898, 10397.272190946562, 7160.355567998905, 7227.819304517429, 7239.348458638748, 7318.414526837733, 7393.927880457196, 6779.762708305186, 6895.241523432318, 6953.339432754083, 6959.756408525677, 7051.0], "value": 6769.626850853706, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [491.27782160224456, 514.25176368384, 539.9784008639655, 540.4568408749208, 545.5962587685111, 6264.874702505953, 6288.937110628893, 6321.273053598838, 6363.872722545546, 6442.420182183602, 6176.320604733476, 6208.906866397007, 6262.780802671908, 6273.2472103347545, 6279.717412716427, 5208.60404214062, 5225.712585807779, 5264.526192642661, 5274.4198138204765, 5312.618719133034, 5155.639105262633, 5193.143131383317, 5231.73841307935, 5236.73816309183, 5255.737213139347, 4868.902621947559, 4984.4018717753925, 5021.497850214986, 5049.2678561608545, 5061.16490779021, 4962.741952411644, 5021.899562008739, 5040.6471546991725, 5046.470120637336, 5068.569171620398, 4378.8029538670835, 4527.86416407505, 4537.954620453786, 4538.682292239545, 4543.295789152654, 6040.818775436744, 6060.696965151769, 6133.907991380132, 6137.7851775187655, 6196.96901515494, 5645.774169033234, 5670.886582268373, 5696.23100881384, 5717.113847353667, 5762.510186634141, 6554.934450655528, 6577.440917522016, 6604.6697665117035, 6606.8017959461295, 6635.601863888182, 4641.141388843041, 4901.975490122562, 4903.63222758291, 4927.6550641455115, 4939.308496810413, 6669.566478178951, 6782.8304292392595, 6883.386222233367, 6897.689603967783, 6911.7926462206215, 4999.750012499433, 5001.224810154432, 5025.120603894304, 5049.697018178921, 5066.214736715815, 5035.974820125949, 5055.469175736568, 5073.239014147907, 5075.847724568269, 5126.384833819965, 5699.145128230799, 5714.314282286152, 5759.6832174229985, 5799.405163579965, 5804.361520232771, 6572.474202063823, 6635.4359879410795, 6651.800445986628, 6678.762614524188, 6681.231658359254, 1755.5786611213216, 1765.2233017472272, 1769.5576105973507, 1776.8845025073329, 1785.259117466237, 1168.0, 1228.9201201921846, 1240.8635050144476, 1248.3134276148264, 1263.886250237483, 5505.284313039359, 5604.551635869121, 5610.410906854721, 5616.522595579339, 5653.236813030188, 6586.50601204902, 6605.240397354366, 6721.22705888829, 6729.803343411841, 6749.831254218586, 5543.833684989457, 5598.692071936082, 5601.350878770692, 5661.377248502661, 5694.1458781183155, 3417.9487307690406, 3450.240946991658, 3471.264367816092, 3497.527833742412, 3540.0795793093466, 6347.143785296635, 6372.617642941438, 6435.742570297136, 6466.967665161644, 6521.184851893513, 6748.358905903931, 6754.966225168842, 6792.966035169891, 6830.419414349736, 6965.616891071039, 6427.517936154807, 6488.870222595616, 6512.73949042033, 6536.607803531803, 6547.377999090078, 6648.736740019381, 6682.164729408824, 6741.662916854236, 6759.695813688494, 6805.251422343439, 6497.8700425992165, 6611.603303801788, 6699.296573859675, 6713.966430167818, 6726.3520437493125, 6373.776917807854, 6492.805215843343, 6550.508711846726, 6610.305917878553, 6649.534532582673, 5035.571976382049, 5048.949510504995, 5055.898882022412, 5095.261187127771, 5139.6145289102715, 6364.681765911593, 6571.277159512354, 6599.406053455212, 6847.21257055445, 6914.377706006282, 6252.968735156295, 6467.870642587216, 6479.967600161969, 6520.315366886598, 6533.060122944599, 6366.4588509977175, 6432.581882177644, 6491.0, 6504.837379065372, 6505.7072431741635, 6326.177596912372, 6395.776147834804, 6409.166808314889, 6449.451796597342, 6470.838229044311, 6529.4776417885505, 6552.868942621216, 6602.141721576164, 6606.240282367589, 6615.66921653925, 6323.1463752394675, 6371.96814015927, 6410.839729006812, 6430.742770289136, 6437.517186210959], "value": 5071.45426230871, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [908.513945039404, 914.9130832570904, 927.2767241551592, 932.7901222225, 934.177923427384, 19581.216751329946, 20353.18587256512, 20487.0, 20633.0, 20725.585488290242, 16211.756823647655, 16440.177991100456, 16532.83467165328, 16552.917235413806, 16591.336346546155, 950.6102497975814, 959.9376040557377, 960.2749923807515, 962.0475729028282, 963.1283688262134, 978.7993461340408, 980.4019548075668, 981.0532835813439, 981.3817295104075, 982.4252812104892, 1003.0671475527756, 1005.9849102263472, 1018.0430395428274, 1024.4416792847917, 1046.6075221791828, 1028.4754775064696, 1029.078974317985, 1030.2993964104405, 1032.189731061121, 1032.8554002439664, 740.7111226621622, 745.8694728422504, 746.8020974441778, 751.6579956119953, 752.4695089961564, 18970.905145474324, 19003.42989710297, 20528.58942821135, 20887.37337879866, 21134.0, 21890.89054554733, 22253.44366390837, 22561.0, 22673.433164170863, 22682.886585567132, 17669.911650441794, 18008.189631466616, 18020.36928707502, 18063.638727225512, 18199.36302229429, 3863.690904727615, 3878.5151856017997, 3912.080661044652, 3913.4521167036637, 3915.6671682906986, 107153.0, 123991.0, 148556.2572187132, 149482.99999999892, 151152.2442387792, 17592.0, 17924.0, 18076.276948922157, 18165.636687266444, 18168.45494635163, 31777.84111079476, 31857.8407107963, 32080.0, 32085.19787005297, 32353.0, 8710.782230444289, 8883.289336853037, 8978.461292322481, 9160.81678366429, 9197.862032069525, 4155.8961025974595, 4223.007593215591, 4228.70399072062, 4253.106847562047, 4266.807993640232, 5737.0, 5773.336066352424, 5856.355800861902, 5858.414158584193, 5858.853528661817, 1081.0702795595782, 1082.5236895765995, 1102.9081209602437, 1105.795427845851, 1110.2062025651699, 635.3646353646353, 660.38254232292, 664.4319107163402, 675.4360109308782, 678.5657179405184, 11000.889991100146, 11345.432728363548, 11396.202265841477, 11590.362530060927, 11817.881821181678, 684.910961574992, 693.6982412650514, 701.5229643842134, 709.3474003916409, 709.8438343564408, 5423.864403389947, 5458.344998600114, 5557.277553917965, 5578.330600327987, 5661.122526008393, 126191.0, 127126.0, 128136.35931820281, 129811.70188297996, 131945.0, 106572.0, 106944.0, 110416.4479177599, 112190.43904780423, 113877.0, 96438.0, 97000.0, 97356.51321743487, 97697.51151244341, 100656.49671751594, 11704.70738231551, 12006.39968001579, 12084.093692973238, 12215.389230538616, 12268.447919843808, 44209.33685994777, 44286.0, 45634.0, 46285.611431657104, 46471.302930456746, 57335.426645734675, 58656.70671646614, 58814.705926470095, 59218.703906480194, 59829.0, 18779.0, 18932.716009260148, 18985.620287594447, 19057.90471047636, 19247.0, 20697.068631911297, 21061.473463163544, 21071.789282106984, 21217.469563261042, 21600.13599456004, 16969.406070787463, 17487.912560437115, 17553.912230438767, 17583.120843958008, 17774.0, 18743.250269989047, 18876.0, 18935.71596426027, 19001.334953276568, 19070.046497674783, 16769.0, 17126.486205413858, 17352.65294694124, 17358.0, 17511.474655760347, 13411.664708382368, 13599.86400135986, 13739.656508587364, 13783.793243101562, 14062.648433789238, 13126.212427254395, 13166.802497962346, 13311.935045196167, 13325.0, 13369.0], "value": 9306.390506665675, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1454.9000000000003, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 106.0, 106.0, 107.0, 117.0, 111.0, 116.0, 107.0, 114.0, 110.0], "value": 110.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [126.0, 126.0, 119.0, 117.0, 120.0, 128.0, 118.0, 124.0, 116.0, 119.0], "value": 121.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 169.0, 169.0, 163.0, 161.0, 175.0, 166.0, 164.0, 166.0, 165.0], "value": 166.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 80.0, 84.0, 76.0, 78.0, 84.0, 88.0, 80.0, 74.0, 74.0], "value": 79.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 80.0, 90.0, 81.0, 78.0, 81.0, 81.0, 79.0, 87.0, 80.0], "value": 82.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 116.0, 118.0, 116.0, 114.0, 110.0, 116.0, 114.0, 117.0, 111.0], "value": 114.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 148.0, 147.0, 151.0, 150.0, 151.0, 151.0, 150.0, 154.0, 155.0], "value": 150.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 101.0, 94.0, 96.0, 95.0, 96.0, 94.0, 98.0, 94.0, 96.0], "value": 96.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 63.0, 53.0, 60.0, 53.0, 54.0, 56.0, 54.0, 53.0, 59.0], "value": 56.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [43.0, 41.0, 41.0, 43.0, 38.0, 48.0, 38.0, 43.0, 39.0, 38.0], "value": 41.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 73.0, 75.0, 76.0, 74.0, 73.0, 74.0, 84.0, 80.0, 78.0], "value": 76.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 122.0, 122.0, 113.0, 117.0, 114.0, 112.0, 120.0, 130.0], "value": 118.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [173.0, 181.0, 177.0, 169.0, 174.0, 169.0, 169.0, 167.0, 176.0, 172.0], "value": 172.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 65.0, 67.0, 65.0, 65.0, 73.0, 79.0, 67.0, 64.0], "value": 67.9, "unit": "score"}]}]} 09:02:50 INFO - Return code: 0 09:02:50 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:02:50 INFO - # TBPL SUCCESS # 09:02:50 INFO - Running post-action listener: _package_coverage_data 09:02:50 INFO - Running post-action listener: _resource_record_post_action 09:02:50 INFO - [mozharness: 2017-09-21 16:02:50.806418Z] Finished run-tests step (success) 09:02:50 INFO - Running post-run listener: _resource_record_post_run 09:02:50 INFO - Total resource usage - Wall time: 1193s; CPU: 13.0%; Read bytes: 4096; Write bytes: 344600576; Read time: 16; Write time: 240700 09:02:50 INFO - TinderboxPrint: CPU usage
13.3% 09:02:50 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 16 09:02:50 INFO - TinderboxPrint: I/O write bytes / time
344,600,576 / 240,700 09:02:50 INFO - TinderboxPrint: CPU idle
8,245.7 (86.7%) 09:02:50 INFO - TinderboxPrint: CPU user
1,201.4 (12.6%) 09:02:50 INFO - TinderboxPrint: Swap in / out
0 / 0 09:02:50 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 10436608; Read time: 0; Write time: 784 09:02:50 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 09:02:50 INFO - run-tests - Wall time: 1175s; CPU: 13.0%; Read bytes: 0; Write bytes: 334163968; Read time: 0; Write time: 239916 09:02:51 INFO - Running post-run listener: _upload_blobber_files 09:02:51 INFO - Blob upload gear active. 09:02:51 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:02:51 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 09:02:51 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'] 09:02:51 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 09:02:51 INFO - (blobuploader) - INFO - Open directory for files ... 09:02:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 09:02:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:02:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:02:52 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 09:02:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:02:52 INFO - (blobuploader) - INFO - Done attempting. 09:02:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 09:02:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:02:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:02:53 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 09:02:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:02:53 INFO - (blobuploader) - INFO - Done attempting. 09:02:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:02:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:02:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:03:00 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:03:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:03:00 INFO - (blobuploader) - INFO - Done attempting. 09:03:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 09:03:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:03:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:03:01 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 09:03:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:03:01 INFO - (blobuploader) - INFO - Done attempting. 09:03:01 INFO - (blobuploader) - INFO - Iteration through files over. 09:03:01 INFO - Return code: 0 09:03:01 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:03:01 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:03:01 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8948315908482737b7cadb4338b6dd41359f96e36d81778a9aa2ba3752721c8cfdf86989a660da58b183058766a102768b1e387c658b8b8335451ed6092a6013", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/64e967586b09b8e44c26e245c0d5c260f74116d8f2666c51a61e2343023ca048430a97ccc67e1ef3768a498d6886ce91a30e3c39af6e80618f65b7e405fdcfc2", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/699918abd222f31d92123e51d3dcd9960b7b7d80a8e86e61f88942469d2ba6ed71f10e0bac2d1a4a2d8bd7609840a9529c384de92c85750a28153d09a4db7bec", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c86a357554d2757789dd4f25a477ae9d99faafbf9b0bf37dd9936f4c97245c3b83681e7d13b4b52d9c58a4aad0e3a1bfd17d02526b0ac32b4db897428db37769"} 09:03:01 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:03:01 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:03:01 INFO - Contents: 09:03:01 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8948315908482737b7cadb4338b6dd41359f96e36d81778a9aa2ba3752721c8cfdf86989a660da58b183058766a102768b1e387c658b8b8335451ed6092a6013", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/64e967586b09b8e44c26e245c0d5c260f74116d8f2666c51a61e2343023ca048430a97ccc67e1ef3768a498d6886ce91a30e3c39af6e80618f65b7e405fdcfc2", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/699918abd222f31d92123e51d3dcd9960b7b7d80a8e86e61f88942469d2ba6ed71f10e0bac2d1a4a2d8bd7609840a9529c384de92c85750a28153d09a4db7bec", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c86a357554d2757789dd4f25a477ae9d99faafbf9b0bf37dd9936f4c97245c3b83681e7d13b4b52d9c58a4aad0e3a1bfd17d02526b0ac32b4db897428db37769"} 09:03:01 INFO - Running post-run listener: copy_logs_to_upload_dir 09:03:01 INFO - Copying logs to upload dir... 09:03:01 INFO - mkdir: /builds/slave/test/build/upload/logs 09:03:01 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1246.046547 ========= master_lag: -0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 46 secs) (at 2017-09-21 09:03:02.136728) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-21 09:03:02.141080) ========= 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=826874ccc97bd7bf541b4875000001d5-1506006346.323149-1094679459 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8948315908482737b7cadb4338b6dd41359f96e36d81778a9aa2ba3752721c8cfdf86989a660da58b183058766a102768b1e387c658b8b8335451ed6092a6013", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/64e967586b09b8e44c26e245c0d5c260f74116d8f2666c51a61e2343023ca048430a97ccc67e1ef3768a498d6886ce91a30e3c39af6e80618f65b7e405fdcfc2", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/699918abd222f31d92123e51d3dcd9960b7b7d80a8e86e61f88942469d2ba6ed71f10e0bac2d1a4a2d8bd7609840a9529c384de92c85750a28153d09a4db7bec", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c86a357554d2757789dd4f25a477ae9d99faafbf9b0bf37dd9936f4c97245c3b83681e7d13b4b52d9c58a4aad0e3a1bfd17d02526b0ac32b4db897428db37769"} build_url:https://queue.taskcluster.net/v1/task/NkyEVKxfQa-s6GWN1Ap4sg/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011296 build_url: 'https://queue.taskcluster.net/v1/task/NkyEVKxfQa-s6GWN1Ap4sg/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8948315908482737b7cadb4338b6dd41359f96e36d81778a9aa2ba3752721c8cfdf86989a660da58b183058766a102768b1e387c658b8b8335451ed6092a6013", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/64e967586b09b8e44c26e245c0d5c260f74116d8f2666c51a61e2343023ca048430a97ccc67e1ef3768a498d6886ce91a30e3c39af6e80618f65b7e405fdcfc2", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/699918abd222f31d92123e51d3dcd9960b7b7d80a8e86e61f88942469d2ba6ed71f10e0bac2d1a4a2d8bd7609840a9529c384de92c85750a28153d09a4db7bec", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c86a357554d2757789dd4f25a477ae9d99faafbf9b0bf37dd9936f4c97245c3b83681e7d13b4b52d9c58a4aad0e3a1bfd17d02526b0ac32b4db897428db37769"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-21 09:03:02.175385) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-21 09:03:02.179967) ========= 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=826874ccc97bd7bf541b4875000001d5-1506006346.323149-1094679459 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004528 ========= master_lag: 0.07 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-21 09:03:02.254561) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-21 09:03:02.257884) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-21 09:03:02.258180) ========= ========= Total master_lag: 0.19 =========