builder: mozilla-central_ubuntu64_hw_qr_test-dromaeojs-e10s slave: talos-linux64-ix-036 starttime: 1505673026.44 results: success (0) revision: 0408418cdef3a7ed4f72cdaaf5540b07b70021fd ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-17 11:30:26.444960) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-17 11:30:26.445589) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-17 11:30:26.445879) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-17 11:30:26.550224) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-17 11:30:26.550522) ========= 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=14ef55db36f7623abb31b9cf000001d5-1505671170.656474-1949342314 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-17 11:30:26-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 67.1M=0s 2017-09-17 11:30:26 (67.1 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.125878 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-17 11:30:26.707894) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-17 11:30:26.708316) ========= 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=14ef55db36f7623abb31b9cf000001d5-1505671170.656474-1949342314 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018595 ========= master_lag: 0.09 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-17 11:30:26.815778) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-17 11:30:26.816101) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 0408418cdef3a7ed4f72cdaaf5540b07b70021fd --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 0408418cdef3a7ed4f72cdaaf5540b07b70021fd --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=14ef55db36f7623abb31b9cf000001d5-1505671170.656474-1949342314 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-17 11:30:26,862 truncating revision to first 12 chars 2017-09-17 11:30:26,863 Setting DEBUG logging. 2017-09-17 11:30:26,863 attempt 1/10 2017-09-17 11:30:26,863 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/0408418cdef3?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-17 11:30:27,099 unpacking tar archive at: mozilla-central-0408418cdef3/testing/mozharness/ program finished with exit code 0 elapsedTime=0.530964 ========= master_lag: 0.10 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-17 11:30:27.442460) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-17 11:30:27.442852) ========= script_repo_revision: 0408418cdef3a7ed4f72cdaaf5540b07b70021fd ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-17 11:30:27.443271) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-17 11:30:27.443567) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-17 11:30:27.641151) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 47 secs) (at 2017-09-17 11:30:27.641530) ========= /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=14ef55db36f7623abb31b9cf000001d5-1505671170.656474-1949342314 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 11:30:27 INFO - MultiFileLogger online at 20170917 11:30:27 in /builds/slave/test 11:30:27 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 11:30:27 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:30:27 INFO - {'append_to_log': False, 11:30:27 INFO - 'base_work_dir': '/builds/slave/test', 11:30:27 INFO - 'blob_upload_branch': 'Firefox', 11:30:27 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:30:27 INFO - 'branch': 'Firefox', 11:30:27 INFO - 'buildbot_json_path': 'buildprops.json', 11:30:27 INFO - 'code_coverage': False, 11:30:27 INFO - 'config_files': ('talos/linux_config.py',), 11:30:27 INFO - 'default_actions': ('clobber', 11:30:27 INFO - 'read-buildbot-config', 11:30:27 INFO - 'download-and-extract', 11:30:27 INFO - 'populate-webroot', 11:30:27 INFO - 'create-virtualenv', 11:30:27 INFO - 'install', 11:30:27 INFO - 'setup-mitmproxy', 11:30:27 INFO - 'run-tests'), 11:30:27 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:30:27 INFO - 'disable_ccov_upload': False, 11:30:27 INFO - 'disable_stylo': False, 11:30:27 INFO - 'download_minidump_stackwalk': True, 11:30:27 INFO - 'download_symbols': 'ondemand', 11:30:27 INFO - 'enable_stylo': False, 11:30:27 INFO - 'enable_webrender': False, 11:30:27 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 11:30:27 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:30:27 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:30:27 INFO - 'gecko_profile': False, 11:30:27 INFO - 'gecko_profile_interval': 0, 11:30:27 INFO - 'installer_path': 'installer.exe', 11:30:27 INFO - 'log_level': 'info', 11:30:27 INFO - 'log_name': 'talos', 11:30:27 INFO - 'log_to_console': True, 11:30:27 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 11:30:27 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 11:30:27 INFO - 'opt_config_files': (), 11:30:27 INFO - 'pip_index': False, 11:30:27 INFO - 'suite': 'dromaeojs-e10s', 11:30:27 INFO - 'system_bits': '32', 11:30:27 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:30:27 INFO - 'title': 'talos-linux64-ix-036', 11:30:27 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:30:27 INFO - 'use_talos_json': True, 11:30:27 INFO - 'verify': 'False', 11:30:27 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:30:27 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:30:27 INFO - 'work_dir': 'build'} 11:30:27 INFO - [mozharness: 2017-09-17 18:30:27.783124Z] Running clobber step. 11:30:27 INFO - Running pre-action listener: _resource_record_pre_action 11:30:27 INFO - Running main action method: clobber 11:30:27 INFO - rmtree: /builds/slave/test/build 11:30:27 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:30:28 INFO - Running post-action listener: _resource_record_post_action 11:30:28 INFO - [mozharness: 2017-09-17 18:30:28.775099Z] Finished clobber step (success) 11:30:28 INFO - [mozharness: 2017-09-17 18:30:28.775196Z] Running read-buildbot-config step. 11:30:28 INFO - Running pre-action listener: _resource_record_pre_action 11:30:28 INFO - Running main action method: read_buildbot_config 11:30:28 INFO - Using buildbot properties: 11:30:28 INFO - { 11:30:28 INFO - "project": "", 11:30:28 INFO - "product": "firefox", 11:30:28 INFO - "who": "ffxbld@noreply.mozilla.org", 11:30:28 INFO - "installer_path": "public/build/target.tar.bz2", 11:30:28 INFO - "builddir": "mozilla-central_ubuntu64_hw_qr_test-dromaeojs-e10s", 11:30:28 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 11:30:28 INFO - "buildername": "Ubuntu HW 12.04 x64 qr mozilla-central talos dromaeojs-e10s", 11:30:28 INFO - "stage_platform": "linux64-qr", 11:30:28 INFO - "basedir": "/builds/slave/test", 11:30:28 INFO - "buildnumber": 70, 11:30:28 INFO - "platform": "ubuntu64_hw_qr", 11:30:28 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 11:30:28 INFO - "slavebuilddir": "test", 11:30:28 INFO - "taskId": "XxCClwhGRhaRTG89exMbDw", 11:30:28 INFO - "branch": "mozilla-central", 11:30:28 INFO - "script_repo_revision": "production", 11:30:28 INFO - "revision": "0408418cdef3a7ed4f72cdaaf5540b07b70021fd", 11:30:28 INFO - "slavename": "talos-linux64-ix-036", 11:30:28 INFO - "repo_path": "mozilla-central" 11:30:28 INFO - } 11:30:28 INFO - Finding installer, test and symbols from parent task. 11:30:28 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XxCClwhGRhaRTG89exMbDw'}, attempt #1 11:30:29 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XxCClwhGRhaRTG89exMbDw'}, attempt #1 11:30:29 INFO - Task dependencies: BAsmZyBYTdem8SXBoYX9ag 11:30:29 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BAsmZyBYTdem8SXBoYX9ag'}, attempt #1 11:30:30 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/BAsmZyBYTdem8SXBoYX9ag/artifacts/public/build/target.tar.bz2 11:30:30 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/BAsmZyBYTdem8SXBoYX9ag/artifacts/public/build/target.test_packages.json 11:30:30 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/BAsmZyBYTdem8SXBoYX9ag/artifacts/public/build/target.crashreporter-symbols.zip 11:30:30 INFO - Running post-action listener: _resource_record_post_action 11:30:30 INFO - [mozharness: 2017-09-17 18:30:30.122883Z] Finished read-buildbot-config step (success) 11:30:30 INFO - [mozharness: 2017-09-17 18:30:30.123098Z] Running download-and-extract step. 11:30:30 INFO - Running pre-action listener: _resource_record_pre_action 11:30:30 INFO - Running main action method: download_and_extract 11:30:30 INFO - mkdir: /builds/slave/test/build/tests 11:30:30 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')]} 11:30:30 INFO - trying https://queue.taskcluster.net/v1/task/BAsmZyBYTdem8SXBoYX9ag/artifacts/public/build/target.test_packages.json 11:30:30 INFO - Downloading https://queue.taskcluster.net/v1/task/BAsmZyBYTdem8SXBoYX9ag/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 11:30:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BAsmZyBYTdem8SXBoYX9ag/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 11:30:30 INFO - Downloaded 989 bytes. 11:30:30 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 11:30:30 INFO - Using the following test package requirements: 11:30:30 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 11:30:30 INFO - u'common': [u'target.common.tests.zip'], 11:30:30 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 11:30:30 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 11:30:30 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 11:30:30 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 11:30:30 INFO - u'mozbase': [u'target.common.tests.zip'], 11:30:30 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 11:30:30 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 11:30:30 INFO - u'web-platform': [u'target.common.tests.zip', 11:30:30 INFO - u'target.web-platform.tests.tar.gz'], 11:30:30 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 11:30:30 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 11:30:30 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/BAsmZyBYTdem8SXBoYX9ag/artifacts/public/build/target.common.tests.zip 11:30:30 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/BAsmZyBYTdem8SXBoYX9ag/artifacts/public/build/target.common.tests.zip'}, attempt #1 11:30:30 INFO - Fetch https://queue.taskcluster.net/v1/task/BAsmZyBYTdem8SXBoYX9ag/artifacts/public/build/target.common.tests.zip into memory 11:30:32 INFO - Content-Length response header: 54731159 11:30:32 INFO - Bytes received: 54731159 11:30:43 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/BAsmZyBYTdem8SXBoYX9ag/artifacts/public/build/target.talos.tests.zip 11:30:43 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/BAsmZyBYTdem8SXBoYX9ag/artifacts/public/build/target.talos.tests.zip'}, attempt #1 11:30:43 INFO - Fetch https://queue.taskcluster.net/v1/task/BAsmZyBYTdem8SXBoYX9ag/artifacts/public/build/target.talos.tests.zip into memory 11:30:44 INFO - Content-Length response header: 14508748 11:30:44 INFO - Bytes received: 14508748 11:30:44 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')]} 11:30:44 INFO - trying https://queue.taskcluster.net/v1/task/BAsmZyBYTdem8SXBoYX9ag/artifacts/public/build/target.tar.bz2 11:30:44 INFO - Downloading https://queue.taskcluster.net/v1/task/BAsmZyBYTdem8SXBoYX9ag/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 11:30:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BAsmZyBYTdem8SXBoYX9ag/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 11:30:48 INFO - Downloaded 61409685 bytes. 11:30:48 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/BAsmZyBYTdem8SXBoYX9ag/artifacts/public/build/target.tar.bz2 11:30:48 INFO - mkdir: /builds/slave/test/properties 11:30:48 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:30:48 INFO - Writing to file /builds/slave/test/properties/build_url 11:30:48 INFO - Contents: 11:30:48 INFO - build_url:https://queue.taskcluster.net/v1/task/BAsmZyBYTdem8SXBoYX9ag/artifacts/public/build/target.tar.bz2 11:30:48 INFO - Running post-action listener: _resource_record_post_action 11:30:48 INFO - Running post-action listener: find_tests_for_verification 11:30:48 INFO - Running post-action listener: set_extra_try_arguments 11:30:48 INFO - [mozharness: 2017-09-17 18:30:48.604279Z] Finished download-and-extract step (success) 11:30:48 INFO - [mozharness: 2017-09-17 18:30:48.604509Z] Running populate-webroot step. 11:30:48 INFO - Running pre-action listener: _resource_record_pre_action 11:30:48 INFO - Running main action method: populate_webroot 11:30:48 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:30:48 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:30:48 INFO - 'tests': ['tresize', 11:30:48 INFO - 'tcanvasmark']}, 11:30:48 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:30:48 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:30:48 INFO - 'tests': ['dromaeo_css', 11:30:48 INFO - 'kraken']}, 11:30:48 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 11:30:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:30:48 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 11:30:48 INFO - 'talos_options': ['--disable-stylo'], 11:30:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:30:48 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 11:30:48 INFO - 'tests': ['damp', 'tps']}, 11:30:48 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 11:30:48 INFO - 'talos_options': ['--disable-stylo'], 11:30:48 INFO - 'tests': ['damp', 'tps']}, 11:30:48 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:30:48 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:30:48 INFO - 'tests': ['dromaeo_dom']}, 11:30:48 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 11:30:48 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:30:48 INFO - 'tests': ['basic_compositor_video', 11:30:48 INFO - 'glvideo']}, 11:30:48 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 11:30:48 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 11:30:48 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 11:30:48 INFO - 'talos_options': ['--disable-stylo'], 11:30:48 INFO - 'tests': ['ts_paint_webext', 11:30:48 INFO - 'tp5o_webext']}, 11:30:48 INFO - 'other-e10s': {'tests': ['a11yr', 11:30:48 INFO - 'ts_paint', 11:30:48 INFO - 'tpaint', 11:30:48 INFO - 'sessionrestore', 11:30:48 INFO - 'sessionrestore_many_windows', 11:30:48 INFO - 'sessionrestore_no_auto_restore', 11:30:48 INFO - 'tabpaint']}, 11:30:48 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:30:48 INFO - 'tests': ['a11yr', 11:30:48 INFO - 'ts_paint', 11:30:48 INFO - 'tpaint', 11:30:48 INFO - 'sessionrestore', 11:30:48 INFO - 'sessionrestore_many_windows', 11:30:48 INFO - 'sessionrestore_no_auto_restore', 11:30:48 INFO - 'tabpaint']}, 11:30:48 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 11:30:48 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']}, 11:30:48 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:30:48 INFO - 'tests': ['perf_reftest_singletons']}, 11:30:48 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:30:48 INFO - 'tests': ['bloom_basic']}, 11:30:48 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:30:48 INFO - 'tsvgr_opacity', 11:30:48 INFO - 'tart', 11:30:48 INFO - 'tscrollx', 11:30:48 INFO - 'tsvg_static']}, 11:30:48 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:30:48 INFO - 'tests': ['tsvgx', 11:30:48 INFO - 'tsvgr_opacity', 11:30:48 INFO - 'tart', 11:30:48 INFO - 'tscrollx', 11:30:48 INFO - 'tsvg_static']}, 11:30:48 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 11:30:48 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 11:30:48 INFO - 'talos_options': ['--disable-stylo'], 11:30:48 INFO - 'tests': ['tp5o']}, 11:30:48 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 11:30:48 INFO - 'talos_options': ['--mitmproxy', 11:30:48 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 11:30:48 INFO - '--firstNonBlankPaint'], 11:30:48 INFO - 'tests': ['tp6_google', 11:30:48 INFO - 'tp6_youtube', 11:30:48 INFO - 'tp6_amazon', 11:30:48 INFO - 'tp6_facebook']}, 11:30:48 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 11:30:48 INFO - 'talos_options': ['--disable-stylo', 11:30:48 INFO - '--mitmproxy', 11:30:48 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 11:30:48 INFO - '--firstNonBlankPaint'], 11:30:48 INFO - 'tests': ['tp6_google', 11:30:48 INFO - 'tp6_youtube', 11:30:48 INFO - 'tp6_amazon', 11:30:48 INFO - 'tp6_facebook']}, 11:30:48 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 11:30:48 INFO - 'talos_options': ['--stylo-threads=1', 11:30:48 INFO - '--mitmproxy', 11:30:48 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 11:30:48 INFO - '--firstNonBlankPaint'], 11:30:48 INFO - 'tests': ['tp6_google', 11:30:48 INFO - 'tp6_youtube', 11:30:48 INFO - 'tp6_amazon', 11:30:48 INFO - 'tp6_facebook']}, 11:30:48 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 11:30:48 INFO - 'talos_options': ['--xperf_path', 11:30:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:30:48 INFO - 'tests': ['tp5n']}, 11:30:48 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 11:30:48 INFO - 'talos_options': ['--disable-stylo', 11:30:48 INFO - '--xperf_path', 11:30:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:30:48 INFO - 'tests': ['tp5n']}}} 11:30:48 INFO - Running post-action listener: _resource_record_post_action 11:30:48 INFO - [mozharness: 2017-09-17 18:30:48.623157Z] Finished populate-webroot step (success) 11:30:48 INFO - [mozharness: 2017-09-17 18:30:48.623247Z] Running create-virtualenv step. 11:30:48 INFO - Running pre-action listener: _resource_record_pre_action 11:30:48 INFO - Running main action method: create_virtualenv 11:30:48 INFO - Creating virtualenv /builds/slave/test/build/venv 11:30:48 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 11:30:48 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 11:30:48 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 11:30:48 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:30:48 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:30:49 INFO - Installing distribute.............................................................................................................................................................................................done. 11:30:49 INFO - Installing pip...............done. 11:30:49 INFO - Return code: 0 11:30:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 11:30:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 11:30:49 INFO - Reading from file tmpfile_stdout 11:30:49 INFO - Output received: 11:30:49 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 11:30:49 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:30:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:30:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:30:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:30:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:30:49 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 0xe701f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1039a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11dca60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11e0090>, '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 0x11e0d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11daf90>, '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': '14ef55db36f7623abb31b9cf000001d5-1505671170.656474-1949342314', '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 11:30:49 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 11:30:49 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 11:30:49 INFO - Using env: {'DISPLAY': ':0', 11:30:49 INFO - 'HOME': '/home/cltbld', 11:30:49 INFO - 'LANG': 'en_US.UTF-8', 11:30:49 INFO - 'LANGUAGE': 'en_US:en', 11:30:49 INFO - 'LOGNAME': 'cltbld', 11:30:49 INFO - 'MAIL': '/var/mail/cltbld', 11:30:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:30:49 INFO - 'MOZ_NO_REMOTE': '1', 11:30:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:30:49 INFO - 'NO_EM_RESTART': '1', 11:30:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:30:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:30:49 INFO - 'PWD': '/builds/slave/test', 11:30:49 INFO - 'SHELL': '/bin/bash', 11:30:49 INFO - 'SHLVL': '1', 11:30:49 INFO - 'TERM': 'linux', 11:30:49 INFO - 'TMOUT': '86400', 11:30:49 INFO - 'USER': 'cltbld', 11:30:49 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1505671170.656474-1949342314', 11:30:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:30:49 INFO - '_': '/tools/buildbot/bin/python'} 11:30:50 INFO - Ignoring indexes: http://pypi.python.org/simple/ 11:30:50 INFO - Downloading/unpacking pip>=1.5 11:30:50 INFO - Running setup.py egg_info for package pip 11:30:50 INFO - warning: no files found matching 'pip/cacert.pem' 11:30:50 INFO - warning: no files found matching '*.html' under directory 'docs' 11:30:50 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 11:30:50 INFO - no previously-included directories found matching 'docs/_build/_sources' 11:30:50 INFO - Installing collected packages: pip 11:30:50 INFO - Found existing installation: pip 1.1 11:30:50 INFO - Uninstalling pip: 11:30:50 INFO - Successfully uninstalled pip 11:30:50 INFO - Running setup.py install for pip 11:30:50 INFO - warning: no files found matching 'pip/cacert.pem' 11:30:50 INFO - warning: no files found matching '*.html' under directory 'docs' 11:30:50 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 11:30:50 INFO - no previously-included directories found matching 'docs/_build/_sources' 11:30:50 INFO - Installing pip script to /builds/slave/test/build/venv/bin 11:30:50 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 11:30:50 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 11:30:50 INFO - Successfully installed pip 11:30:50 INFO - Cleaning up... 11:30:50 INFO - Return code: 0 11:30:50 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 11:30:50 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')]} 11:30:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:30:50 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')]} 11:30:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:30:50 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 0xe701f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1039a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11dca60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11e0090>, '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 0x11e0d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11daf90>, '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': '14ef55db36f7623abb31b9cf000001d5-1505671170.656474-1949342314', '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 11:30:50 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 11:30:50 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 11:30:50 INFO - Using env: {'DISPLAY': ':0', 11:30:50 INFO - 'HOME': '/home/cltbld', 11:30:50 INFO - 'LANG': 'en_US.UTF-8', 11:30:50 INFO - 'LANGUAGE': 'en_US:en', 11:30:50 INFO - 'LOGNAME': 'cltbld', 11:30:50 INFO - 'MAIL': '/var/mail/cltbld', 11:30:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:30:50 INFO - 'MOZ_NO_REMOTE': '1', 11:30:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:30:50 INFO - 'NO_EM_RESTART': '1', 11:30:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:30:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:30:50 INFO - 'PWD': '/builds/slave/test', 11:30:50 INFO - 'SHELL': '/bin/bash', 11:30:50 INFO - 'SHLVL': '1', 11:30:50 INFO - 'TERM': 'linux', 11:30:50 INFO - 'TMOUT': '86400', 11:30:50 INFO - 'USER': 'cltbld', 11:30:50 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1505671170.656474-1949342314', 11:30:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:30:50 INFO - '_': '/tools/buildbot/bin/python'} 11:30:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:30:51 INFO - Downloading/unpacking psutil>=3.1.1 11:30:51 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 11:30:51 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 11:30:51 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 11:30:51 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 11:30:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:30:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:30:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:30:52 INFO - Installing collected packages: psutil 11:30:52 INFO - Running setup.py install for psutil 11:30:52 INFO - building 'psutil._psutil_linux' extension 11:30:52 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 11:30:52 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 11:30:52 INFO - building 'psutil._psutil_posix' extension 11:30:52 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 11:30:52 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 11:30:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:30:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:30:52 INFO - Successfully installed psutil 11:30:52 INFO - Cleaning up... 11:30:52 INFO - Return code: 0 11:30:52 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 11:30:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:30:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:30:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:30:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:30:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe701f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1039a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11dca60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11e0090>, '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 0x11e0d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11daf90>, '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': '14ef55db36f7623abb31b9cf000001d5-1505671170.656474-1949342314', '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 11:30:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 11:30:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 11:30:52 INFO - Using env: {'DISPLAY': ':0', 11:30:52 INFO - 'HOME': '/home/cltbld', 11:30:52 INFO - 'LANG': 'en_US.UTF-8', 11:30:52 INFO - 'LANGUAGE': 'en_US:en', 11:30:52 INFO - 'LOGNAME': 'cltbld', 11:30:52 INFO - 'MAIL': '/var/mail/cltbld', 11:30:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:30:52 INFO - 'MOZ_NO_REMOTE': '1', 11:30:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:30:52 INFO - 'NO_EM_RESTART': '1', 11:30:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:30:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:30:52 INFO - 'PWD': '/builds/slave/test', 11:30:52 INFO - 'SHELL': '/bin/bash', 11:30:52 INFO - 'SHLVL': '1', 11:30:52 INFO - 'TERM': 'linux', 11:30:52 INFO - 'TMOUT': '86400', 11:30:52 INFO - 'USER': 'cltbld', 11:30:52 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1505671170.656474-1949342314', 11:30:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:30:52 INFO - '_': '/tools/buildbot/bin/python'} 11:30:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:30:52 INFO - Downloading/unpacking mozsystemmonitor==0.3 11:30: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 11:30: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 11:30: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 11:30: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 11:30:54 INFO - Downloading mozsystemmonitor-0.3.tar.gz 11:30:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:30:54 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 11:30:54 INFO - Installing collected packages: mozsystemmonitor 11:30:54 INFO - Running setup.py install for mozsystemmonitor 11:30:54 INFO - Successfully installed mozsystemmonitor 11:30:54 INFO - Cleaning up... 11:30:54 INFO - Return code: 0 11:30:54 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 11:30: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')]} 11:30:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:30: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')]} 11:30:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:30:54 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 0xe701f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1039a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11dca60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11e0090>, '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 0x11e0d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11daf90>, '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': '14ef55db36f7623abb31b9cf000001d5-1505671170.656474-1949342314', '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 11:30:54 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 11:30:54 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 11:30:54 INFO - Using env: {'DISPLAY': ':0', 11:30:54 INFO - 'HOME': '/home/cltbld', 11:30:54 INFO - 'LANG': 'en_US.UTF-8', 11:30:54 INFO - 'LANGUAGE': 'en_US:en', 11:30:54 INFO - 'LOGNAME': 'cltbld', 11:30:54 INFO - 'MAIL': '/var/mail/cltbld', 11:30:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:30:54 INFO - 'MOZ_NO_REMOTE': '1', 11:30:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:30:54 INFO - 'NO_EM_RESTART': '1', 11:30:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:30:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:30:54 INFO - 'PWD': '/builds/slave/test', 11:30:54 INFO - 'SHELL': '/bin/bash', 11:30:54 INFO - 'SHLVL': '1', 11:30:54 INFO - 'TERM': 'linux', 11:30:54 INFO - 'TMOUT': '86400', 11:30:54 INFO - 'USER': 'cltbld', 11:30:54 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1505671170.656474-1949342314', 11:30:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:30:54 INFO - '_': '/tools/buildbot/bin/python'} 11:30:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:30:54 INFO - Downloading/unpacking jsonschema==2.5.1 11:30: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 11:30: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 11:30: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 11:30: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 11:30:55 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:30:55 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 11:30:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:30:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:30:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:30:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:30:55 INFO - Downloading functools32-3.2.3-2.tar.gz 11:30:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:30:55 INFO - warning: no files found matching '*.txt' 11:30:55 INFO - no previously-included directories found matching 'build' 11:30:55 INFO - no previously-included directories found matching 'dist' 11:30:55 INFO - no previously-included directories found matching '.git*' 11:30:55 INFO - Installing collected packages: jsonschema, functools32 11:30:55 INFO - Running setup.py install for functools32 11:30:55 INFO - warning: no files found matching '*.txt' 11:30:55 INFO - no previously-included directories found matching 'build' 11:30:55 INFO - no previously-included directories found matching 'dist' 11:30:55 INFO - no previously-included directories found matching '.git*' 11:30:55 INFO - Successfully installed jsonschema functools32 11:30:55 INFO - Cleaning up... 11:30:55 INFO - Return code: 0 11:30:55 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 11:30:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:30:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:30:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:30:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:30:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0xe701f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1039a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11dca60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11e0090>, '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 0x11e0d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11daf90>, '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': '14ef55db36f7623abb31b9cf000001d5-1505671170.656474-1949342314', '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 11:30:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 11:30:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 11:30:55 INFO - Using env: {'DISPLAY': ':0', 11:30:55 INFO - 'HOME': '/home/cltbld', 11:30:55 INFO - 'LANG': 'en_US.UTF-8', 11:30:55 INFO - 'LANGUAGE': 'en_US:en', 11:30:55 INFO - 'LOGNAME': 'cltbld', 11:30:55 INFO - 'MAIL': '/var/mail/cltbld', 11:30:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:30:55 INFO - 'MOZ_NO_REMOTE': '1', 11:30:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:30:55 INFO - 'NO_EM_RESTART': '1', 11:30:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:30:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:30:55 INFO - 'PWD': '/builds/slave/test', 11:30:55 INFO - 'SHELL': '/bin/bash', 11:30:55 INFO - 'SHLVL': '1', 11:30:55 INFO - 'TERM': 'linux', 11:30:55 INFO - 'TMOUT': '86400', 11:30:55 INFO - 'USER': 'cltbld', 11:30:55 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1505671170.656474-1949342314', 11:30:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:30:55 INFO - '_': '/tools/buildbot/bin/python'} 11:30:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:30:56 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 11:30:56 INFO - Cleaning up... 11:30:56 INFO - Return code: 0 11:30:56 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:30: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')]} 11:30:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:30: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')]} 11:30:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:30: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', '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 0xe701f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1039a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11dca60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11e0090>, '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 0x11e0d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11daf90>, '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': '14ef55db36f7623abb31b9cf000001d5-1505671170.656474-1949342314', '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 11:30: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 11:30: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 blobuploader==1.2.4 11:30:56 INFO - Using env: {'DISPLAY': ':0', 11:30:56 INFO - 'HOME': '/home/cltbld', 11:30:56 INFO - 'LANG': 'en_US.UTF-8', 11:30:56 INFO - 'LANGUAGE': 'en_US:en', 11:30:56 INFO - 'LOGNAME': 'cltbld', 11:30:56 INFO - 'MAIL': '/var/mail/cltbld', 11:30:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:30:56 INFO - 'MOZ_NO_REMOTE': '1', 11:30:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:30:56 INFO - 'NO_EM_RESTART': '1', 11:30:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:30:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:30:56 INFO - 'PWD': '/builds/slave/test', 11:30:56 INFO - 'SHELL': '/bin/bash', 11:30:56 INFO - 'SHLVL': '1', 11:30:56 INFO - 'TERM': 'linux', 11:30:56 INFO - 'TMOUT': '86400', 11:30:56 INFO - 'USER': 'cltbld', 11:30:56 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1505671170.656474-1949342314', 11:30:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:30:56 INFO - '_': '/tools/buildbot/bin/python'} 11:30:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:30:56 INFO - Downloading/unpacking blobuploader==1.2.4 11:30:56 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 11:30:56 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 11:30:56 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 11:30:56 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 11:30:57 INFO - Downloading blobuploader-1.2.4.tar.gz 11:30:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:30:57 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:30:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:30:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:30:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:30:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:30:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:30:57 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:30:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:30:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:30:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:30:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:30:57 INFO - Downloading docopt-0.6.1.tar.gz 11:30:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:30:58 INFO - Installing collected packages: blobuploader, requests, docopt 11:30:58 INFO - Running setup.py install for blobuploader 11:30:58 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:30:58 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:30:58 INFO - Running setup.py install for requests 11:30:58 INFO - Running setup.py install for docopt 11:31:00 INFO - Successfully installed blobuploader requests docopt 11:31:00 INFO - Cleaning up... 11:31:00 INFO - Return code: 0 11:31:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:31:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:31:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:31:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:31:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:31:00 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 0xe701f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1039a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11dca60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11e0090>, '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 0x11e0d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11daf90>, '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': '14ef55db36f7623abb31b9cf000001d5-1505671170.656474-1949342314', '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 11:31:00 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 11:31:00 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 11:31:00 INFO - Using env: {'DISPLAY': ':0', 11:31:00 INFO - 'HOME': '/home/cltbld', 11:31:00 INFO - 'LANG': 'en_US.UTF-8', 11:31:00 INFO - 'LANGUAGE': 'en_US:en', 11:31:00 INFO - 'LOGNAME': 'cltbld', 11:31:00 INFO - 'MAIL': '/var/mail/cltbld', 11:31:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:31:00 INFO - 'MOZ_NO_REMOTE': '1', 11:31:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:31:00 INFO - 'NO_EM_RESTART': '1', 11:31:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:31:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:31:00 INFO - 'PWD': '/builds/slave/test', 11:31:00 INFO - 'SHELL': '/bin/bash', 11:31:00 INFO - 'SHLVL': '1', 11:31:00 INFO - 'TERM': 'linux', 11:31:00 INFO - 'TMOUT': '86400', 11:31:00 INFO - 'USER': 'cltbld', 11:31:00 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1505671170.656474-1949342314', 11:31:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:31:00 INFO - '_': '/tools/buildbot/bin/python'} 11:31:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:31:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:31:00 INFO - Running setup.py (path:/tmp/pip-x7ejue-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:31:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:31:00 INFO - Running setup.py (path:/tmp/pip-YoSfHo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:31:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:31:00 INFO - Running setup.py (path:/tmp/pip-hgkS4b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:31:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:31:00 INFO - Running setup.py (path:/tmp/pip-Af1N_N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:31:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:31:00 INFO - Running setup.py (path:/tmp/pip-bRFk68-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:31:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:31:00 INFO - Running setup.py (path:/tmp/pip-f6dgK8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:31:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:31:00 INFO - Running setup.py (path:/tmp/pip-eqaAqf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:31:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:31:00 INFO - Running setup.py (path:/tmp/pip-WwpJd6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:31:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:31:00 INFO - Running setup.py (path:/tmp/pip-o_W_0U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:31:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:31:00 INFO - Running setup.py (path:/tmp/pip-4Ga3Bi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:31:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:31:00 INFO - Running setup.py (path:/tmp/pip-CX5J8u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:31:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:31:01 INFO - Running setup.py (path:/tmp/pip-XeH3AW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:31:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:31:01 INFO - Running setup.py (path:/tmp/pip-hfCEpR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:31:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:31:01 INFO - Running setup.py (path:/tmp/pip-oGhSiW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:31:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:31:01 INFO - Running setup.py (path:/tmp/pip-KCQCNM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:31:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:31:01 INFO - Running setup.py (path:/tmp/pip-RMdXrG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:31:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:31:01 INFO - Running setup.py (path:/tmp/pip-qPyNO_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:31:01 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:31:01 INFO - Running setup.py install for manifestparser 11:31:01 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:31:01 INFO - Running setup.py install for mozcrash 11:31:01 INFO - Running setup.py install for mozdebug 11:31:01 INFO - Running setup.py install for mozdevice 11:31:01 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:31:01 INFO - Running setup.py install for mozfile 11:31:01 INFO - Running setup.py install for mozhttpd 11:31:02 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:31:02 INFO - Running setup.py install for mozinfo 11:31:02 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:31:02 INFO - Running setup.py install for mozInstall 11:31:02 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:31:02 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:31:02 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:31:02 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:31:02 INFO - Running setup.py install for mozleak 11:31:02 INFO - Running setup.py install for mozlog 11:31:02 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:31:02 INFO - Running setup.py install for moznetwork 11:31:02 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:31:02 INFO - Running setup.py install for mozprocess 11:31:02 INFO - Running setup.py install for mozprofile 11:31:02 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:31:02 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:31:02 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:31:02 INFO - Running setup.py install for mozrunner 11:31:02 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:31:02 INFO - Running setup.py install for mozscreenshot 11:31:03 INFO - Running setup.py install for moztest 11:31:03 INFO - Running setup.py install for mozversion 11:31:03 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:31:03 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:31:03 INFO - Cleaning up... 11:31:03 INFO - Return code: 0 11:31:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:31:03 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')]} 11:31:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:31:03 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')]} 11:31:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:31:03 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 0xe701f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1039a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11dca60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11e0090>, '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 0x11e0d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11daf90>, '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': '14ef55db36f7623abb31b9cf000001d5-1505671170.656474-1949342314', '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 11:31:03 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 11:31:03 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 11:31:03 INFO - Using env: {'DISPLAY': ':0', 11:31:03 INFO - 'HOME': '/home/cltbld', 11:31:03 INFO - 'LANG': 'en_US.UTF-8', 11:31:03 INFO - 'LANGUAGE': 'en_US:en', 11:31:03 INFO - 'LOGNAME': 'cltbld', 11:31:03 INFO - 'MAIL': '/var/mail/cltbld', 11:31:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:31:03 INFO - 'MOZ_NO_REMOTE': '1', 11:31:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:31:03 INFO - 'NO_EM_RESTART': '1', 11:31:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:31:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:31:03 INFO - 'PWD': '/builds/slave/test', 11:31:03 INFO - 'SHELL': '/bin/bash', 11:31:03 INFO - 'SHLVL': '1', 11:31:03 INFO - 'TERM': 'linux', 11:31:03 INFO - 'TMOUT': '86400', 11:31:03 INFO - 'USER': 'cltbld', 11:31:03 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1505671170.656474-1949342314', 11:31:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:31:03 INFO - '_': '/tools/buildbot/bin/python'} 11:31:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:31:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:31:03 INFO - Running setup.py (path:/tmp/pip-9cMVCU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:31:03 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)) 11:31:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:31:03 INFO - Running setup.py (path:/tmp/pip-TdZV2w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:31:03 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)) 11:31:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:31:03 INFO - Running setup.py (path:/tmp/pip-KZtvfm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:31:03 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)) 11:31:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:31:03 INFO - Running setup.py (path:/tmp/pip-vmwsdw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:31:03 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)) 11:31:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:31:03 INFO - Running setup.py (path:/tmp/pip-MDsZAg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:31:03 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)) 11:31:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:31:03 INFO - Running setup.py (path:/tmp/pip-ZL82cC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:31:03 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)) 11:31:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:31:03 INFO - Running setup.py (path:/tmp/pip-rFhgd5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:31:03 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)) 11:31:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:31:03 INFO - Running setup.py (path:/tmp/pip-dr08Q5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:31:04 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)) 11:31:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:31:04 INFO - Running setup.py (path:/tmp/pip-SZ3kTk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:31:04 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)) 11:31:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:31:04 INFO - Running setup.py (path:/tmp/pip-gtu7iM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:31:04 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)) 11:31:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:31:04 INFO - Running setup.py (path:/tmp/pip-TuNiED-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:31:04 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)) 11:31:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:31:04 INFO - Running setup.py (path:/tmp/pip-BRvfEe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:31:04 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)) 11:31:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:31:04 INFO - Running setup.py (path:/tmp/pip-zo_xJi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:31:04 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)) 11:31:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:31:04 INFO - Running setup.py (path:/tmp/pip-H3ScSb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:31:04 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)) 11:31:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:31:04 INFO - Running setup.py (path:/tmp/pip-bhCWYt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:31:04 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)) 11:31:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:31:04 INFO - Running setup.py (path:/tmp/pip-7HwSOi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:31:04 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)) 11:31:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:31:04 INFO - Running setup.py (path:/tmp/pip-XTuyig-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:31:04 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)) 11:31:04 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)) 11:31:04 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)) 11:31:04 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)) 11:31:04 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)) 11:31:04 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)) 11:31:04 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)) 11:31:04 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:31:04 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 11:31:04 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 11:31:04 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 11:31:04 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 11:31:05 INFO - Downloading blessings-1.6.tar.gz 11:31:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:31:05 INFO - Installing collected packages: blessings 11:31:05 INFO - Running setup.py install for blessings 11:31:05 INFO - Successfully installed blessings 11:31:05 INFO - Cleaning up... 11:31:06 INFO - Return code: 0 11:31:06 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:31:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:31:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:31:06 INFO - Reading from file tmpfile_stdout 11:31:06 INFO - Current package versions: 11:31:06 INFO - argparse == 1.2.1 11:31:06 INFO - blessings == 1.6 11:31:06 INFO - blobuploader == 1.2.4 11:31:06 INFO - docopt == 0.6.1 11:31:06 INFO - functools32 == 3.2.3-2 11:31:06 INFO - jsonschema == 2.5.1 11:31:06 INFO - manifestparser == 1.1 11:31:06 INFO - mozInstall == 1.13 11:31:06 INFO - mozcrash == 1.0 11:31:06 INFO - mozdebug == 0.1 11:31:06 INFO - mozdevice == 0.51 11:31:06 INFO - mozfile == 1.2 11:31:06 INFO - mozhttpd == 0.7 11:31:06 INFO - mozinfo == 0.10 11:31:06 INFO - mozleak == 0.1 11:31:06 INFO - mozlog == 3.5 11:31:06 INFO - moznetwork == 0.27 11:31:06 INFO - mozprocess == 0.25 11:31:06 INFO - mozprofile == 0.28 11:31:06 INFO - mozrunner == 6.13 11:31:06 INFO - mozscreenshot == 0.1 11:31:06 INFO - mozsystemmonitor == 0.3 11:31:06 INFO - moztest == 0.8 11:31:06 INFO - mozversion == 1.4 11:31:06 INFO - psutil == 3.1.1 11:31:06 INFO - requests == 1.2.3 11:31:06 INFO - wsgiref == 0.1.2 11:31:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:31:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:31:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:31:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:31:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:31:06 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 0xe701f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1039a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11dca60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11e0090>, '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 0x11e0d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11daf90>, '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': '14ef55db36f7623abb31b9cf000001d5-1505671170.656474-1949342314', '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 11:31:06 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 11:31:06 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 11:31:06 INFO - Using env: {'DISPLAY': ':0', 11:31:06 INFO - 'HOME': '/home/cltbld', 11:31:06 INFO - 'LANG': 'en_US.UTF-8', 11:31:06 INFO - 'LANGUAGE': 'en_US:en', 11:31:06 INFO - 'LOGNAME': 'cltbld', 11:31:06 INFO - 'MAIL': '/var/mail/cltbld', 11:31:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:31:06 INFO - 'MOZ_NO_REMOTE': '1', 11:31:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:31:06 INFO - 'NO_EM_RESTART': '1', 11:31:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:31:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:31:06 INFO - 'PWD': '/builds/slave/test', 11:31:06 INFO - 'SHELL': '/bin/bash', 11:31:06 INFO - 'SHLVL': '1', 11:31:06 INFO - 'TERM': 'linux', 11:31:06 INFO - 'TMOUT': '86400', 11:31:06 INFO - 'USER': 'cltbld', 11:31:06 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1505671170.656474-1949342314', 11:31:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:31:06 INFO - '_': '/tools/buildbot/bin/python'} 11:31:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:31:06 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)) 11:31:06 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)) 11:31:06 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)) 11:31:06 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)) 11:31:06 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)) 11:31:06 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)) 11:31:06 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)) 11:31:06 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)) 11:31:06 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)) 11:31:06 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 11:31:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:31:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:31:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:31:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:31:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 11:31:07 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)) 11:31:07 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)) 11:31:07 INFO - Installing collected packages: simplejson 11:31:07 INFO - Running setup.py install for simplejson 11:31:07 INFO - building 'simplejson._speedups' extension 11:31:07 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 11:31:08 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 11:31:08 INFO - Successfully installed simplejson 11:31:08 INFO - Cleaning up... 11:31:08 INFO - Return code: 0 11:31:08 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:31:08 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')]} 11:31:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:31:08 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')]} 11:31:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:31:08 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 0xe701f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1039a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11dca60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11e0090>, '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 0x11e0d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11daf90>, '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': '14ef55db36f7623abb31b9cf000001d5-1505671170.656474-1949342314', '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 11:31:08 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 11:31:08 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 11:31:08 INFO - Using env: {'DISPLAY': ':0', 11:31:08 INFO - 'HOME': '/home/cltbld', 11:31:08 INFO - 'LANG': 'en_US.UTF-8', 11:31:08 INFO - 'LANGUAGE': 'en_US:en', 11:31:08 INFO - 'LOGNAME': 'cltbld', 11:31:08 INFO - 'MAIL': '/var/mail/cltbld', 11:31:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:31:08 INFO - 'MOZ_NO_REMOTE': '1', 11:31:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:31:08 INFO - 'NO_EM_RESTART': '1', 11:31:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:31:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:31:08 INFO - 'PWD': '/builds/slave/test', 11:31:08 INFO - 'SHELL': '/bin/bash', 11:31:08 INFO - 'SHLVL': '1', 11:31:08 INFO - 'TERM': 'linux', 11:31:08 INFO - 'TMOUT': '86400', 11:31:08 INFO - 'USER': 'cltbld', 11:31:08 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1505671170.656474-1949342314', 11:31:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:31:08 INFO - '_': '/tools/buildbot/bin/python'} 11:31:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:31:08 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 11:31:08 INFO - Cleaning up... 11:31:08 INFO - Return code: 0 11:31:08 INFO - Running post-action listener: _resource_record_post_action 11:31:08 INFO - Running post-action listener: _start_resource_monitoring 11:31:08 INFO - Starting resource monitoring. 11:31:08 INFO - [mozharness: 2017-09-17 18:31:08.927174Z] Finished create-virtualenv step (success) 11:31:08 INFO - [mozharness: 2017-09-17 18:31:08.927684Z] Running install step. 11:31:08 INFO - Running pre-action listener: _resource_record_pre_action 11:31:08 INFO - Running main action method: install 11:31:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:31:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:31:09 INFO - Reading from file tmpfile_stdout 11:31:09 INFO - Detecting whether we're running mozinstall >=1.0... 11:31:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:31:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:31:09 INFO - Reading from file tmpfile_stdout 11:31:09 INFO - Output received: 11:31:09 INFO - Usage: mozinstall [options] installer 11:31:09 INFO - Options: 11:31:09 INFO - -h, --help show this help message and exit 11:31:09 INFO - -d DEST, --destination=DEST 11:31:09 INFO - Directory to install application into. [default: 11:31:09 INFO - "/builds/slave/test"] 11:31:09 INFO - --app=APP Application being installed. [default: firefox] 11:31:09 INFO - mkdir: /builds/slave/test/build/application 11:31:09 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'] 11:31:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 11:31:26 INFO - Reading from file tmpfile_stdout 11:31:26 INFO - Output received: 11:31:26 INFO - /builds/slave/test/build/application/firefox/firefox 11:31:26 INFO - Running post-action listener: _resource_record_post_action 11:31:26 INFO - [mozharness: 2017-09-17 18:31:26.986356Z] Finished install step (success) 11:31:26 INFO - [mozharness: 2017-09-17 18:31:26.986640Z] Running setup-mitmproxy step. 11:31:26 INFO - Running pre-action listener: _resource_record_pre_action 11:31:26 INFO - Running main action method: setup_mitmproxy 11:31:26 INFO - Skipping: mitmproxy is not required 11:31:26 INFO - Running post-action listener: _resource_record_post_action 11:31:26 INFO - [mozharness: 2017-09-17 18:31:26.988247Z] Finished setup-mitmproxy step (success) 11:31:26 INFO - [mozharness: 2017-09-17 18:31:26.988556Z] Running run-tests step. 11:31:26 INFO - Running pre-action listener: _resource_record_pre_action 11:31:26 INFO - Running pre-action listener: _set_gcov_prefix 11:31:26 INFO - Running main action method: run_tests 11:31:26 WARNING - Try message not found. 11:31:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:31:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:31:26 INFO - Python 2.7.3 11:31:26 INFO - Return code: 0 11:31:26 INFO - grabbing minidump binary from tooltool 11:31:27 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')]} 11:31:27 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 0x11e0090>, '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 0x11e0d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11daf90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:31:27 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 11:31:27 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 11:31:27 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 11:31:27 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:31:27 INFO - Return code: 0 11:31:27 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 11:31:27 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:31:27 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:31:27 INFO - ENV: RUST_BACKTRACE is now full 11:31:27 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 11:31:27 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:31:27 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/BAsmZyBYTdem8SXBoYX9ag/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-036', '--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 11:31:27 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/BAsmZyBYTdem8SXBoYX9ag/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-036 --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 11:31:27 INFO - Using env: {'DISPLAY': ':0', 11:31:27 INFO - 'HOME': '/home/cltbld', 11:31:27 INFO - 'LANG': 'en_US.UTF-8', 11:31:27 INFO - 'LANGUAGE': 'en_US:en', 11:31:27 INFO - 'LOGNAME': 'cltbld', 11:31:27 INFO - 'MAIL': '/var/mail/cltbld', 11:31:27 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:31:27 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 11:31:27 INFO - 'MOZ_ACCELERATED': '1', 11:31:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:31:27 INFO - 'MOZ_NO_REMOTE': '1', 11:31:27 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:31:27 INFO - 'MOZ_WEBRENDER': '1', 11:31:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:31:27 INFO - 'NO_EM_RESTART': '1', 11:31:27 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:31:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:31:27 INFO - 'PWD': '/builds/slave/test', 11:31:27 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:31:27 INFO - 'RUST_BACKTRACE': 'full', 11:31:27 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 11:31:27 INFO - 'SHELL': '/bin/bash', 11:31:27 INFO - 'SHLVL': '1', 11:31:27 INFO - 'TERM': 'linux', 11:31:27 INFO - 'TMOUT': '86400', 11:31:27 INFO - 'USER': 'cltbld', 11:31:27 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1505671170.656474-1949342314', 11:31:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:31:27 INFO - '_': '/tools/buildbot/bin/python'} 11:31:27 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/BAsmZyBYTdem8SXBoYX9ag/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-036', '--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 11:31:27 INFO - mozversion application_buildid: 20170917174109 11:31:27 INFO - mozversion application_changeset: 0408418cdef3a7ed4f72cdaaf5540b07b70021fd 11:31:27 INFO - mozversion application_display_name: Nightly 11:31:27 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:31:27 INFO - mozversion application_name: Firefox 11:31:27 INFO - mozversion application_remotingname: firefox 11:31:27 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 11:31:27 INFO - mozversion application_vendor: Mozilla 11:31:27 INFO - mozversion application_version: 57.0a1 11:31:27 INFO - mozversion platform_buildid: 20170917174109 11:31:27 INFO - mozversion platform_changeset: 0408418cdef3a7ed4f72cdaaf5540b07b70021fd 11:31:27 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 11:31:27 INFO - mozversion platform_version: 57.0a1 11:31:27 INFO - using testdate: 1505673087 11:31:27 INFO - actual date: 1505673087 11:31:27 INFO - starting webserver on 'localhost:37323' 11:31:27 INFO - SUITE-START | Running 2 tests 11:31:27 INFO - TEST-START | dromaeo_css 11:31:27 INFO - Initialising browser for dromaeo_css test... 11:31:27 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:37323/getInfo.html -profile /tmp/tmpRiNFri/profile 11:31:27 INFO - TEST-INFO | started process 5036 (/builds/slave/test/build/application/firefox/firefox http://localhost:37323/getInfo.html) 11:31:34 INFO - TEST-INFO | 5036: exit 0 11:31:34 INFO - Browser initialized. 11:31:34 INFO - Running cycle 1/1 for dromaeo_css test... 11:31:34 INFO - Using env: {'DISPLAY': ':0', 11:31:34 INFO - 'HOME': '/home/cltbld', 11:31:34 INFO - 'JSGC_DISABLE_POISONING': '1', 11:31:34 INFO - 'LANG': 'en_US.UTF-8', 11:31:34 INFO - 'LANGUAGE': 'en_US:en', 11:31:34 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 11:31:34 INFO - 'LOGNAME': 'cltbld', 11:31:34 INFO - 'MAIL': '/var/mail/cltbld', 11:31:34 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:31:34 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 11:31:34 INFO - 'MOZ_ACCELERATED': '1', 11:31:34 INFO - 'MOZ_CRASHREPORTER': '1', 11:31:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:31:34 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 11:31:34 INFO - 'MOZ_NO_REMOTE': '1', 11:31:34 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:31:34 INFO - 'MOZ_WEBRENDER': '1', 11:31:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:31:34 INFO - 'NO_EM_RESTART': '1', 11:31:34 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:31:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:31:34 INFO - 'PWD': '/builds/slave/test', 11:31:34 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:31:34 INFO - 'RUST_BACKTRACE': 'full', 11:31:34 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 11:31:34 INFO - 'SHELL': '/bin/bash', 11:31:34 INFO - 'SHLVL': '1', 11:31:34 INFO - 'TERM': 'linux', 11:31:34 INFO - 'TMOUT': '86400', 11:31:34 INFO - 'USER': 'cltbld', 11:31:34 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1505671170.656474-1949342314', 11:31:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:31:34 INFO - '_': '/tools/buildbot/bin/python'} 11:31:34 INFO - TEST-INFO | started process 5282 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpRiNFri/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 11:31:35 INFO - PID 5282 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 11:31:35 INFO - PID 5282 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 11:31:35 INFO - PID 5282 | 11:31:35 INFO - PID 5282 | (/builds/slave/test/build/application/firefox/firefox:5341): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 11:31:35 INFO - PID 5282 | 11:31:46 INFO - PID 5282 | RSS: Main: 186150912 11:31:46 INFO - PID 5282 | 11:34:44 INFO - PID 5282 | Cycle 1(1): loaded http://localhost:37323/tests/dromaeo/cssquery-dojo.html (next: http://localhost:37323/tests/dromaeo/cssquery-ext.html) 11:34:45 INFO - PID 5282 | RSS: Main: 189624320 11:34:45 INFO - PID 5282 | 11:37:47 INFO - PID 5282 | Cycle 1(1): loaded http://localhost:37323/tests/dromaeo/cssquery-ext.html (next: http://localhost:37323/tests/dromaeo/cssquery-jquery.html) 11:37:48 INFO - PID 5282 | RSS: Main: 184246272 11:37:48 INFO - PID 5282 | 11:40:50 INFO - PID 5282 | Cycle 1(1): loaded http://localhost:37323/tests/dromaeo/cssquery-jquery.html (next: http://localhost:37323/tests/dromaeo/cssquery-mootools.html) 11:40:51 INFO - PID 5282 | RSS: Main: 188649472 11:40:51 INFO - PID 5282 | 11:43:53 INFO - PID 5282 | Cycle 1(1): loaded http://localhost:37323/tests/dromaeo/cssquery-mootools.html (next: http://localhost:37323/tests/dromaeo/cssquery-prototype.html) 11:43:53 INFO - PID 5282 | RSS: Main: 187437056 11:43:53 INFO - PID 5282 | 11:46:57 INFO - PID 5282 | Cycle 1(1): loaded http://localhost:37323/tests/dromaeo/cssquery-prototype.html (next: http://localhost:37323/tests/dromaeo/cssquery-yui.html) 11:46:58 INFO - PID 5282 | RSS: Main: 189464576 11:46:58 INFO - PID 5282 | 11:50:00 INFO - PID 5282 | Cycle 1(1): loaded http://localhost:37323/tests/dromaeo/cssquery-yui.html (next: http://localhost:37323/tests/dromaeo/cssquery-dojo.html) 11:50:00 INFO - PID 5282 | RSS: Main: 191983616 11:50:00 INFO - PID 5282 | 11:50:00 INFO - PID 5282 | __start_tp_report 11:50:00 INFO - PID 5282 | _x_x_mozilla_page_load 11:50:00 INFO - PID 5282 | _x_x_mozilla_page_load_details 11:50:00 INFO - PID 5282 | |i|pagename|runs| 11:50:00 INFO - PID 5282 | |0;dojo.html;3129.030000699782;3129.921751956202;3133.2323580722727;3166.7149956503918;3173.7937034092797;6042.637441753498;6181.56729028967;6184.721687524061;6213.254409470864;6306.085617585447;6156.384361563842;6172.969135154318;6207;6208.6585237811905;6211;5656.519195868356;5667.4615911488545;5702.059160238566;5740.512056475184;5770.076787713967;5738.340090889538;5768.971155144219;5816.1275808628625;5857.76568937242;5885.911711324334;5784.768609255625;5808.622439541437;5848.912266316009;5854.853628659275;5905.232319798421;5500.4224556421395;5619.325680918316;5738.598298119134;5823.039198532216;5845.357010728816;5624.0439125348785;5637.746301416405;5647.29408823897;5726.627769194989;5745.2243947067445;6148.6618235997;6165.660888651168;6200.379962003809;6246.781362652286;6263.530235232376;6422.903656445157;6542.116814230066;6550.737970481175;6610.636414997173;6680.7327706891665;6548.214214294268;6582.275949645536;6609.933900661028;6612.669366531654;6650.966745166292;6116;6135.938640613582;6159;6161.722722477454;6167;6902.275261097613;6903.827404314932;6912.205096413977;6926.030355750199;6934.098567186234;6482.416582507502;6510.21877374718;6609.834754131185;6613.503987200979;6621.602703837785;6633.071370008106;6654.201495820533;6661.700223489955;6688.33116688337;6730.831729206809;6429.903551446732;6547.312532184147;6559.212894452697;6566.080748695186;6713.69788359525;6572.967135164389;6617.404433600999;6619.735210591522;6643.40209381158;6654.035164901159;5190.532852043334;5214.7914083437;5251.789928402897;5296.073187192226;5301;4616.399868017136;4638.281066434708;4651.883702907387;4653.418322709661;4659.324397962344;5650.886982260332;5701.629394089371;5737.3115226172295;5842.59101862874;5848.444397782204;6645.368689974444;6668.533202675766;6740.696668649923;6762.087118238975;6780.898286525706;5763.394843541451;5774.653520788766;5819.505342045889;5837.591368604242;5847.8012007538355;5569.777208911598;5675.205471233948;5686.658800471985;5744.827655170351;5771.8557036074435;6493.123428337114;6505.869882602321;6514.9674251629385;6521.445677117498;6555.180602424697;6167.167432396569;6668.099806526056;6700.329967003261;6703.66481675924;6719.193696756421;6506.544541882023;6532.869342613216;6534.771283005072;6554.279029306867;6581.111549940697;6388.840278993062;6529.412352888263;6585.604863708193;6629.204495460575;6660.666966651745;6679.365460281266;6718.6976586054725;6769.221539523018;6779.050932869303;6784.084148640068;6493.577917435352;6560.639164845979;6578.76974305897;6579.539432239697;6583.341665833378;6413.807585772434;6460.289368169404;6462.224533056064;6545.770898018546;6557.967210163919;6209;6344.270408903035;6416.871662566629;6433.517486188648;6439.098526206429;6449.064885591469;6460.030995350735;6472.190976127984;6496.155499784998;6505.73977040932;6191.9690401547705;6222.346653601307;6234.251889773256;6243.344448832807;6266.874662506633 11:50:00 INFO - PID 5282 | |1;ext.html;830.5432012393184;845.8646616541353;850.0012485329739;850.5704619167324;851.23388949945;39027.41458878119;40036.59963400365;42484;42625.78687106564;43245.27018919239;38756.612433875656;39326.60673393272;39417.21165576687;39553.40669889954;39668;6970.790876273707;7073.186583542907;7091.645417729119;7097.751578694747;7106.360427561518;7617.428692848031;7769.844603107935;7781;7790.766277011699;7827.060752709683;7787;7844.058712954454;7855.253750893656;7861.21387861215;7898.486598371116;7668.616569171575;7776.9222307777045;7804.570748608824;7870.291673749332;7891.21087891212;7706.38348932084;7744.303012728881;7751.496152750054;7760.146383897767;7819.3744500439825;38525.614743852486;39102;39161;39175.8041209795;39477.40783888244;3551.7158627309755;3563.1448452371505;3579.266250418658;3582.7133829293593;3598.7840729556046;31696.524552131737;31787;31929.361412771614;31960.360792784013;31994.2403167828;5226.477352264781;5274.4198138204765;5309;5335.519803217729;5403.756830942617;188744;189356;189669;190130;190636;43077.35383969243;49133.50866491361;49394.75302623464;49433.50566494361;49604.25593616099;45545.772271138434;45756.77121614371;50159.24761128586;51313.48686513087;51401;15701.214939252992;16062.196890155445;16069.196540172945;16082.195890205678;16083.437079702155;12898.419571119322;13090.410931508104;13100.21398716061;13110.213387196798;13118.147320424141;13759.449622015007;13761.31193440324;13862.584122476343;13896.235707036009;13939.790903136462;5362.597805164628;5513.5037846593195;5525.889482210414;5629.6903670298525;5633.6619802811965;999.4053538144792;1011.5549158370294;1034.250168627754;1038.8909164537615;1041.8385150301715;22926;22952.311430657108;23219.88390058039;23338.416539586306;23678.526429471312;1088.7387027113593;1122.8484154639182;1133.9376334301692;1134.7276653603076;1140.8916152965455;12118.575849845212;12338.568150114883;12348;12412.81380779289;12422.937885310515;215152;215517;217413;217545;218977;52856.73571632117;52931;53140.20289695658;53342.46657533375;53582.464175357745;1412.9081609695543;1466.9779953300708;1467.713795809807;1489.3298015892892;1498.6628008698174;22422.77577224207;22890.31329060131;22908.656370154793;23066.53866922686;23072.192473263356;36997.44503832391;37466.62533374631;37586.62413375831;37836.81081594574;37923.81038094792;194458;195329;195819;196850;197388;33228;33716.66283337135;35023.29953400867;35349;35538.46692299669;43171.35242971421;43561.56438435575;43926;44541.77729111463;44625.33062004137;32014.51978220375;32396.35207295888;32482.837585811918;32507.18732031718;32560;31419.52870706987;31422.68577314289;31812.522812157375;31852;31997;34856;35244.11889702778;35461.46807797834;35557.28885422329;35744.82127589345;30857.537136943414;30919.2270193247;31297.530537041508;31376.058718238488;31571;23284.83575821236;23328.883355583115;23518.412039699146;23645.763542365043;23864.761352386948 11:50:00 INFO - PID 5282 | |2;jquery.html;552.235154311279;566.563745915645;571.8084441712025;575.278026077273;579.6319337220865;21543.138274469024;22529.436764080867;22691;22773.4306642334;22831.429214269632;22899.656505152438;24187.75812241877;24378.87810560945;24394.75605243947;24757.62863557048;6219.782307619223;6330.525210609199;6333.683315834213;6391.744330226786;6401.039844023388;6270.184875966118;6285.779997700081;6329.8101056968135;6339.809805705813;6350.841228969267;6048.425399587054;6327.4305312521865;6342.365763423644;6377.394147555975;6400.135981642466;6362.3637636236235;6377.139086223393;6396.648184349859;6403.039544068403;6418.711157997855;5910.556708246856;5936.881262374772;5945.791897283618;5956.553258505586;5958.195643588147;27365;28341;28555;28697;28727.28181795451;2000.0999550202466;2027.3208475160839;2043.4074118505616;2043.8875861827594;2046.3860841747564;18064.548386290317;18575.53561160968;18816.341428049953;18842.586805989606;18871;2243.9663405048937;2308.6421604651305;2335.3927978725656;2350.5416443793465;2357.964630530543;112089.43955280172;115298.42350788192;115546.42226788896;135510.32244838812;142556;14334.993876644898;14348.426062957366;14675.706485870436;14786.186759728318;14839.738622217017;22239.22162724297;22416.775832241794;22468.88765556162;22617.547649047257;22628.094876205098;9429.434233945985;9636.662716804878;9703.611855525842;9749.805003899883;9785.412875227508;8842.911570884338;8913.910860891308;9004.774880627905;9009.324300677476;9129.132732390412;8529.189726975927;8731.650733970697;8969.461832290084;8970.730878073668;9138.451692898447;2653.5091008163167;2768.224897028835;2768.8892444302405;2786.2337857089465;2854.372038151645;945.7966537194399;965.3869792681697;969.0551712080805;969.1132613658424;971.7522031881825;18233.27066917335;18800.15399307007;18921;19179.90410047941;19270.132844022053;1027.6095083868083;1028.4189432970347;1032.200044965143;1033.6950599573145;1038.8441733739999;10373.84439233412;10413.47932603382;10527.684169474927;10631.893681063091;10841.13270938323;457164;461139;468471;472820;473125;110262.44868775604;111031;112369;112548.43725781482;115573;1202.6331968749441;1205.8974987126192;1227.2145826670937;1234.2532767675607;1238.7646347194004;16614.335426582802;16863;16888.57778555546;16948.152592370578;17263.309467621155;54995;54999;55265.1710224355;55957.72021140031;57600;70068;70503.29496704967;70851.64574177303;71490;72856.63571682108;13888.791668125188;14034;14203.289835507976;14221.217833019284;14238.715225695636;22241.77758222397;22449.775502245422;22621.321360359216;22638.547229055;22673.2064377746;17609.20758565842;17805;17901.19444625021;17952.730709039635;18170.09149542544;15690.215489225722;16083.919580402024;16118.435854745028;16361.918190408971;16711;17687.911560442117;17701.114944252993;18105.366312179012;18212.45362639123;18288.72566911471;13863.930680346937;13938.58184254474;14004.649883752987;14017.299135043004;14088.647783805076;17279.222434990203;17394.391196308068;17642;17657.20542575613;17733.73399398984 11:50:00 INFO - PID 5282 | |3;mootools.html;1357.1382172320577;1404.1504889541827;1419.6237996930822;1427.0438805999986;1431.1413152108732;14748.926255368722;15421.537353879401;15421.922890385546;15472.071675699466;15534.611634709137;15201.391944322242;15258.313375898084;15269.465568705069;15342.232888355566;15342.386304547805;2685.2212858271077;2791.6510436195476;2798.4123334099863;2799.5240809062407;2803.4673412051643;2526.4820711754046;2545.338212064867;2552.923412297634;2555.169569889784;2568.8715564221807;2445.3275349279;2499.125306142854;2509.0591028364365;2515.383730985911;2526.166365099513;2383.8212134089927;2431.5866302728577;2441.951160976779;2448.461338505526;2452.154006867637;2483.503299340121;2487.5398051360376;2514.3839759258917;2526.4820711754046;2531.873406329676;14541.418343266256;14551.490697825526;14626.561203163923;14693.118412895261;14708.705825883422;7893.171217022187;8036.959815200887;8161.63272652732;8165.346772258205;8179.386546009014;5296.311479507678;5325.467453254682;5458.699771512565;5611.635243709186;5632.4085970972865;1904.086038701431;1907.7043058325983;1917.4918646558674;1957.1290775604862;1960.2257108442227;23092.307230783103;23959.40101497459;23975.40061498459;24002;25252.24243272705;7243.782686519413;7303.8539229216185;7351.742689005966;7511.737089201852;7556.168821429638;19552.608947820965;19623.607527849363;19635.214591416185;19641.508962276057;19645.705314420295;8260.710875119343;8487.830243395221;8496.235338819535;8531.146885311544;8598.140185981478;7435.107787065586;7521.135069466972;7633.6564854581675;7650.31147196755;7663.655135518915;5212.400573934046;5283.365996080495;5333.333333333333;5342.091844386464;5350.839474815762;1540.0836502281124;1550.5193390049121;1561.9297131629105;1562.6327812963943;1577.37693611023;900.5902314447042;916.5508900638639;917.2982668258787;931.9207867331218;933.8179055084329;7435.888461673079;7582.241775822374;7647.655855486405;7682.769516914502;7712.460127791001;689.007828726632;693.7987983484733;696.585531608692;703.1140762639111;705.386313906894;4763.499832517605;4828.66199366048;4899.730514821647;4995.900081998339;5143.614228932845;21077.578448430944;21795.34613961584;21833.563328733337;21886.23398181056;21967.121315147535;20203.595928081355;20891.58216835685;21373.251936182158;21453.46366340854;21632.891835540722;9591.808163836824;10653.414062226651;10984.791672915973;11051.17116216287;11183.32900026001;8248.298894594027;9073.546322683707;9202.585883635118;9256.166944974984;9285.582148803454;17454.21456034507;18121.184546695167;18262.08689565543;18333.724994124834;18453.81546184521;18033.73763836519;18411;19179.808201917804;19402.12690428906;19472.318468853522;10736.409497477714;10958.616448424267;10966.725831853948;10967.890321096687;10975.28660637056;12749.490020399451;12756.936215318863;12921.547745828851;12973.091883568437;13102.672433188865;11266.098712103176;11725.41372931367;11729.061675065803;11743;11808.527658893549;11231.326120432801;11492.712682183012;11495;11496.655100347003;11499.195056345976;8965.103489650983;9154.176124148858;9157.679481218393;9191.632334706805;9223.44659320443;7037.296270373126;7081.716731330689;7096.751613693703;7190.101237345332;7286.16209135935;7138.821529461805;7150.10623672041;7196.568205907663;7231.566106033655;7378.5941773202985 11:50:00 INFO - PID 5282 | |4;prototype.html;498.3048647137244;504.32924210799627;530.8991291654588;534.6952237224781;534.8607466097213;6128.601640893345;6136.23297087864;6151.507879369651;6219.657918814463;6230.221222347207;5962.552808539355;6053.818385448432;6081.604695694788;6101.572889897709;6157.784477543287;5058.013687330963;5070.366204224472;5106.08090543702;5150.201718733583;5155.20094385369;5150.381954165487;5160.716160611165;5163.1480805667015;5183.870403239912;5199.506046925536;4764.714117152965;4879.024195160964;4896.657233993622;4937.851864444073;5096.8470945871495;4854;4903.901921961558;4921.163402221613;4954.504549545053;4965.404151501807;4374.456690081044;4387.451568553931;4394.736315821061;4403.622044096216;4413.227685155085;5837.620554663933;5855.8536036599;5978.701064946736;5992.31088424828;6002.3697511761075;5569.777208911639;5577.832665020056;5618.747156377973;5634.295713035871;5636.126400407943;6342.778002769927;6362.618242905441;6364.872702545923;6368.235811702579;6428;4544.909101817945;4734.597559207472;4769.880752981168;4794.400699912511;4799.256115302133;6588.60468371904;6631.303713110102;6662.766803161866;6681.966590167067;6687;4927.5811556017425;4928.112939670822;4943.233798761125;4948.579370753526;4967.726775027409;4913.803447862046;4933.605311575066;4946.480619534969;4949.925751113737;4977.278294647256;5423.694929654881;5502.697351645618;5522.796777868826;5530.308711411074;5603.7478313475995;6479.643619600967;6487.167060539834;6495.935040649533;6514.706838192293;6522.282548919615;1714.5884987603135;1724.5257554172704;1724.7067998440295;1729.256419739524;1732.3763445159732;1128.4921785196693;1188.395665851094;1194.1819853400448;1214.319980810731;1224.8836360545733;5633.211350410946;5636.351819540723;5707.001274776898;5708.887711546754;5750.30996280449;6487.3188315226225;6618.8345291368105;6626.012196341175;6649.73401063952;6661.433778128913;5511.448855114457;5619.325680918316;5622.184783206494;5637;5646.830595082154;3419.8803041893416;3425.591536421277;3458.7578869478893;3491.742972529887;3503.667151620592;6252.656103914237;6254.74981000764;6257.687115644199;6314.273858506331;6371.776987805497;6579.618280161124;6609.306022867626;6718.563293385914;6720.395164435126;6729.192496900403;6314.62112273265;6377.585456945284;6448.290688024314;6455.4835613150835;6464.095026696266;6069.3309339931875;6477.676116194078;6507.967460162668;6535.771248006297;6558.458762190786;6280.905786413299;6333.746650133943;6353.378574212655;6386.393292637192;6493.902591461227;6183.103449999635;6282.403171698681;6318.778842740482;6333.778317758856;6443.67781610927;4865.9756701216265;4886.167687117331;4902.681325713817;4911.950880491149;4920.311156438173;6248.812535623939;6440.4525615321345;6502.837429064311;6515;6565.343465653396;6071.2714474263375;6361.459275961595;6407.327230640902;6427.357264273535;6431.324710905287;6320.905186422299;6343.270523889812;6380.489560835029;6425.485961123192;6484.416402523795;6203.565750397609;6231.594946328655;6244.500439964882;6249.812505624838;6342.143810585512;6433.967830160819;6458.579112595222;6462.579932304385;6477.1579694639395;6526.347365263436;6246.875062498633;6250.812475625738;6259.687015649109;6307.24313082433;6312.747490100344 11:50:00 INFO - PID 5282 | |5;yui.html;894.7360528644053;901.3420203251626;909.3679892474729;915.6932427636741;920.2224120618077;19510.804891951077;20153.395398138044;20195.39413817584;20317.49206269844;20382.082806273716;18306.542336441595;18464.16911238994;18551.257949681996;18552.721709174373;18588.53528661784;940.614348117272;955.4458414119829;962.7111866440075;962.9085236902483;963.1957315641448;972.9367591106591;976.2580438866477;979.3732011512637;981.6613268422383;985.4875464758321;988.77258230607;1022.4887556221889;1027.6557353286657;1035.0012238190145;1035.7876635289786;1005.4017859373661;1005.5726316315537;1006.9848952265722;1008.1178968402647;1012.296453964495;750.7297372945735;752.2665401233808;752.5898385379982;755.4107795919192;756.3344257053758;19534.51163720904;20511;20670;20849.478763030893;20865.269715560033;19963.600727985504;19995.300164494318;20020;20228.595428091357;20245.595088098155;17059;17532.824671753373;17659.55851103733;17761.02314372709;17783.555411114696;3743;3776.206996530732;3800.258949504848;3817.3128836809365;3820.3887378019663;101406;134721;142466.28766856203;143734;143949;16035;16690;16696.165191740365;16815.6636867262;16833.494995150166;29596.852015739783;30715.539266911015;30758.846205768827;30817.537736933962;31085.378292434478;8792.692255771146;9030.683926062557;9091.727248182566;9112.681056163003;9134.451932884049;4150.937735933963;4197.202531519001;4225.492940847056;4231.576842315805;4231.597998190167;5023.321851549994;5094.694318340911;5095.87260318495;5157.510036546522;5174.870628234249;1077.5043479999288;1096.8299913513417;1102.5204036244259;1111.349860331715;1111.8332250162541;676.3507033248038;689.4208864553799;696.3663066609361;712.7006657203987;715.506300652548;12003.63989080329;12072.396380180955;12098.87901120995;12289.385530723428;12348.320842353578;679.4157024958532;692.3768608252612;695.446269562535;695.5096656856904;706.8939659051183;5787.247657804459;5856.882862342729;5919.67441790697;5943.049112142036;6006.309274433496;115954.42022789831;121472;121550;122047;125615.3719231398;100017;100397.49801250947;100564;100661.49669251754;101530;84627;86951.13048869556;87613.56193219121;88486.55756721304;89951.55024224837;10698.839517407401;10740.355578665305;10801.297915635458;10867.836982445111;10957.397343146204;42262.57737422586;42694;43354.78322608493;43746.781266093465;43892.12215755731;57887;57928;58279.41720582909;58510.12234816566;58687.413125868195;15650.843491564938;15731.449399271429;15765.290561924512;15852.68294634124;15877.2061396932;17539.82460175382;17683.204255808778;17793.377231796825;17807.73288400649;18039.278428863225;15521;15863.286152122952;15869.920650396674;15874.206289685239;16115.597110072342;16268.267927943507;16286.348546058025;16291.592710181867;16321.59196020109;16480.34078636841;15368.385264589291;15436.459723910059;15443.305051272944;15537.45618903333;15657.68684626324;11854.703632409259;11934.164608477324;12211.450484728031;12270.631881043582;12553.121281510572;12241.632751017483;12287.69280767988;12413.813792793297;12428.937855311027;12711.682207944876 11:50:00 INFO - PID 5282 | __end_tp_report 11:50:00 INFO - PID 5282 | __start_cc_report 11:50:00 INFO - PID 5282 | _x_x_mozilla_cycle_collect,291 11:50:00 INFO - PID 5282 | __end_cc_report 11:50:00 INFO - PID 5282 | __startTimestamp1505674200281__endTimestamp 11:50:00 INFO - PID 5282 | 11:50:00 INFO - PID 5282 | ------- Summary: start ------- 11:50:00 INFO - PID 5282 | Number of tests: 6 11:50:00 INFO - PID 5282 | 11:50:00 INFO - PID 5282 | [#0] dojo.html Cycles:165 Average:6123.40 Median:6344.27 stddev:722.26 (11.4%) stddev-sans-first:685.22 11:50:00 INFO - PID 5282 | Values: 3129.0 3129.9 3133.2 3166.7 3173.8 6042.6 6181.6 6184.7 6213.3 6306.1 6156.4 6173.0 6207.0 6208.7 6211.0 5656.5 5667.5 5702.1 5740.5 5770.1 5738.3 5769.0 5816.1 5857.8 5885.9 5784.8 5808.6 5848.9 5854.9 5905.2 5500.4 5619.3 5738.6 5823.0 5845.4 5624.0 5637.7 5647.3 5726.6 5745.2 6148.7 6165.7 6200.4 6246.8 6263.5 6422.9 6542.1 6550.7 6610.6 6680.7 6548.2 6582.3 6609.9 6612.7 6651.0 6116.0 6135.9 6159.0 6161.7 6167.0 6902.3 6903.8 6912.2 6926.0 6934.1 6482.4 6510.2 6609.8 6613.5 6621.6 6633.1 6654.2 6661.7 6688.3 6730.8 6429.9 6547.3 6559.2 6566.1 6713.7 6573.0 6617.4 6619.7 6643.4 6654.0 5190.5 5214.8 5251.8 5296.1 5301.0 4616.4 4638.3 4651.9 4653.4 4659.3 5650.9 5701.6 5737.3 5842.6 5848.4 6645.4 6668.5 6740.7 6762.1 6780.9 5763.4 5774.7 5819.5 5837.6 5847.8 5569.8 5675.2 5686.7 5744.8 5771.9 6493.1 6505.9 6515.0 6521.4 6555.2 6167.2 6668.1 6700.3 6703.7 6719.2 6506.5 6532.9 6534.8 6554.3 6581.1 6388.8 6529.4 6585.6 6629.2 6660.7 6679.4 6718.7 6769.2 6779.1 6784.1 6493.6 6560.6 6578.8 6579.5 6583.3 6413.8 6460.3 6462.2 6545.8 6558.0 6209.0 6344.3 6416.9 6433.5 6439.1 6449.1 6460.0 6472.2 6496.2 6505.7 6192.0 6222.3 6234.3 6243.3 6266.9 11:50:00 INFO - PID 5282 | 11:50:00 INFO - PID 5282 | [#1] ext.html Cycles:180 Average:37035.05 Median:19506.59 stddev:52054.57 (266.9%) stddev-sans-first:52129.61 11:50:00 INFO - PID 5282 | Values: 830.5 845.9 850.0 850.6 851.2 39027.4 40036.6 42484.0 42625.8 43245.3 38756.6 39326.6 39417.2 39553.4 39668.0 6970.8 7073.2 7091.6 7097.8 7106.4 7617.4 7769.8 7781.0 7790.8 7827.1 7787.0 7844.1 7855.3 7861.2 7898.5 7668.6 7776.9 7804.6 7870.3 7891.2 7706.4 7744.3 7751.5 7760.1 7819.4 38525.6 39102.0 39161.0 39175.8 39477.4 3551.7 3563.1 3579.3 3582.7 3598.8 31696.5 31787.0 31929.4 31960.4 31994.2 5226.5 5274.4 5309.0 5335.5 5403.8 188744.0 189356.0 189669.0 190130.0 190636.0 43077.4 49133.5 49394.8 49433.5 49604.3 45545.8 45756.8 50159.2 51313.5 51401.0 15701.2 16062.2 16069.2 16082.2 16083.4 12898.4 13090.4 13100.2 13110.2 13118.1 13759.4 13761.3 13862.6 13896.2 13939.8 5362.6 5513.5 5525.9 5629.7 5633.7 999.4 1011.6 1034.3 1038.9 1041.8 22926.0 22952.3 23219.9 23338.4 23678.5 1088.7 1122.8 1133.9 1134.7 1140.9 12118.6 12338.6 12348.0 12412.8 12422.9 215152.0 215517.0 217413.0 217545.0 218977.0 52856.7 52931.0 53140.2 53342.5 53582.5 1412.9 1467.0 1467.7 1489.3 1498.7 22422.8 22890.3 22908.7 23066.5 23072.2 36997.4 37466.6 37586.6 37836.8 37923.8 194458.0 195329.0 195819.0 196850.0 197388.0 33228.0 33716.7 35023.3 35349.0 35538.5 43171.4 43561.6 43926.0 44541.8 44625.3 32014.5 32396.4 32482.8 32507.2 32560.0 31419.5 31422.7 31812.5 31852.0 31997.0 34856.0 35244.1 35461.5 35557.3 35744.8 30857.5 30919.2 31297.5 31376.1 31571.0 23284.8 23328.9 23518.4 23645.8 23864.8 11:50:00 INFO - PID 5282 | 11:50:00 INFO - PID 5282 | [#2] jquery.html Cycles:180 Average:33248.73 Median:22633.32 stddev:78495.58 (346.8%) stddev-sans-first:78677.39 11:50:00 INFO - PID 5282 | Values: 552.2 566.6 571.8 575.3 579.6 21543.1 22529.4 22691.0 22773.4 22831.4 22899.7 24187.8 24378.9 24394.8 24757.6 6219.8 6330.5 6333.7 6391.7 6401.0 6270.2 6285.8 6329.8 6339.8 6350.8 6048.4 6327.4 6342.4 6377.4 6400.1 6362.4 6377.1 6396.6 6403.0 6418.7 5910.6 5936.9 5945.8 5956.6 5958.2 27365.0 28341.0 28555.0 28697.0 28727.3 2000.1 2027.3 2043.4 2043.9 2046.4 18064.5 18575.5 18816.3 18842.6 18871.0 2244.0 2308.6 2335.4 2350.5 2358.0 112089.4 115298.4 115546.4 135510.3 142556.0 14335.0 14348.4 14675.7 14786.2 14839.7 22239.2 22416.8 22468.9 22617.5 22628.1 9429.4 9636.7 9703.6 9749.8 9785.4 8842.9 8913.9 9004.8 9009.3 9129.1 8529.2 8731.7 8969.5 8970.7 9138.5 2653.5 2768.2 2768.9 2786.2 2854.4 945.8 965.4 969.1 969.1 971.8 18233.3 18800.2 18921.0 19179.9 19270.1 1027.6 1028.4 1032.2 1033.7 1038.8 10373.8 10413.5 10527.7 10631.9 10841.1 457164.0 461139.0 468471.0 472820.0 473125.0 110262.4 111031.0 112369.0 112548.4 115573.0 1202.6 1205.9 1227.2 1234.3 1238.8 16614.3 16863.0 16888.6 16948.2 17263.3 54995.0 54999.0 55265.2 55957.7 57600.0 70068.0 70503.3 70851.6 71490.0 72856.6 13888.8 14034.0 14203.3 14221.2 14238.7 22241.8 22449.8 22621.3 22638.5 22673.2 17609.2 17805.0 17901.2 17952.7 18170.1 15690.2 16083.9 16118.4 16361.9 16711.0 17687.9 17701.1 18105.4 18212.5 18288.7 13863.9 13938.6 14004.6 14017.3 14088.6 17279.2 17394.4 17642.0 17657.2 17733.7 11:50:00 INFO - PID 5282 | 11:50:00 INFO - PID 5282 | [#3] mootools.html Cycles:180 Average:9249.83 Median:2493.33 stddev:6551.96 (262.8%) stddev-sans-first:6543.50 11:50:00 INFO - PID 5282 | Values: 1357.1 1404.2 1419.6 1427.0 1431.1 14748.9 15421.5 15421.9 15472.1 15534.6 15201.4 15258.3 15269.5 15342.2 15342.4 2685.2 2791.7 2798.4 2799.5 2803.5 2526.5 2545.3 2552.9 2555.2 2568.9 2445.3 2499.1 2509.1 2515.4 2526.2 2383.8 2431.6 2442.0 2448.5 2452.2 2483.5 2487.5 2514.4 2526.5 2531.9 14541.4 14551.5 14626.6 14693.1 14708.7 7893.2 8037.0 8161.6 8165.3 8179.4 5296.3 5325.5 5458.7 5611.6 5632.4 1904.1 1907.7 1917.5 1957.1 1960.2 23092.3 23959.4 23975.4 24002.0 25252.2 7243.8 7303.9 7351.7 7511.7 7556.2 19552.6 19623.6 19635.2 19641.5 19645.7 8260.7 8487.8 8496.2 8531.1 8598.1 7435.1 7521.1 7633.7 7650.3 7663.7 5212.4 5283.4 5333.3 5342.1 5350.8 1540.1 1550.5 1561.9 1562.6 1577.4 900.6 916.6 917.3 931.9 933.8 7435.9 7582.2 7647.7 7682.8 7712.5 689.0 693.8 696.6 703.1 705.4 4763.5 4828.7 4899.7 4995.9 5143.6 21077.6 21795.3 21833.6 21886.2 21967.1 20203.6 20891.6 21373.3 21453.5 21632.9 9591.8 10653.4 10984.8 11051.2 11183.3 8248.3 9073.5 9202.6 9256.2 9285.6 17454.2 18121.2 18262.1 18333.7 18453.8 18033.7 18411.0 19179.8 19402.1 19472.3 10736.4 10958.6 10966.7 10967.9 10975.3 12749.5 12756.9 12921.5 12973.1 13102.7 11266.1 11725.4 11729.1 11743.0 11808.5 11231.3 11492.7 11495.0 11496.7 11499.2 8965.1 9154.2 9157.7 9191.6 9223.4 7037.3 7081.7 7096.8 7190.1 7286.2 7138.8 7150.1 7196.6 7231.6 7378.6 11:50:00 INFO - PID 5282 | 11:50:00 INFO - PID 5282 | [#4] prototype.html Cycles:180 Average:5397.75 Median:6028.09 stddev:1497.36 (24.8%) stddev-sans-first:1455.70 11:50:00 INFO - PID 5282 | Values: 498.3 504.3 530.9 534.7 534.9 6128.6 6136.2 6151.5 6219.7 6230.2 5962.6 6053.8 6081.6 6101.6 6157.8 5058.0 5070.4 5106.1 5150.2 5155.2 5150.4 5160.7 5163.1 5183.9 5199.5 4764.7 4879.0 4896.7 4937.9 5096.8 4854.0 4903.9 4921.2 4954.5 4965.4 4374.5 4387.5 4394.7 4403.6 4413.2 5837.6 5855.9 5978.7 5992.3 6002.4 5569.8 5577.8 5618.7 5634.3 5636.1 6342.8 6362.6 6364.9 6368.2 6428.0 4544.9 4734.6 4769.9 4794.4 4799.3 6588.6 6631.3 6662.8 6682.0 6687.0 4927.6 4928.1 4943.2 4948.6 4967.7 4913.8 4933.6 4946.5 4949.9 4977.3 5423.7 5502.7 5522.8 5530.3 5603.7 6479.6 6487.2 6495.9 6514.7 6522.3 1714.6 1724.5 1724.7 1729.3 1732.4 1128.5 1188.4 1194.2 1214.3 1224.9 5633.2 5636.4 5707.0 5708.9 5750.3 6487.3 6618.8 6626.0 6649.7 6661.4 5511.4 5619.3 5622.2 5637.0 5646.8 3419.9 3425.6 3458.8 3491.7 3503.7 6252.7 6254.7 6257.7 6314.3 6371.8 6579.6 6609.3 6718.6 6720.4 6729.2 6314.6 6377.6 6448.3 6455.5 6464.1 6069.3 6477.7 6508.0 6535.8 6558.5 6280.9 6333.7 6353.4 6386.4 6493.9 6183.1 6282.4 6318.8 6333.8 6443.7 4866.0 4886.2 4902.7 4912.0 4920.3 6248.8 6440.5 6502.8 6515.0 6565.3 6071.3 6361.5 6407.3 6427.4 6431.3 6320.9 6343.3 6380.5 6425.5 6484.4 6203.6 6231.6 6244.5 6249.8 6342.1 6434.0 6458.6 6462.6 6477.2 6526.3 6246.9 6250.8 6259.7 6307.2 6312.7 11:50:00 INFO - PID 5282 | 11:50:00 INFO - PID 5282 | [#5] yui.html Cycles:180 Average:23696.55 Median:19749.06 stddev:33738.71 (170.8%) stddev-sans-first:33789.91 11:50:00 INFO - PID 5282 | Values: 894.7 901.3 909.4 915.7 920.2 19510.8 20153.4 20195.4 20317.5 20382.1 18306.5 18464.2 18551.3 18552.7 18588.5 940.6 955.4 962.7 962.9 963.2 972.9 976.3 979.4 981.7 985.5 988.8 1022.5 1027.7 1035.0 1035.8 1005.4 1005.6 1007.0 1008.1 1012.3 750.7 752.3 752.6 755.4 756.3 19534.5 20511.0 20670.0 20849.5 20865.3 19963.6 19995.3 20020.0 20228.6 20245.6 17059.0 17532.8 17659.6 17761.0 17783.6 3743.0 3776.2 3800.3 3817.3 3820.4 101406.0 134721.0 142466.3 143734.0 143949.0 16035.0 16690.0 16696.2 16815.7 16833.5 29596.9 30715.5 30758.8 30817.5 31085.4 8792.7 9030.7 9091.7 9112.7 9134.5 4150.9 4197.2 4225.5 4231.6 4231.6 5023.3 5094.7 5095.9 5157.5 5174.9 1077.5 1096.8 1102.5 1111.3 1111.8 676.4 689.4 696.4 712.7 715.5 12003.6 12072.4 12098.9 12289.4 12348.3 679.4 692.4 695.4 695.5 706.9 5787.2 5856.9 5919.7 5943.0 6006.3 115954.4 121472.0 121550.0 122047.0 125615.4 100017.0 100397.5 100564.0 100661.5 101530.0 84627.0 86951.1 87613.6 88486.6 89951.6 10698.8 10740.4 10801.3 10867.8 10957.4 42262.6 42694.0 43354.8 43746.8 43892.1 57887.0 57928.0 58279.4 58510.1 58687.4 15650.8 15731.4 15765.3 15852.7 15877.2 17539.8 17683.2 17793.4 17807.7 18039.3 15521.0 15863.3 15869.9 15874.2 16115.6 16268.3 16286.3 16291.6 16321.6 16480.3 15368.4 15436.5 15443.3 15537.5 15657.7 11854.7 11934.2 12211.5 12270.6 12553.1 12241.6 12287.7 12413.8 12428.9 12711.7 11:50:00 INFO - PID 5282 | -------- Summary: end -------- 11:50:00 INFO - PID 5282 | 11:50:01 INFO - TEST-INFO | 5282: exit 0 11:50:01 INFO - TEST-OK | dromaeo_css | took 1113796ms 11:50:01 INFO - TEST-START | kraken 11:50:01 INFO - Initialising browser for kraken test... 11:50:01 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:37323/getInfo.html -profile /tmp/tmptG_YwA/profile 11:50:01 INFO - TEST-INFO | started process 5662 (/builds/slave/test/build/application/firefox/firefox http://localhost:37323/getInfo.html) 11:50:07 INFO - TEST-INFO | 5662: exit 0 11:50:07 INFO - Browser initialized. 11:50:07 INFO - Running cycle 1/1 for kraken test... 11:50:07 INFO - Using env: {'DISPLAY': ':0', 11:50:07 INFO - 'HOME': '/home/cltbld', 11:50:07 INFO - 'JSGC_DISABLE_POISONING': '1', 11:50:07 INFO - 'LANG': 'en_US.UTF-8', 11:50:07 INFO - 'LANGUAGE': 'en_US:en', 11:50:07 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 11:50:07 INFO - 'LOGNAME': 'cltbld', 11:50:07 INFO - 'MAIL': '/var/mail/cltbld', 11:50:07 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:50:07 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 11:50:07 INFO - 'MOZ_ACCELERATED': '1', 11:50:07 INFO - 'MOZ_CRASHREPORTER': '1', 11:50:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:50:07 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 11:50:07 INFO - 'MOZ_NO_REMOTE': '1', 11:50:07 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:50:07 INFO - 'MOZ_WEBRENDER': '1', 11:50:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:50:07 INFO - 'NO_EM_RESTART': '1', 11:50:07 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:50:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:50:07 INFO - 'PWD': '/builds/slave/test', 11:50:07 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:50:07 INFO - 'RUST_BACKTRACE': 'full', 11:50:07 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 11:50:07 INFO - 'SHELL': '/bin/bash', 11:50:07 INFO - 'SHLVL': '1', 11:50:07 INFO - 'TERM': 'linux', 11:50:07 INFO - 'TMOUT': '86400', 11:50:07 INFO - 'USER': 'cltbld', 11:50:07 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1505671170.656474-1949342314', 11:50:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:50:07 INFO - '_': '/tools/buildbot/bin/python'} 11:50:07 INFO - TEST-INFO | started process 5907 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmptG_YwA/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 11:50:08 INFO - PID 5907 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 11:50:08 INFO - PID 5907 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 11:50:08 INFO - PID 5907 | 11:50:08 INFO - PID 5907 | (/builds/slave/test/build/application/firefox/firefox:5966): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 11:50:08 INFO - PID 5907 | 11:50:18 INFO - PID 5907 | RSS: Main: 186003456 11:50:18 INFO - PID 5907 | 11:50:21 INFO - PID 5907 | Cycle 1(1): loaded http://localhost:37323/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:37323/tests/kraken/driver.html?testName=audio-beat-detection) 11:50:22 INFO - PID 5907 | RSS: Main: 186707968 11:50:22 INFO - PID 5907 | 11:50:25 INFO - PID 5907 | Cycle 1(1): loaded http://localhost:37323/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:37323/tests/kraken/driver.html?testName=audio-dft) 11:50:25 INFO - PID 5907 | RSS: Main: 187297792 11:50:25 INFO - PID 5907 | 11:50:28 INFO - PID 5907 | Cycle 1(1): loaded http://localhost:37323/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:37323/tests/kraken/driver.html?testName=audio-fft) 11:50:29 INFO - PID 5907 | RSS: Main: 184590336 11:50:29 INFO - PID 5907 | 11:50:31 INFO - PID 5907 | Cycle 1(1): loaded http://localhost:37323/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:37323/tests/kraken/driver.html?testName=audio-oscillator) 11:50:32 INFO - PID 5907 | RSS: Main: 184262656 11:50:32 INFO - PID 5907 | 11:50:34 INFO - PID 5907 | Cycle 1(1): loaded http://localhost:37323/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:37323/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:50:35 INFO - PID 5907 | RSS: Main: 185389056 11:50:35 INFO - PID 5907 | 11:50:38 INFO - PID 5907 | Cycle 1(1): loaded http://localhost:37323/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:37323/tests/kraken/driver.html?testName=imaging-darkroom) 11:50:39 INFO - PID 5907 | RSS: Main: 186372096 11:50:39 INFO - PID 5907 | 11:50:42 INFO - PID 5907 | Cycle 1(1): loaded http://localhost:37323/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:37323/tests/kraken/driver.html?testName=imaging-desaturate) 11:50:43 INFO - PID 5907 | RSS: Main: 186892288 11:50:43 INFO - PID 5907 | 11:50:46 INFO - PID 5907 | Cycle 1(1): loaded http://localhost:37323/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:37323/tests/kraken/driver.html?testName=json-parse-financial) 11:50:47 INFO - PID 5907 | RSS: Main: 187047936 11:50:47 INFO - PID 5907 | 11:50:48 INFO - PID 5907 | Cycle 1(1): loaded http://localhost:37323/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:37323/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:50:49 INFO - PID 5907 | RSS: Main: 187236352 11:50:49 INFO - PID 5907 | 11:50:51 INFO - PID 5907 | Cycle 1(1): loaded http://localhost:37323/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:37323/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:50:51 INFO - PID 5907 | RSS: Main: 183689216 11:50:51 INFO - PID 5907 | 11:50:53 INFO - PID 5907 | Cycle 1(1): loaded http://localhost:37323/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:37323/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:50:54 INFO - PID 5907 | RSS: Main: 184279040 11:50:54 INFO - PID 5907 | 11:50:57 INFO - PID 5907 | Cycle 1(1): loaded http://localhost:37323/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:37323/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:50:57 INFO - PID 5907 | RSS: Main: 185495552 11:50:57 INFO - PID 5907 | 11:51:00 INFO - PID 5907 | Cycle 1(1): loaded http://localhost:37323/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:37323/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:51:01 INFO - PID 5907 | RSS: Main: 184528896 11:51:01 INFO - PID 5907 | 11:51:03 INFO - PID 5907 | Cycle 1(1): loaded http://localhost:37323/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:37323/tests/kraken/driver.html?testName=ai-astar) 11:51:03 INFO - PID 5907 | RSS: Main: 185692160 11:51:03 INFO - PID 5907 | 11:51:03 INFO - PID 5907 | __start_tp_report 11:51:03 INFO - PID 5907 | _x_x_mozilla_page_load 11:51:03 INFO - PID 5907 | _x_x_mozilla_page_load_details 11:51:03 INFO - PID 5907 | |i|pagename|runs| 11:51:03 INFO - PID 5907 | |0;ai-astar;119;114;114;107;121;110;108;112;107;107 11:51:03 INFO - PID 5907 | |1;audio-beat-detection;126;129;122;116;117;116;149;131;124;119 11:51:03 INFO - PID 5907 | |2;audio-dft;169;165;172;162;165;168;167;166;169;164 11:51:03 INFO - PID 5907 | |3;audio-fft;84;73;82;83;79;77;84;78;78;77 11:51:03 INFO - PID 5907 | |4;audio-oscillator;82;84;80;86;90;79;88;82;87;78 11:51:03 INFO - PID 5907 | |5;imaging-gaussian-blur;116;117;112;115;115;122;114;118;113;115 11:51:03 INFO - PID 5907 | |6;imaging-darkroom;148;156;149;148;147;149;148;149;148;150 11:51:03 INFO - PID 5907 | |7;imaging-desaturate;100;96;105;94;98;94;100;96;95;98 11:51:03 INFO - PID 5907 | |8;json-parse-financial;55;55;61;55;56;56;55;56;61;59 11:51:03 INFO - PID 5907 | |9;json-stringify-tinderbox;47;47;49;48;40;48;44;38;39;47 11:51:03 INFO - PID 5907 | |10;stanford-crypto-aes;92;77;74;75;81;85;75;73;75;75 11:51:03 INFO - PID 5907 | |11;stanford-crypto-ccm;126;117;111;111;118;114;128;119;121;115 11:51:03 INFO - PID 5907 | |12;stanford-crypto-pbkdf2;169;169;171;165;176;170;166;175;175;177 11:51:03 INFO - PID 5907 | |13;stanford-crypto-sha256-iterative;71;68;65;65;70;63;71;64;65;67 11:51:03 INFO - PID 5907 | __end_tp_report 11:51:03 INFO - PID 5907 | __start_cc_report 11:51:03 INFO - PID 5907 | _x_x_mozilla_cycle_collect,757 11:51:03 INFO - PID 5907 | __end_cc_report 11:51:03 INFO - PID 5907 | __startTimestamp1505674263437__endTimestamp 11:51:03 INFO - PID 5907 | 11:51:03 INFO - PID 5907 | ------- Summary: start ------- 11:51:03 INFO - PID 5907 | Number of tests: 14 11:51:03 INFO - PID 5907 | 11:51:03 INFO - PID 5907 | [#0] ai-astar Cycles:10 Average:111.90 Median:113.00 stddev:5.09 (4.5%) stddev-sans-first:4.70 11:51:03 INFO - PID 5907 | Values: 119.0 114.0 114.0 107.0 121.0 110.0 108.0 112.0 107.0 107.0 11:51:03 INFO - PID 5907 | 11:51:03 INFO - PID 5907 | [#1] audio-beat-detection Cycles:10 Average:124.90 Median:125.00 stddev:10.00 (8.0%) stddev-sans-first:10.60 11:51:03 INFO - PID 5907 | Values: 126.0 129.0 122.0 116.0 117.0 116.0 149.0 131.0 124.0 119.0 11:51:03 INFO - PID 5907 | 11:51:03 INFO - PID 5907 | [#2] audio-dft Cycles:10 Average:166.70 Median:167.50 stddev:2.91 (1.7%) stddev-sans-first:2.96 11:51:03 INFO - PID 5907 | Values: 169.0 165.0 172.0 162.0 165.0 168.0 167.0 166.0 169.0 164.0 11:51:03 INFO - PID 5907 | 11:51:03 INFO - PID 5907 | [#3] audio-fft Cycles:10 Average:79.50 Median:80.50 stddev:3.63 (4.5%) stddev-sans-first:3.46 11:51:03 INFO - PID 5907 | Values: 84.0 73.0 82.0 83.0 79.0 77.0 84.0 78.0 78.0 77.0 11:51:03 INFO - PID 5907 | 11:51:03 INFO - PID 5907 | [#4] audio-oscillator Cycles:10 Average:83.60 Median:85.00 stddev:4.06 (4.8%) stddev-sans-first:4.27 11:51:03 INFO - PID 5907 | Values: 82.0 84.0 80.0 86.0 90.0 79.0 88.0 82.0 87.0 78.0 11:51:03 INFO - PID 5907 | 11:51:03 INFO - PID 5907 | [#5] imaging-gaussian-blur Cycles:10 Average:115.70 Median:115.50 stddev:2.83 (2.5%) stddev-sans-first:3.00 11:51:03 INFO - PID 5907 | Values: 116.0 117.0 112.0 115.0 115.0 122.0 114.0 118.0 113.0 115.0 11:51:03 INFO - PID 5907 | 11:51:03 INFO - PID 5907 | [#6] imaging-darkroom Cycles:10 Average:149.20 Median:149.00 stddev:2.53 (1.7%) stddev-sans-first:2.65 11:51:03 INFO - PID 5907 | Values: 148.0 156.0 149.0 148.0 147.0 149.0 148.0 149.0 148.0 150.0 11:51:03 INFO - PID 5907 | 11:51:03 INFO - PID 5907 | [#7] imaging-desaturate Cycles:10 Average:97.60 Median:95.50 stddev:3.41 (3.6%) stddev-sans-first:3.50 11:51:03 INFO - PID 5907 | Values: 100.0 96.0 105.0 94.0 98.0 94.0 100.0 96.0 95.0 98.0 11:51:03 INFO - PID 5907 | 11:51:03 INFO - PID 5907 | [#8] json-parse-financial Cycles:10 Average:56.90 Median:56.00 stddev:2.47 (4.4%) stddev-sans-first:2.52 11:51:03 INFO - PID 5907 | Values: 55.0 55.0 61.0 55.0 56.0 56.0 55.0 56.0 61.0 59.0 11:51:03 INFO - PID 5907 | 11:51:03 INFO - PID 5907 | [#9] json-stringify-tinderbox Cycles:10 Average:44.70 Median:47.00 stddev:4.16 (8.9%) stddev-sans-first:4.33 11:51:03 INFO - PID 5907 | Values: 47.0 47.0 49.0 48.0 40.0 48.0 44.0 38.0 39.0 47.0 11:51:03 INFO - PID 5907 | 11:51:03 INFO - PID 5907 | [#10] stanford-crypto-aes Cycles:10 Average:78.20 Median:76.00 stddev:6.07 (8.0%) stddev-sans-first:3.87 11:51:03 INFO - PID 5907 | Values: 92.0 77.0 74.0 75.0 81.0 85.0 75.0 73.0 75.0 75.0 11:51:03 INFO - PID 5907 | 11:51:03 INFO - PID 5907 | [#11] stanford-crypto-ccm Cycles:10 Average:118.00 Median:118.50 stddev:5.75 (4.9%) stddev-sans-first:5.33 11:51:03 INFO - PID 5907 | Values: 126.0 117.0 111.0 111.0 118.0 114.0 128.0 119.0 121.0 115.0 11:51:03 INFO - PID 5907 | 11:51:03 INFO - PID 5907 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:171.30 Median:173.00 stddev:4.24 (2.5%) stddev-sans-first:4.42 11:51:03 INFO - PID 5907 | Values: 169.0 169.0 171.0 165.0 176.0 170.0 166.0 175.0 175.0 177.0 11:51:03 INFO - PID 5907 | 11:51:03 INFO - PID 5907 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.90 Median:67.50 stddev:2.96 (4.4%) stddev-sans-first:2.74 11:51:03 INFO - PID 5907 | Values: 71.0 68.0 65.0 65.0 70.0 63.0 71.0 64.0 65.0 67.0 11:51:03 INFO - PID 5907 | -------- Summary: end -------- 11:51:03 INFO - PID 5907 | 11:51:04 INFO - PID 5907 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 11:51:04 INFO - TEST-INFO | 5907: exit 0 11:51:04 INFO - TEST-OK | kraken | took 63308ms 11:51:04 INFO - SUITE-END | took 1177s 11:51:04 INFO - Completed test suite (00:19:37) 11:51:04 INFO - javascript benchmark 11:51:04 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8395.451234130245, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3129.030000699782, 3129.921751956202, 3133.2323580722727, 3166.7149956503918, 3173.7937034092797, 6042.637441753498, 6181.56729028967, 6184.721687524061, 6213.254409470864, 6306.085617585447, 6156.384361563842, 6172.969135154318, 6207.0, 6208.6585237811905, 6211.0, 5656.519195868356, 5667.4615911488545, 5702.059160238566, 5740.512056475184, 5770.076787713967, 5738.340090889538, 5768.971155144219, 5816.1275808628625, 5857.76568937242, 5885.911711324334, 5784.768609255625, 5808.622439541437, 5848.912266316009, 5854.853628659275, 5905.232319798421, 5500.4224556421395, 5619.325680918316, 5738.598298119134, 5823.039198532216, 5845.357010728816, 5624.0439125348785, 5637.746301416405, 5647.29408823897, 5726.627769194989, 5745.2243947067445, 6148.6618235997, 6165.660888651168, 6200.379962003809, 6246.781362652286, 6263.530235232376, 6422.903656445157, 6542.116814230066, 6550.737970481175, 6610.636414997173, 6680.7327706891665, 6548.214214294268, 6582.275949645536, 6609.933900661028, 6612.669366531654, 6650.966745166292, 6116.0, 6135.938640613582, 6159.0, 6161.722722477454, 6167.0, 6902.275261097613, 6903.827404314932, 6912.205096413977, 6926.030355750199, 6934.098567186234, 6482.416582507502, 6510.21877374718, 6609.834754131185, 6613.503987200979, 6621.602703837785, 6633.071370008106, 6654.201495820533, 6661.700223489955, 6688.33116688337, 6730.831729206809, 6429.903551446732, 6547.312532184147, 6559.212894452697, 6566.080748695186, 6713.69788359525, 6572.967135164389, 6617.404433600999, 6619.735210591522, 6643.40209381158, 6654.035164901159, 5190.532852043334, 5214.7914083437, 5251.789928402897, 5296.073187192226, 5301.0, 4616.399868017136, 4638.281066434708, 4651.883702907387, 4653.418322709661, 4659.324397962344, 5650.886982260332, 5701.629394089371, 5737.3115226172295, 5842.59101862874, 5848.444397782204, 6645.368689974444, 6668.533202675766, 6740.696668649923, 6762.087118238975, 6780.898286525706, 5763.394843541451, 5774.653520788766, 5819.505342045889, 5837.591368604242, 5847.8012007538355, 5569.777208911598, 5675.205471233948, 5686.658800471985, 5744.827655170351, 5771.8557036074435, 6493.123428337114, 6505.869882602321, 6514.9674251629385, 6521.445677117498, 6555.180602424697, 6167.167432396569, 6668.099806526056, 6700.329967003261, 6703.66481675924, 6719.193696756421, 6506.544541882023, 6532.869342613216, 6534.771283005072, 6554.279029306867, 6581.111549940697, 6388.840278993062, 6529.412352888263, 6585.604863708193, 6629.204495460575, 6660.666966651745, 6679.365460281266, 6718.6976586054725, 6769.221539523018, 6779.050932869303, 6784.084148640068, 6493.577917435352, 6560.639164845979, 6578.76974305897, 6579.539432239697, 6583.341665833378, 6413.807585772434, 6460.289368169404, 6462.224533056064, 6545.770898018546, 6557.967210163919, 6209.0, 6344.270408903035, 6416.871662566629, 6433.517486188648, 6439.098526206429, 6449.064885591469, 6460.030995350735, 6472.190976127984, 6496.155499784998, 6505.73977040932, 6191.9690401547705, 6222.346653601307, 6234.251889773256, 6243.344448832807, 6266.874662506633], "value": 6069.399587980897, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [830.5432012393184, 845.8646616541353, 850.0012485329739, 850.5704619167324, 851.23388949945, 39027.41458878119, 40036.59963400365, 42484.0, 42625.78687106564, 43245.27018919239, 38756.612433875656, 39326.60673393272, 39417.21165576687, 39553.40669889954, 39668.0, 6970.790876273707, 7073.186583542907, 7091.645417729119, 7097.751578694747, 7106.360427561518, 7617.428692848031, 7769.844603107935, 7781.0, 7790.766277011699, 7827.060752709683, 7787.0, 7844.058712954454, 7855.253750893656, 7861.21387861215, 7898.486598371116, 7668.616569171575, 7776.9222307777045, 7804.570748608824, 7870.291673749332, 7891.21087891212, 7706.38348932084, 7744.303012728881, 7751.496152750054, 7760.146383897767, 7819.3744500439825, 38525.614743852486, 39102.0, 39161.0, 39175.8041209795, 39477.40783888244, 3551.7158627309755, 3563.1448452371505, 3579.266250418658, 3582.7133829293593, 3598.7840729556046, 31696.524552131737, 31787.0, 31929.361412771614, 31960.360792784013, 31994.2403167828, 5226.477352264781, 5274.4198138204765, 5309.0, 5335.519803217729, 5403.756830942617, 188744.0, 189356.0, 189669.0, 190130.0, 190636.0, 43077.35383969243, 49133.50866491361, 49394.75302623464, 49433.50566494361, 49604.25593616099, 45545.772271138434, 45756.77121614371, 50159.24761128586, 51313.48686513087, 51401.0, 15701.214939252992, 16062.196890155445, 16069.196540172945, 16082.195890205678, 16083.437079702155, 12898.419571119322, 13090.410931508104, 13100.21398716061, 13110.213387196798, 13118.147320424141, 13759.449622015007, 13761.31193440324, 13862.584122476343, 13896.235707036009, 13939.790903136462, 5362.597805164628, 5513.5037846593195, 5525.889482210414, 5629.6903670298525, 5633.6619802811965, 999.4053538144792, 1011.5549158370294, 1034.250168627754, 1038.8909164537615, 1041.8385150301715, 22926.0, 22952.311430657108, 23219.88390058039, 23338.416539586306, 23678.526429471312, 1088.7387027113593, 1122.8484154639182, 1133.9376334301692, 1134.7276653603076, 1140.8916152965455, 12118.575849845212, 12338.568150114883, 12348.0, 12412.81380779289, 12422.937885310515, 215152.0, 215517.0, 217413.0, 217545.0, 218977.0, 52856.73571632117, 52931.0, 53140.20289695658, 53342.46657533375, 53582.464175357745, 1412.9081609695543, 1466.9779953300708, 1467.713795809807, 1489.3298015892892, 1498.6628008698174, 22422.77577224207, 22890.31329060131, 22908.656370154793, 23066.53866922686, 23072.192473263356, 36997.44503832391, 37466.62533374631, 37586.62413375831, 37836.81081594574, 37923.81038094792, 194458.0, 195329.0, 195819.0, 196850.0, 197388.0, 33228.0, 33716.66283337135, 35023.29953400867, 35349.0, 35538.46692299669, 43171.35242971421, 43561.56438435575, 43926.0, 44541.77729111463, 44625.33062004137, 32014.51978220375, 32396.35207295888, 32482.837585811918, 32507.18732031718, 32560.0, 31419.52870706987, 31422.68577314289, 31812.522812157375, 31852.0, 31997.0, 34856.0, 35244.11889702778, 35461.46807797834, 35557.28885422329, 35744.82127589345, 30857.537136943414, 30919.2270193247, 31297.530537041508, 31376.058718238488, 31571.0, 23284.83575821236, 23328.883355583115, 23518.412039699146, 23645.763542365043, 23864.761352386948], "value": 17075.783856207785, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [552.235154311279, 566.563745915645, 571.8084441712025, 575.278026077273, 579.6319337220865, 21543.138274469024, 22529.436764080867, 22691.0, 22773.4306642334, 22831.429214269632, 22899.656505152438, 24187.75812241877, 24378.87810560945, 24394.75605243947, 24757.62863557048, 6219.782307619223, 6330.525210609199, 6333.683315834213, 6391.744330226786, 6401.039844023388, 6270.184875966118, 6285.779997700081, 6329.8101056968135, 6339.809805705813, 6350.841228969267, 6048.425399587054, 6327.4305312521865, 6342.365763423644, 6377.394147555975, 6400.135981642466, 6362.3637636236235, 6377.139086223393, 6396.648184349859, 6403.039544068403, 6418.711157997855, 5910.556708246856, 5936.881262374772, 5945.791897283618, 5956.553258505586, 5958.195643588147, 27365.0, 28341.0, 28555.0, 28697.0, 28727.28181795451, 2000.0999550202466, 2027.3208475160839, 2043.4074118505616, 2043.8875861827594, 2046.3860841747564, 18064.548386290317, 18575.53561160968, 18816.341428049953, 18842.586805989606, 18871.0, 2243.9663405048937, 2308.6421604651305, 2335.3927978725656, 2350.5416443793465, 2357.964630530543, 112089.43955280172, 115298.42350788192, 115546.42226788896, 135510.32244838812, 142556.0, 14334.993876644898, 14348.426062957366, 14675.706485870436, 14786.186759728318, 14839.738622217017, 22239.22162724297, 22416.775832241794, 22468.88765556162, 22617.547649047257, 22628.094876205098, 9429.434233945985, 9636.662716804878, 9703.611855525842, 9749.805003899883, 9785.412875227508, 8842.911570884338, 8913.910860891308, 9004.774880627905, 9009.324300677476, 9129.132732390412, 8529.189726975927, 8731.650733970697, 8969.461832290084, 8970.730878073668, 9138.451692898447, 2653.5091008163167, 2768.224897028835, 2768.8892444302405, 2786.2337857089465, 2854.372038151645, 945.7966537194399, 965.3869792681697, 969.0551712080805, 969.1132613658424, 971.7522031881825, 18233.27066917335, 18800.15399307007, 18921.0, 19179.90410047941, 19270.132844022053, 1027.6095083868083, 1028.4189432970347, 1032.200044965143, 1033.6950599573145, 1038.8441733739999, 10373.84439233412, 10413.47932603382, 10527.684169474927, 10631.893681063091, 10841.13270938323, 457164.0, 461139.0, 468471.0, 472820.0, 473125.0, 110262.44868775604, 111031.0, 112369.0, 112548.43725781482, 115573.0, 1202.6331968749441, 1205.8974987126192, 1227.2145826670937, 1234.2532767675607, 1238.7646347194004, 16614.335426582802, 16863.0, 16888.57778555546, 16948.152592370578, 17263.309467621155, 54995.0, 54999.0, 55265.1710224355, 55957.72021140031, 57600.0, 70068.0, 70503.29496704967, 70851.64574177303, 71490.0, 72856.63571682108, 13888.791668125188, 14034.0, 14203.289835507976, 14221.217833019284, 14238.715225695636, 22241.77758222397, 22449.775502245422, 22621.321360359216, 22638.547229055, 22673.2064377746, 17609.20758565842, 17805.0, 17901.19444625021, 17952.730709039635, 18170.09149542544, 15690.215489225722, 16083.919580402024, 16118.435854745028, 16361.918190408971, 16711.0, 17687.911560442117, 17701.114944252993, 18105.366312179012, 18212.45362639123, 18288.72566911471, 13863.930680346937, 13938.58184254474, 14004.649883752987, 14017.299135043004, 14088.647783805076, 17279.222434990203, 17394.391196308068, 17642.0, 17657.20542575613, 17733.73399398984], "value": 11619.282812764766, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1357.1382172320577, 1404.1504889541827, 1419.6237996930822, 1427.0438805999986, 1431.1413152108732, 14748.926255368722, 15421.537353879401, 15421.922890385546, 15472.071675699466, 15534.611634709137, 15201.391944322242, 15258.313375898084, 15269.465568705069, 15342.232888355566, 15342.386304547805, 2685.2212858271077, 2791.6510436195476, 2798.4123334099863, 2799.5240809062407, 2803.4673412051643, 2526.4820711754046, 2545.338212064867, 2552.923412297634, 2555.169569889784, 2568.8715564221807, 2445.3275349279, 2499.125306142854, 2509.0591028364365, 2515.383730985911, 2526.166365099513, 2383.8212134089927, 2431.5866302728577, 2441.951160976779, 2448.461338505526, 2452.154006867637, 2483.503299340121, 2487.5398051360376, 2514.3839759258917, 2526.4820711754046, 2531.873406329676, 14541.418343266256, 14551.490697825526, 14626.561203163923, 14693.118412895261, 14708.705825883422, 7893.171217022187, 8036.959815200887, 8161.63272652732, 8165.346772258205, 8179.386546009014, 5296.311479507678, 5325.467453254682, 5458.699771512565, 5611.635243709186, 5632.4085970972865, 1904.086038701431, 1907.7043058325983, 1917.4918646558674, 1957.1290775604862, 1960.2257108442227, 23092.307230783103, 23959.40101497459, 23975.40061498459, 24002.0, 25252.24243272705, 7243.782686519413, 7303.8539229216185, 7351.742689005966, 7511.737089201852, 7556.168821429638, 19552.608947820965, 19623.607527849363, 19635.214591416185, 19641.508962276057, 19645.705314420295, 8260.710875119343, 8487.830243395221, 8496.235338819535, 8531.146885311544, 8598.140185981478, 7435.107787065586, 7521.135069466972, 7633.6564854581675, 7650.31147196755, 7663.655135518915, 5212.400573934046, 5283.365996080495, 5333.333333333333, 5342.091844386464, 5350.839474815762, 1540.0836502281124, 1550.5193390049121, 1561.9297131629105, 1562.6327812963943, 1577.37693611023, 900.5902314447042, 916.5508900638639, 917.2982668258787, 931.9207867331218, 933.8179055084329, 7435.888461673079, 7582.241775822374, 7647.655855486405, 7682.769516914502, 7712.460127791001, 689.007828726632, 693.7987983484733, 696.585531608692, 703.1140762639111, 705.386313906894, 4763.499832517605, 4828.66199366048, 4899.730514821647, 4995.900081998339, 5143.614228932845, 21077.578448430944, 21795.34613961584, 21833.563328733337, 21886.23398181056, 21967.121315147535, 20203.595928081355, 20891.58216835685, 21373.251936182158, 21453.46366340854, 21632.891835540722, 9591.808163836824, 10653.414062226651, 10984.791672915973, 11051.17116216287, 11183.32900026001, 8248.298894594027, 9073.546322683707, 9202.585883635118, 9256.166944974984, 9285.582148803454, 17454.21456034507, 18121.184546695167, 18262.08689565543, 18333.724994124834, 18453.81546184521, 18033.73763836519, 18411.0, 19179.808201917804, 19402.12690428906, 19472.318468853522, 10736.409497477714, 10958.616448424267, 10966.725831853948, 10967.890321096687, 10975.28660637056, 12749.490020399451, 12756.936215318863, 12921.547745828851, 12973.091883568437, 13102.672433188865, 11266.098712103176, 11725.41372931367, 11729.061675065803, 11743.0, 11808.527658893549, 11231.326120432801, 11492.712682183012, 11495.0, 11496.655100347003, 11499.195056345976, 8965.103489650983, 9154.176124148858, 9157.679481218393, 9191.632334706805, 9223.44659320443, 7037.296270373126, 7081.716731330689, 7096.751613693703, 7190.101237345332, 7286.16209135935, 7138.821529461805, 7150.10623672041, 7196.568205907663, 7231.566106033655, 7378.5941773202985], "value": 6566.718825129381, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [498.3048647137244, 504.32924210799627, 530.8991291654588, 534.6952237224781, 534.8607466097213, 6128.601640893345, 6136.23297087864, 6151.507879369651, 6219.657918814463, 6230.221222347207, 5962.552808539355, 6053.818385448432, 6081.604695694788, 6101.572889897709, 6157.784477543287, 5058.013687330963, 5070.366204224472, 5106.08090543702, 5150.201718733583, 5155.20094385369, 5150.381954165487, 5160.716160611165, 5163.1480805667015, 5183.870403239912, 5199.506046925536, 4764.714117152965, 4879.024195160964, 4896.657233993622, 4937.851864444073, 5096.8470945871495, 4854.0, 4903.901921961558, 4921.163402221613, 4954.504549545053, 4965.404151501807, 4374.456690081044, 4387.451568553931, 4394.736315821061, 4403.622044096216, 4413.227685155085, 5837.620554663933, 5855.8536036599, 5978.701064946736, 5992.31088424828, 6002.3697511761075, 5569.777208911639, 5577.832665020056, 5618.747156377973, 5634.295713035871, 5636.126400407943, 6342.778002769927, 6362.618242905441, 6364.872702545923, 6368.235811702579, 6428.0, 4544.909101817945, 4734.597559207472, 4769.880752981168, 4794.400699912511, 4799.256115302133, 6588.60468371904, 6631.303713110102, 6662.766803161866, 6681.966590167067, 6687.0, 4927.5811556017425, 4928.112939670822, 4943.233798761125, 4948.579370753526, 4967.726775027409, 4913.803447862046, 4933.605311575066, 4946.480619534969, 4949.925751113737, 4977.278294647256, 5423.694929654881, 5502.697351645618, 5522.796777868826, 5530.308711411074, 5603.7478313475995, 6479.643619600967, 6487.167060539834, 6495.935040649533, 6514.706838192293, 6522.282548919615, 1714.5884987603135, 1724.5257554172704, 1724.7067998440295, 1729.256419739524, 1732.3763445159732, 1128.4921785196693, 1188.395665851094, 1194.1819853400448, 1214.319980810731, 1224.8836360545733, 5633.211350410946, 5636.351819540723, 5707.001274776898, 5708.887711546754, 5750.30996280449, 6487.3188315226225, 6618.8345291368105, 6626.012196341175, 6649.73401063952, 6661.433778128913, 5511.448855114457, 5619.325680918316, 5622.184783206494, 5637.0, 5646.830595082154, 3419.8803041893416, 3425.591536421277, 3458.7578869478893, 3491.742972529887, 3503.667151620592, 6252.656103914237, 6254.74981000764, 6257.687115644199, 6314.273858506331, 6371.776987805497, 6579.618280161124, 6609.306022867626, 6718.563293385914, 6720.395164435126, 6729.192496900403, 6314.62112273265, 6377.585456945284, 6448.290688024314, 6455.4835613150835, 6464.095026696266, 6069.3309339931875, 6477.676116194078, 6507.967460162668, 6535.771248006297, 6558.458762190786, 6280.905786413299, 6333.746650133943, 6353.378574212655, 6386.393292637192, 6493.902591461227, 6183.103449999635, 6282.403171698681, 6318.778842740482, 6333.778317758856, 6443.67781610927, 4865.9756701216265, 4886.167687117331, 4902.681325713817, 4911.950880491149, 4920.311156438173, 6248.812535623939, 6440.4525615321345, 6502.837429064311, 6515.0, 6565.343465653396, 6071.2714474263375, 6361.459275961595, 6407.327230640902, 6427.357264273535, 6431.324710905287, 6320.905186422299, 6343.270523889812, 6380.489560835029, 6425.485961123192, 6484.416402523795, 6203.565750397609, 6231.594946328655, 6244.500439964882, 6249.812505624838, 6342.143810585512, 6433.967830160819, 6458.579112595222, 6462.579932304385, 6477.1579694639395, 6526.347365263436, 6246.875062498633, 6250.812475625738, 6259.687015649109, 6307.24313082433, 6312.747490100344], "value": 4959.57921659116, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [894.7360528644053, 901.3420203251626, 909.3679892474729, 915.6932427636741, 920.2224120618077, 19510.804891951077, 20153.395398138044, 20195.39413817584, 20317.49206269844, 20382.082806273716, 18306.542336441595, 18464.16911238994, 18551.257949681996, 18552.721709174373, 18588.53528661784, 940.614348117272, 955.4458414119829, 962.7111866440075, 962.9085236902483, 963.1957315641448, 972.9367591106591, 976.2580438866477, 979.3732011512637, 981.6613268422383, 985.4875464758321, 988.77258230607, 1022.4887556221889, 1027.6557353286657, 1035.0012238190145, 1035.7876635289786, 1005.4017859373661, 1005.5726316315537, 1006.9848952265722, 1008.1178968402647, 1012.296453964495, 750.7297372945735, 752.2665401233808, 752.5898385379982, 755.4107795919192, 756.3344257053758, 19534.51163720904, 20511.0, 20670.0, 20849.478763030893, 20865.269715560033, 19963.600727985504, 19995.300164494318, 20020.0, 20228.595428091357, 20245.595088098155, 17059.0, 17532.824671753373, 17659.55851103733, 17761.02314372709, 17783.555411114696, 3743.0, 3776.206996530732, 3800.258949504848, 3817.3128836809365, 3820.3887378019663, 101406.0, 134721.0, 142466.28766856203, 143734.0, 143949.0, 16035.0, 16690.0, 16696.165191740365, 16815.6636867262, 16833.494995150166, 29596.852015739783, 30715.539266911015, 30758.846205768827, 30817.537736933962, 31085.378292434478, 8792.692255771146, 9030.683926062557, 9091.727248182566, 9112.681056163003, 9134.451932884049, 4150.937735933963, 4197.202531519001, 4225.492940847056, 4231.576842315805, 4231.597998190167, 5023.321851549994, 5094.694318340911, 5095.87260318495, 5157.510036546522, 5174.870628234249, 1077.5043479999288, 1096.8299913513417, 1102.5204036244259, 1111.349860331715, 1111.8332250162541, 676.3507033248038, 689.4208864553799, 696.3663066609361, 712.7006657203987, 715.506300652548, 12003.63989080329, 12072.396380180955, 12098.87901120995, 12289.385530723428, 12348.320842353578, 679.4157024958532, 692.3768608252612, 695.446269562535, 695.5096656856904, 706.8939659051183, 5787.247657804459, 5856.882862342729, 5919.67441790697, 5943.049112142036, 6006.309274433496, 115954.42022789831, 121472.0, 121550.0, 122047.0, 125615.3719231398, 100017.0, 100397.49801250947, 100564.0, 100661.49669251754, 101530.0, 84627.0, 86951.13048869556, 87613.56193219121, 88486.55756721304, 89951.55024224837, 10698.839517407401, 10740.355578665305, 10801.297915635458, 10867.836982445111, 10957.397343146204, 42262.57737422586, 42694.0, 43354.78322608493, 43746.781266093465, 43892.12215755731, 57887.0, 57928.0, 58279.41720582909, 58510.12234816566, 58687.413125868195, 15650.843491564938, 15731.449399271429, 15765.290561924512, 15852.68294634124, 15877.2061396932, 17539.82460175382, 17683.204255808778, 17793.377231796825, 17807.73288400649, 18039.278428863225, 15521.0, 15863.286152122952, 15869.920650396674, 15874.206289685239, 16115.597110072342, 16268.267927943507, 16286.348546058025, 16291.592710181867, 16321.59196020109, 16480.34078636841, 15368.385264589291, 15436.459723910059, 15443.305051272944, 15537.45618903333, 15657.68684626324, 11854.703632409259, 11934.164608477324, 12211.450484728031, 12270.631881043582, 12553.121281510572, 12241.632751017483, 12287.69280767988, 12413.813792793297, 12428.937855311027, 12711.682207944876], "value": 8927.687428400863, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1465.1000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [119.0, 114.0, 114.0, 107.0, 121.0, 110.0, 108.0, 112.0, 107.0, 107.0], "value": 111.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [126.0, 129.0, 122.0, 116.0, 117.0, 116.0, 149.0, 131.0, 124.0, 119.0], "value": 124.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 165.0, 172.0, 162.0, 165.0, 168.0, 167.0, 166.0, 169.0, 164.0], "value": 166.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 73.0, 82.0, 83.0, 79.0, 77.0, 84.0, 78.0, 78.0, 77.0], "value": 79.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 84.0, 80.0, 86.0, 90.0, 79.0, 88.0, 82.0, 87.0, 78.0], "value": 83.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 117.0, 112.0, 115.0, 115.0, 122.0, 114.0, 118.0, 113.0, 115.0], "value": 115.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 156.0, 149.0, 148.0, 147.0, 149.0, 148.0, 149.0, 148.0, 150.0], "value": 149.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 96.0, 105.0, 94.0, 98.0, 94.0, 100.0, 96.0, 95.0, 98.0], "value": 97.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 55.0, 61.0, 55.0, 56.0, 56.0, 55.0, 56.0, 61.0, 59.0], "value": 56.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [47.0, 47.0, 49.0, 48.0, 40.0, 48.0, 44.0, 38.0, 39.0, 47.0], "value": 44.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 77.0, 74.0, 75.0, 81.0, 85.0, 75.0, 73.0, 75.0, 75.0], "value": 78.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [126.0, 117.0, 111.0, 111.0, 118.0, 114.0, 128.0, 119.0, 121.0, 115.0], "value": 118.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 169.0, 171.0, 165.0, 176.0, 170.0, 166.0, 175.0, 175.0, 177.0], "value": 171.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 68.0, 65.0, 65.0, 70.0, 63.0, 71.0, 64.0, 65.0, 67.0], "value": 66.9, "unit": "score"}]}]} 11:51:04 INFO - Return code: 0 11:51:04 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 11:51:04 INFO - # TBPL SUCCESS # 11:51:04 INFO - Running post-action listener: _package_coverage_data 11:51:04 INFO - Running post-action listener: _resource_record_post_action 11:51:04 INFO - [mozharness: 2017-09-17 18:51:04.717928Z] Finished run-tests step (success) 11:51:04 INFO - Running post-run listener: _resource_record_post_run 11:51:04 INFO - Total resource usage - Wall time: 1195s; CPU: 13.0%; Read bytes: 6873088; Write bytes: 325324800; Read time: 1700; Write time: 391912 11:51:04 INFO - TinderboxPrint: CPU usage
13.3% 11:51:04 INFO - TinderboxPrint: I/O read bytes / time
6,873,088 / 1,700 11:51:04 INFO - TinderboxPrint: I/O write bytes / time
325,324,800 / 391,912 11:51:04 INFO - TinderboxPrint: CPU idle
8,255.8 (86.7%) 11:51:04 INFO - TinderboxPrint: CPU user
1,200.4 (12.6%) 11:51:04 INFO - TinderboxPrint: Swap in / out
0 / 0 11:51:04 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 397312; Read time: 0; Write time: 64 11:51:04 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 11:51:04 INFO - run-tests - Wall time: 1178s; CPU: 13.0%; Read bytes: 6868992; Write bytes: 324526080; Read time: 1688; Write time: 391808 11:51:05 INFO - Running post-run listener: _upload_blobber_files 11:51:05 INFO - Blob upload gear active. 11:51:05 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:51:05 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 11:51:05 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'] 11:51:05 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 11:51:05 INFO - (blobuploader) - INFO - Open directory for files ... 11:51:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 11:51:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:51:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:51:06 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 11:51:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:51:06 INFO - (blobuploader) - INFO - Done attempting. 11:51:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 11:51:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:51:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:51:07 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 11:51:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:51:07 INFO - (blobuploader) - INFO - Done attempting. 11:51:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 11:51:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:51:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:51:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 11:51:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:51:08 INFO - (blobuploader) - INFO - Done attempting. 11:51:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 11:51:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:51:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:51:15 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 11:51:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:51:15 INFO - (blobuploader) - INFO - Done attempting. 11:51:15 INFO - (blobuploader) - INFO - Iteration through files over. 11:51:15 INFO - Return code: 0 11:51:15 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:51:15 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:51:15 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/399c05437f7722a75cf33a139711b57ad8a302b249a31a184eb49354dd8c82b490d6db10e319086e3ab3583e087d47081babfbf4f4d267db3d84eec2c59681cc", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/59e3653fc93ef439ea7ba443206a85d043e72e67483bad70f9b9c58815a96575c7bb7990009b28e7eee3f3f55388616c8b0e229e42bc739b835613bebae135af", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/eb43e87b54a17c4acf9dc5ac7b7760bcbea625e31f24198e2e835378451b6e9304d82dd5f5b590a2d12eb3f5b1b4ff5aee21995a11591f48a63173b058e1cd8f", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d659784dbdf057139ad79a2ccbb7de61ea7443fb92789b4a62397b9dce5dc5d864a4529628affa2ee0408fff50ebbe69fb1c9a427d06edf390edcac1ad16f50b"} 11:51:15 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:51:15 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:51:15 INFO - Contents: 11:51:15 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/399c05437f7722a75cf33a139711b57ad8a302b249a31a184eb49354dd8c82b490d6db10e319086e3ab3583e087d47081babfbf4f4d267db3d84eec2c59681cc", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/59e3653fc93ef439ea7ba443206a85d043e72e67483bad70f9b9c58815a96575c7bb7990009b28e7eee3f3f55388616c8b0e229e42bc739b835613bebae135af", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/eb43e87b54a17c4acf9dc5ac7b7760bcbea625e31f24198e2e835378451b6e9304d82dd5f5b590a2d12eb3f5b1b4ff5aee21995a11591f48a63173b058e1cd8f", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d659784dbdf057139ad79a2ccbb7de61ea7443fb92789b4a62397b9dce5dc5d864a4529628affa2ee0408fff50ebbe69fb1c9a427d06edf390edcac1ad16f50b"} 11:51:15 INFO - Running post-run listener: copy_logs_to_upload_dir 11:51:15 INFO - Copying logs to upload dir... 11:51:15 INFO - mkdir: /builds/slave/test/build/upload/logs 11:51:15 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1247.692128 ========= master_lag: 0.07 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 47 secs) (at 2017-09-17 11:51:15.404280) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-17 11:51:15.413811) ========= 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=14ef55db36f7623abb31b9cf000001d5-1505671170.656474-1949342314 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/399c05437f7722a75cf33a139711b57ad8a302b249a31a184eb49354dd8c82b490d6db10e319086e3ab3583e087d47081babfbf4f4d267db3d84eec2c59681cc", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/59e3653fc93ef439ea7ba443206a85d043e72e67483bad70f9b9c58815a96575c7bb7990009b28e7eee3f3f55388616c8b0e229e42bc739b835613bebae135af", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/eb43e87b54a17c4acf9dc5ac7b7760bcbea625e31f24198e2e835378451b6e9304d82dd5f5b590a2d12eb3f5b1b4ff5aee21995a11591f48a63173b058e1cd8f", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d659784dbdf057139ad79a2ccbb7de61ea7443fb92789b4a62397b9dce5dc5d864a4529628affa2ee0408fff50ebbe69fb1c9a427d06edf390edcac1ad16f50b"} build_url:https://queue.taskcluster.net/v1/task/BAsmZyBYTdem8SXBoYX9ag/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011441 build_url: 'https://queue.taskcluster.net/v1/task/BAsmZyBYTdem8SXBoYX9ag/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/399c05437f7722a75cf33a139711b57ad8a302b249a31a184eb49354dd8c82b490d6db10e319086e3ab3583e087d47081babfbf4f4d267db3d84eec2c59681cc", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/59e3653fc93ef439ea7ba443206a85d043e72e67483bad70f9b9c58815a96575c7bb7990009b28e7eee3f3f55388616c8b0e229e42bc739b835613bebae135af", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/eb43e87b54a17c4acf9dc5ac7b7760bcbea625e31f24198e2e835378451b6e9304d82dd5f5b590a2d12eb3f5b1b4ff5aee21995a11591f48a63173b058e1cd8f", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d659784dbdf057139ad79a2ccbb7de61ea7443fb92789b4a62397b9dce5dc5d864a4529628affa2ee0408fff50ebbe69fb1c9a427d06edf390edcac1ad16f50b"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-17 11:51:15.448016) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-17 11:51:15.448330) ========= 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=14ef55db36f7623abb31b9cf000001d5-1505671170.656474-1949342314 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004530 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-17 11:51:15.502174) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-17 11:51:15.504506) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-17 11:51:15.504811) ========= ========= Total master_lag: 0.36 =========