builder: mozilla-central_ubuntu64_hw_stylo_test-dromaeojs-e10s slave: talos-linux64-ix-043 starttime: 1504281832.16 results: success (0) revision: cc85091ab81c7eb865d2cfb31539702c1bb7a18d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-01 09:03:52.161289) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-01 09:03:52.161743) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-01 09:03:52.162016) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-01 09:03:52.178248) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-01 09:03:52.178607) ========= 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=d2a8c569965ab8367867b893000001a1-1504281831.413524-429801474 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-01 09:03:52-- 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% 22.1M=0.001s 2017-09-01 09:03:52 (22.1 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.162436 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-01 09:03:52.355920) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-01 09:03:52.356228) ========= 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=d2a8c569965ab8367867b893000001a1-1504281831.413524-429801474 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014203 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-01 09:03:52.405488) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-09-01 09:03:52.405817) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev cc85091ab81c7eb865d2cfb31539702c1bb7a18d --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 cc85091ab81c7eb865d2cfb31539702c1bb7a18d --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=d2a8c569965ab8367867b893000001a1-1504281831.413524-429801474 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-01 09:03:52,458 truncating revision to first 12 chars 2017-09-01 09:03:52,458 Setting DEBUG logging. 2017-09-01 09:03:52,458 attempt 1/10 2017-09-01 09:03:52,458 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/cc85091ab81c?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-01 09:03:53,702 unpacking tar archive at: mozilla-central-cc85091ab81c/testing/mozharness/ program finished with exit code 0 elapsedTime=1.581166 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-09-01 09:03:54.001856) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-01 09:03:54.002184) ========= script_repo_revision: cc85091ab81c7eb865d2cfb31539702c1bb7a18d ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-01 09:03:54.002582) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-01 09:03:54.002845) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-01 09:03:54.013922) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 49 secs) (at 2017-09-01 09:03:54.014343) ========= /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=d2a8c569965ab8367867b893000001a1-1504281831.413524-429801474 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 09:03:54 INFO - MultiFileLogger online at 20170901 09:03:54 in /builds/slave/test 09:03:54 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 09:03:54 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:03:54 INFO - {'append_to_log': False, 09:03:54 INFO - 'base_work_dir': '/builds/slave/test', 09:03:54 INFO - 'blob_upload_branch': 'Firefox', 09:03:54 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:03:54 INFO - 'branch': 'Firefox', 09:03:54 INFO - 'buildbot_json_path': 'buildprops.json', 09:03:54 INFO - 'code_coverage': False, 09:03:54 INFO - 'config_files': ('talos/linux_config.py',), 09:03:54 INFO - 'default_actions': ('clobber', 09:03:54 INFO - 'read-buildbot-config', 09:03:54 INFO - 'download-and-extract', 09:03:54 INFO - 'populate-webroot', 09:03:54 INFO - 'create-virtualenv', 09:03:54 INFO - 'install', 09:03:54 INFO - 'setup-mitmproxy', 09:03:54 INFO - 'run-tests'), 09:03:54 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:03:54 INFO - 'disable_ccov_upload': False, 09:03:54 INFO - 'disable_stylo': False, 09:03:54 INFO - 'download_minidump_stackwalk': True, 09:03:54 INFO - 'download_symbols': 'ondemand', 09:03:54 INFO - 'enable_stylo': False, 09:03:54 INFO - 'enable_webrender': False, 09:03:54 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 09:03:54 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:03:54 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:03:54 INFO - 'gecko_profile': False, 09:03:54 INFO - 'gecko_profile_interval': 0, 09:03:54 INFO - 'installer_path': 'installer.exe', 09:03:54 INFO - 'log_level': 'info', 09:03:54 INFO - 'log_name': 'talos', 09:03:54 INFO - 'log_to_console': True, 09:03:54 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 09:03:54 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 09:03:54 INFO - 'opt_config_files': (), 09:03:54 INFO - 'pip_index': False, 09:03:54 INFO - 'suite': 'dromaeojs-e10s', 09:03:54 INFO - 'system_bits': '32', 09:03:54 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:03:54 INFO - 'title': 'talos-linux64-ix-043', 09:03:54 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:03:54 INFO - 'use_talos_json': True, 09:03:54 INFO - 'verify': 'False', 09:03:54 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:03:54 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:03:54 INFO - 'work_dir': 'build'} 09:03:54 INFO - [mozharness: 2017-09-01 16:03:54.213102Z] Running clobber step. 09:03:54 INFO - Running pre-action listener: _resource_record_pre_action 09:03:54 INFO - Running main action method: clobber 09:03:54 INFO - rmtree: /builds/slave/test/build 09:03:54 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:03:55 INFO - Running post-action listener: _resource_record_post_action 09:03:55 INFO - [mozharness: 2017-09-01 16:03:55.362925Z] Finished clobber step (success) 09:03:55 INFO - [mozharness: 2017-09-01 16:03:55.363148Z] Running read-buildbot-config step. 09:03:55 INFO - Running pre-action listener: _resource_record_pre_action 09:03:55 INFO - Running main action method: read_buildbot_config 09:03:55 INFO - Using buildbot properties: 09:03:55 INFO - { 09:03:55 INFO - "project": "", 09:03:55 INFO - "product": "firefox", 09:03:55 INFO - "who": "archaeopteryx@coole-files.de", 09:03:55 INFO - "installer_path": "public/build/target.tar.bz2", 09:03:55 INFO - "builddir": "mozilla-central_ubuntu64_hw_stylo_test-dromaeojs-e10s", 09:03:55 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 09:03:55 INFO - "buildername": "Ubuntu HW 12.04 x64 stylo mozilla-central talos dromaeojs-e10s", 09:03:55 INFO - "stage_platform": "linux64-stylo", 09:03:55 INFO - "basedir": "/builds/slave/test", 09:03:55 INFO - "buildnumber": 290, 09:03:55 INFO - "platform": "ubuntu64_hw_stylo", 09:03:55 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 09:03:55 INFO - "slavebuilddir": "test", 09:03:55 INFO - "taskId": "NdKX21wkS7m6pUE0qgh2XQ", 09:03:55 INFO - "branch": "mozilla-central", 09:03:55 INFO - "script_repo_revision": "production", 09:03:55 INFO - "revision": "cc85091ab81c7eb865d2cfb31539702c1bb7a18d", 09:03:55 INFO - "slavename": "talos-linux64-ix-043", 09:03:55 INFO - "repo_path": "mozilla-central" 09:03:55 INFO - } 09:03:55 INFO - Finding installer, test and symbols from parent task. 09:03:55 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NdKX21wkS7m6pUE0qgh2XQ'}, attempt #1 09:03:55 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NdKX21wkS7m6pUE0qgh2XQ'}, attempt #1 09:03:56 INFO - Task dependencies: P0kX4SRdS6ifTGg50JASWg 09:03:56 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/P0kX4SRdS6ifTGg50JASWg'}, attempt #1 09:03:56 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/P0kX4SRdS6ifTGg50JASWg/artifacts/public/build/target.tar.bz2 09:03:56 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/P0kX4SRdS6ifTGg50JASWg/artifacts/public/build/target.test_packages.json 09:03:56 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/P0kX4SRdS6ifTGg50JASWg/artifacts/public/build/target.crashreporter-symbols.zip 09:03:56 INFO - Running post-action listener: _resource_record_post_action 09:03:56 INFO - [mozharness: 2017-09-01 16:03:56.907745Z] Finished read-buildbot-config step (success) 09:03:56 INFO - [mozharness: 2017-09-01 16:03:56.907963Z] Running download-and-extract step. 09:03:56 INFO - Running pre-action listener: _resource_record_pre_action 09:03:56 INFO - Running main action method: download_and_extract 09:03:56 INFO - mkdir: /builds/slave/test/build/tests 09:03: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')]} 09:03:56 INFO - trying https://queue.taskcluster.net/v1/task/P0kX4SRdS6ifTGg50JASWg/artifacts/public/build/target.test_packages.json 09:03:56 INFO - Downloading https://queue.taskcluster.net/v1/task/P0kX4SRdS6ifTGg50JASWg/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 09:03:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/P0kX4SRdS6ifTGg50JASWg/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 09:03:57 INFO - Downloaded 989 bytes. 09:03:57 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 09:03:57 INFO - Using the following test package requirements: 09:03:57 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 09:03:57 INFO - u'common': [u'target.common.tests.zip'], 09:03:57 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 09:03:57 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 09:03:57 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 09:03:57 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 09:03:57 INFO - u'mozbase': [u'target.common.tests.zip'], 09:03:57 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 09:03:57 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 09:03:57 INFO - u'web-platform': [u'target.common.tests.zip', 09:03:57 INFO - u'target.web-platform.tests.tar.gz'], 09:03:57 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 09:03:57 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 09:03:57 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/P0kX4SRdS6ifTGg50JASWg/artifacts/public/build/target.common.tests.zip 09:03:57 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/P0kX4SRdS6ifTGg50JASWg/artifacts/public/build/target.common.tests.zip'}, attempt #1 09:03:57 INFO - Fetch https://queue.taskcluster.net/v1/task/P0kX4SRdS6ifTGg50JASWg/artifacts/public/build/target.common.tests.zip into memory 09:03:58 INFO - Content-Length response header: 54957468 09:03:58 INFO - Bytes received: 54957468 09:04:09 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 09:04:09 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/P0kX4SRdS6ifTGg50JASWg/artifacts/public/build/target.common.tests.zip 09:04:09 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/P0kX4SRdS6ifTGg50JASWg/artifacts/public/build/target.common.tests.zip'}, attempt #1 09:04:09 INFO - Fetch https://queue.taskcluster.net/v1/task/P0kX4SRdS6ifTGg50JASWg/artifacts/public/build/target.common.tests.zip into memory 09:04:10 INFO - Content-Length response header: 54957468 09:04:10 INFO - Bytes received: 54957468 09:04:22 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/P0kX4SRdS6ifTGg50JASWg/artifacts/public/build/target.talos.tests.zip 09:04:22 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/P0kX4SRdS6ifTGg50JASWg/artifacts/public/build/target.talos.tests.zip'}, attempt #1 09:04:22 INFO - Fetch https://queue.taskcluster.net/v1/task/P0kX4SRdS6ifTGg50JASWg/artifacts/public/build/target.talos.tests.zip into memory 09:04:23 INFO - Content-Length response header: 14496650 09:04:23 INFO - Bytes received: 14496650 09:04:23 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')]} 09:04:23 INFO - trying https://queue.taskcluster.net/v1/task/P0kX4SRdS6ifTGg50JASWg/artifacts/public/build/target.tar.bz2 09:04:23 INFO - Downloading https://queue.taskcluster.net/v1/task/P0kX4SRdS6ifTGg50JASWg/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 09:04:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/P0kX4SRdS6ifTGg50JASWg/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 09:04:24 INFO - Downloaded 62657924 bytes. 09:04:24 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/P0kX4SRdS6ifTGg50JASWg/artifacts/public/build/target.tar.bz2 09:04:24 INFO - mkdir: /builds/slave/test/properties 09:04:24 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:04:24 INFO - Writing to file /builds/slave/test/properties/build_url 09:04:24 INFO - Contents: 09:04:24 INFO - build_url:https://queue.taskcluster.net/v1/task/P0kX4SRdS6ifTGg50JASWg/artifacts/public/build/target.tar.bz2 09:04:24 INFO - Running post-action listener: _resource_record_post_action 09:04:24 INFO - Running post-action listener: find_tests_for_verification 09:04:24 INFO - Running post-action listener: set_extra_try_arguments 09:04:24 INFO - [mozharness: 2017-09-01 16:04:24.383424Z] Finished download-and-extract step (success) 09:04:24 INFO - [mozharness: 2017-09-01 16:04:24.383519Z] Running populate-webroot step. 09:04:24 INFO - Running pre-action listener: _resource_record_pre_action 09:04:24 INFO - Running main action method: populate_webroot 09:04:24 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:04:24 INFO - 'chromez-stylo-e10s': {'talos_options': ['--enable-stylo'], 09:04:24 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:04:24 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:04:24 INFO - 'dromaeojs-stylo-e10s': {'talos_options': ['--enable-stylo'], 09:04:24 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:04:24 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 09:04:24 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:04:24 INFO - 'g1-stylo-e10s': {'pagesets_name': 'tp5n.zip', 09:04:24 INFO - 'talos_options': ['--enable-stylo'], 09:04:24 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:04:24 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 09:04:24 INFO - 'tests': ['damp', 'tps']}, 09:04:24 INFO - 'g2-stylo-e10s': {'pagesets_name': 'tp5n.zip', 09:04:24 INFO - 'talos_options': ['--enable-stylo'], 09:04:24 INFO - 'tests': ['damp', 'tps']}, 09:04:24 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:04:24 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 09:04:24 INFO - 'g4-stylo-e10s': {'talos_options': ['--enable-stylo'], 09:04:24 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 09:04:24 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 09:04:24 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 09:04:24 INFO - 'g5-stylo-e10s': {'pagesets_name': 'tp5n.zip', 09:04:24 INFO - 'talos_options': ['--enable-stylo'], 09:04:24 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 09:04:24 INFO - 'other-e10s': {'tests': ['a11yr', 09:04:24 INFO - 'ts_paint', 09:04:24 INFO - 'tpaint', 09:04:24 INFO - 'sessionrestore', 09:04:24 INFO - 'sessionrestore_many_windows', 09:04:24 INFO - 'sessionrestore_no_auto_restore', 09:04:24 INFO - 'tabpaint']}, 09:04:24 INFO - 'other-stylo-e10s': {'talos_options': ['--enable-stylo'], 09:04:24 INFO - 'tests': ['a11yr', 09:04:24 INFO - 'ts_paint', 09:04:24 INFO - 'tpaint', 09:04:24 INFO - 'sessionrestore', 09:04:24 INFO - 'sessionrestore_many_windows', 09:04:24 INFO - 'sessionrestore_no_auto_restore', 09:04:24 INFO - 'tabpaint']}, 09:04:24 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 09:04:24 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 09:04:24 INFO - 'perf-reftest-singletons-stylo-e10s': {'talos_options': ['--enable-stylo'], 09:04:24 INFO - 'tests': ['bloom_basic_singleton']}, 09:04:24 INFO - 'perf-reftest-stylo-e10s': {'talos_options': ['--enable-stylo'], 09:04:24 INFO - 'tests': ['bloom_basic']}, 09:04:24 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:04:24 INFO - 'tsvgr_opacity', 09:04:24 INFO - 'tart', 09:04:24 INFO - 'tscrollx', 09:04:24 INFO - 'tsvg_static']}, 09:04:24 INFO - 'svgr-stylo-e10s': {'talos_options': ['--enable-stylo'], 09:04:24 INFO - 'tests': ['tsvgx', 09:04:24 INFO - 'tsvgr_opacity', 09:04:24 INFO - 'tart', 09:04:24 INFO - 'tscrollx', 09:04:24 INFO - 'tsvg_static']}, 09:04:24 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 09:04:24 INFO - 'tp5o-stylo-e10s': {'pagesets_name': 'tp5n.zip', 09:04:24 INFO - 'talos_options': ['--enable-stylo'], 09:04:24 INFO - 'tests': ['tp5o']}, 09:04:24 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 09:04:24 INFO - 'talos_options': ['--mitmproxy', 09:04:24 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 09:04:24 INFO - '--firstNonBlankPaint'], 09:04:24 INFO - 'tests': ['tp6_google', 09:04:24 INFO - 'tp6_youtube', 09:04:24 INFO - 'tp6_amazon', 09:04:24 INFO - 'tp6_facebook']}, 09:04:24 INFO - 'tp6-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 09:04:24 INFO - 'talos_options': ['--enable-stylo', 09:04:24 INFO - '--mitmproxy', 09:04:24 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 09:04:24 INFO - '--firstNonBlankPaint'], 09:04:24 INFO - 'tests': ['tp6_google', 09:04:24 INFO - 'tp6_youtube', 09:04:24 INFO - 'tp6_amazon', 09:04:24 INFO - 'tp6_facebook']}, 09:04:24 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 09:04:24 INFO - 'talos_options': ['--enable-stylo', 09:04:24 INFO - '--stylo-threads=1', 09:04:24 INFO - '--mitmproxy', 09:04:24 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 09:04:24 INFO - '--firstNonBlankPaint'], 09:04:24 INFO - 'tests': ['tp6_google', 09:04:24 INFO - 'tp6_youtube', 09:04:24 INFO - 'tp6_amazon', 09:04:24 INFO - 'tp6_facebook']}, 09:04:24 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 09:04:24 INFO - 'talos_options': ['--xperf_path', 09:04:24 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:04:24 INFO - 'tests': ['tp5n']}, 09:04:24 INFO - 'xperf-stylo-e10s': {'pagesets_name': 'tp5n.zip', 09:04:24 INFO - 'talos_options': ['--enable-stylo', 09:04:24 INFO - '--xperf_path', 09:04:24 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:04:24 INFO - 'tests': ['tp5n']}}} 09:04:24 INFO - Running post-action listener: _resource_record_post_action 09:04:24 INFO - [mozharness: 2017-09-01 16:04:24.397732Z] Finished populate-webroot step (success) 09:04:24 INFO - [mozharness: 2017-09-01 16:04:24.397820Z] Running create-virtualenv step. 09:04:24 INFO - Running pre-action listener: _resource_record_pre_action 09:04:24 INFO - Running main action method: create_virtualenv 09:04:24 INFO - Creating virtualenv /builds/slave/test/build/venv 09:04:24 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 09:04:24 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 09:04:24 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 09:04:24 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:04:24 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:04:25 INFO - Installing distribute.............................................................................................................................................................................................done. 09:04:25 INFO - Installing pip...............done. 09:04:25 INFO - Return code: 0 09:04:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:04:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:04:25 INFO - Reading from file tmpfile_stdout 09:04:25 INFO - Output received: 09:04:25 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 09:04:25 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:04:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:04:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:04:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:04:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:04:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c311f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dfaa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f9da60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fa1090>, '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 0x1fa1d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f9bf90>, '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': 'd2a8c569965ab8367867b893000001a1-1504281831.413524-429801474', '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 09:04:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 09:04:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:04:25 INFO - Using env: {'DISPLAY': ':0', 09:04:25 INFO - 'HOME': '/home/cltbld', 09:04:25 INFO - 'LANG': 'en_US.UTF-8', 09:04:25 INFO - 'LANGUAGE': 'en_US:en', 09:04:25 INFO - 'LOGNAME': 'cltbld', 09:04:25 INFO - 'MAIL': '/var/mail/cltbld', 09:04:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:04:25 INFO - 'MOZ_NO_REMOTE': '1', 09:04:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:04:25 INFO - 'NO_EM_RESTART': '1', 09:04:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:04:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:04:25 INFO - 'PWD': '/builds/slave/test', 09:04:25 INFO - 'SHELL': '/bin/bash', 09:04:25 INFO - 'SHLVL': '1', 09:04:25 INFO - 'TERM': 'linux', 09:04:25 INFO - 'TMOUT': '86400', 09:04:25 INFO - 'USER': 'cltbld', 09:04:25 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1504281831.413524-429801474', 09:04:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:04:25 INFO - '_': '/tools/buildbot/bin/python'} 09:04:26 INFO - Ignoring indexes: http://pypi.python.org/simple/ 09:04:26 INFO - Downloading/unpacking pip>=1.5 09:04:26 INFO - Running setup.py egg_info for package pip 09:04:26 INFO - warning: no files found matching 'pip/cacert.pem' 09:04:26 INFO - warning: no files found matching '*.html' under directory 'docs' 09:04:26 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 09:04:26 INFO - no previously-included directories found matching 'docs/_build/_sources' 09:04:26 INFO - Installing collected packages: pip 09:04:26 INFO - Found existing installation: pip 1.1 09:04:26 INFO - Uninstalling pip: 09:04:26 INFO - Successfully uninstalled pip 09:04:26 INFO - Running setup.py install for pip 09:04:26 INFO - warning: no files found matching 'pip/cacert.pem' 09:04:26 INFO - warning: no files found matching '*.html' under directory 'docs' 09:04:26 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 09:04:26 INFO - no previously-included directories found matching 'docs/_build/_sources' 09:04:26 INFO - Installing pip script to /builds/slave/test/build/venv/bin 09:04:26 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 09:04:26 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 09:04:26 INFO - Successfully installed pip 09:04:26 INFO - Cleaning up... 09:04:26 INFO - Return code: 0 09:04:26 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:04:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:04:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:04:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:04:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:04:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1c311f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dfaa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f9da60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fa1090>, '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 0x1fa1d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f9bf90>, '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': 'd2a8c569965ab8367867b893000001a1-1504281831.413524-429801474', '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 09:04:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 09:04:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 09:04:26 INFO - Using env: {'DISPLAY': ':0', 09:04:26 INFO - 'HOME': '/home/cltbld', 09:04:26 INFO - 'LANG': 'en_US.UTF-8', 09:04:26 INFO - 'LANGUAGE': 'en_US:en', 09:04:26 INFO - 'LOGNAME': 'cltbld', 09:04:26 INFO - 'MAIL': '/var/mail/cltbld', 09:04:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:04:26 INFO - 'MOZ_NO_REMOTE': '1', 09:04:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:04:26 INFO - 'NO_EM_RESTART': '1', 09:04:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:04:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:04:26 INFO - 'PWD': '/builds/slave/test', 09:04:26 INFO - 'SHELL': '/bin/bash', 09:04:26 INFO - 'SHLVL': '1', 09:04:26 INFO - 'TERM': 'linux', 09:04:26 INFO - 'TMOUT': '86400', 09:04:26 INFO - 'USER': 'cltbld', 09:04:26 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1504281831.413524-429801474', 09:04:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:04:26 INFO - '_': '/tools/buildbot/bin/python'} 09:04:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:04:26 INFO - Downloading/unpacking psutil>=3.1.1 09:04:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:04:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:04:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:04:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:04:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:04:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:04:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:04:28 INFO - Installing collected packages: psutil 09:04:28 INFO - Running setup.py install for psutil 09:04:28 INFO - building 'psutil._psutil_linux' extension 09:04:28 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 09:04:28 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 09:04:28 INFO - building 'psutil._psutil_posix' extension 09:04:28 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 09:04:28 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 09:04:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:04:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:04:28 INFO - Successfully installed psutil 09:04:28 INFO - Cleaning up... 09:04:28 INFO - Return code: 0 09:04:28 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:04:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:04:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:04:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:04:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:04:28 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 0x1c311f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dfaa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f9da60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fa1090>, '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 0x1fa1d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f9bf90>, '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': 'd2a8c569965ab8367867b893000001a1-1504281831.413524-429801474', '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 09:04:28 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 09:04:28 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 09:04:28 INFO - Using env: {'DISPLAY': ':0', 09:04:28 INFO - 'HOME': '/home/cltbld', 09:04:28 INFO - 'LANG': 'en_US.UTF-8', 09:04:28 INFO - 'LANGUAGE': 'en_US:en', 09:04:28 INFO - 'LOGNAME': 'cltbld', 09:04:28 INFO - 'MAIL': '/var/mail/cltbld', 09:04:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:04:28 INFO - 'MOZ_NO_REMOTE': '1', 09:04:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:04:28 INFO - 'NO_EM_RESTART': '1', 09:04:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:04:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:04:28 INFO - 'PWD': '/builds/slave/test', 09:04:28 INFO - 'SHELL': '/bin/bash', 09:04:28 INFO - 'SHLVL': '1', 09:04:28 INFO - 'TERM': 'linux', 09:04:28 INFO - 'TMOUT': '86400', 09:04:28 INFO - 'USER': 'cltbld', 09:04:28 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1504281831.413524-429801474', 09:04:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:04:28 INFO - '_': '/tools/buildbot/bin/python'} 09:04:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:04:28 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:04:28 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 09:04:28 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 09:04:28 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 09:04:28 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 09:04:29 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:04:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:04:29 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:04:29 INFO - Installing collected packages: mozsystemmonitor 09:04:29 INFO - Running setup.py install for mozsystemmonitor 09:04:30 INFO - Successfully installed mozsystemmonitor 09:04:30 INFO - Cleaning up... 09:04:30 INFO - Return code: 0 09:04:30 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:04: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')]} 09:04:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:04: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')]} 09:04:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:04:30 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 0x1c311f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dfaa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f9da60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fa1090>, '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 0x1fa1d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f9bf90>, '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': 'd2a8c569965ab8367867b893000001a1-1504281831.413524-429801474', '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 09:04:30 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 09:04:30 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 09:04:30 INFO - Using env: {'DISPLAY': ':0', 09:04:30 INFO - 'HOME': '/home/cltbld', 09:04:30 INFO - 'LANG': 'en_US.UTF-8', 09:04:30 INFO - 'LANGUAGE': 'en_US:en', 09:04:30 INFO - 'LOGNAME': 'cltbld', 09:04:30 INFO - 'MAIL': '/var/mail/cltbld', 09:04:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:04:30 INFO - 'MOZ_NO_REMOTE': '1', 09:04:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:04:30 INFO - 'NO_EM_RESTART': '1', 09:04:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:04:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:04:30 INFO - 'PWD': '/builds/slave/test', 09:04:30 INFO - 'SHELL': '/bin/bash', 09:04:30 INFO - 'SHLVL': '1', 09:04:30 INFO - 'TERM': 'linux', 09:04:30 INFO - 'TMOUT': '86400', 09:04:30 INFO - 'USER': 'cltbld', 09:04:30 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1504281831.413524-429801474', 09:04:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:04:30 INFO - '_': '/tools/buildbot/bin/python'} 09:04:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:04:30 INFO - Downloading/unpacking jsonschema==2.5.1 09:04:30 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 09:04:30 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 09:04:30 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 09:04:30 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 09:04:31 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:04:31 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:04:31 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 09:04:31 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 09:04:31 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 09:04:31 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 09:04:31 INFO - Downloading functools32-3.2.3-2.tar.gz 09:04:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:04:31 INFO - warning: no files found matching '*.txt' 09:04:31 INFO - no previously-included directories found matching 'build' 09:04:31 INFO - no previously-included directories found matching 'dist' 09:04:31 INFO - no previously-included directories found matching '.git*' 09:04:31 INFO - Installing collected packages: jsonschema, functools32 09:04:31 INFO - Running setup.py install for functools32 09:04:31 INFO - warning: no files found matching '*.txt' 09:04:31 INFO - no previously-included directories found matching 'build' 09:04:31 INFO - no previously-included directories found matching 'dist' 09:04:31 INFO - no previously-included directories found matching '.git*' 09:04:31 INFO - Successfully installed jsonschema functools32 09:04:31 INFO - Cleaning up... 09:04:31 INFO - Return code: 0 09:04:31 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:04:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:04:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:04:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:04:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:04:31 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 0x1c311f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dfaa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f9da60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fa1090>, '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 0x1fa1d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f9bf90>, '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': 'd2a8c569965ab8367867b893000001a1-1504281831.413524-429801474', '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 09:04:31 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 09:04:31 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 09:04:31 INFO - Using env: {'DISPLAY': ':0', 09:04:31 INFO - 'HOME': '/home/cltbld', 09:04:31 INFO - 'LANG': 'en_US.UTF-8', 09:04:31 INFO - 'LANGUAGE': 'en_US:en', 09:04:31 INFO - 'LOGNAME': 'cltbld', 09:04:31 INFO - 'MAIL': '/var/mail/cltbld', 09:04:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:04:31 INFO - 'MOZ_NO_REMOTE': '1', 09:04:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:04:31 INFO - 'NO_EM_RESTART': '1', 09:04:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:04:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:04:31 INFO - 'PWD': '/builds/slave/test', 09:04:31 INFO - 'SHELL': '/bin/bash', 09:04:31 INFO - 'SHLVL': '1', 09:04:31 INFO - 'TERM': 'linux', 09:04:31 INFO - 'TMOUT': '86400', 09:04:31 INFO - 'USER': 'cltbld', 09:04:31 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1504281831.413524-429801474', 09:04:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:04:31 INFO - '_': '/tools/buildbot/bin/python'} 09:04:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:04:31 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:04:31 INFO - Cleaning up... 09:04:31 INFO - Return code: 0 09:04:31 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:04:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:04:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:04:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:04:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:04:31 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 0x1c311f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dfaa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f9da60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fa1090>, '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 0x1fa1d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f9bf90>, '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': 'd2a8c569965ab8367867b893000001a1-1504281831.413524-429801474', '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 09:04:31 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 09:04:31 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 09:04:31 INFO - Using env: {'DISPLAY': ':0', 09:04:31 INFO - 'HOME': '/home/cltbld', 09:04:31 INFO - 'LANG': 'en_US.UTF-8', 09:04:31 INFO - 'LANGUAGE': 'en_US:en', 09:04:31 INFO - 'LOGNAME': 'cltbld', 09:04:31 INFO - 'MAIL': '/var/mail/cltbld', 09:04:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:04:31 INFO - 'MOZ_NO_REMOTE': '1', 09:04:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:04:31 INFO - 'NO_EM_RESTART': '1', 09:04:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:04:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:04:31 INFO - 'PWD': '/builds/slave/test', 09:04:31 INFO - 'SHELL': '/bin/bash', 09:04:31 INFO - 'SHLVL': '1', 09:04:31 INFO - 'TERM': 'linux', 09:04:31 INFO - 'TMOUT': '86400', 09:04:31 INFO - 'USER': 'cltbld', 09:04:31 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1504281831.413524-429801474', 09:04:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:04:31 INFO - '_': '/tools/buildbot/bin/python'} 09:04:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:04:32 INFO - Downloading/unpacking blobuploader==1.2.4 09:04:32 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 09:04:32 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 09:04:32 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 09:04:32 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 09:04:33 INFO - Downloading blobuploader-1.2.4.tar.gz 09:04:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:04:33 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:04:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:04:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:04:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:04:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:04:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:04:33 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:04:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:04:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:04:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:04:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:04:33 INFO - Downloading docopt-0.6.1.tar.gz 09:04:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:04:33 INFO - Installing collected packages: blobuploader, requests, docopt 09:04:33 INFO - Running setup.py install for blobuploader 09:04:33 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:04:34 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:04:34 INFO - Running setup.py install for requests 09:04:34 INFO - Running setup.py install for docopt 09:04:34 INFO - Successfully installed blobuploader requests docopt 09:04:34 INFO - Cleaning up... 09:04:34 INFO - Return code: 0 09:04:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:04:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:04:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:04:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:04:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:04:34 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 0x1c311f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dfaa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f9da60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fa1090>, '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 0x1fa1d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f9bf90>, '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': 'd2a8c569965ab8367867b893000001a1-1504281831.413524-429801474', '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 09:04:34 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 09:04:34 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 09:04:34 INFO - Using env: {'DISPLAY': ':0', 09:04:34 INFO - 'HOME': '/home/cltbld', 09:04:34 INFO - 'LANG': 'en_US.UTF-8', 09:04:34 INFO - 'LANGUAGE': 'en_US:en', 09:04:34 INFO - 'LOGNAME': 'cltbld', 09:04:34 INFO - 'MAIL': '/var/mail/cltbld', 09:04:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:04:34 INFO - 'MOZ_NO_REMOTE': '1', 09:04:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:04:34 INFO - 'NO_EM_RESTART': '1', 09:04:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:04:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:04:34 INFO - 'PWD': '/builds/slave/test', 09:04:34 INFO - 'SHELL': '/bin/bash', 09:04:34 INFO - 'SHLVL': '1', 09:04:34 INFO - 'TERM': 'linux', 09:04:34 INFO - 'TMOUT': '86400', 09:04:34 INFO - 'USER': 'cltbld', 09:04:34 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1504281831.413524-429801474', 09:04:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:04:34 INFO - '_': '/tools/buildbot/bin/python'} 09:04:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:04:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:04:34 INFO - Running setup.py (path:/tmp/pip-a8oWVJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:04:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:04:34 INFO - Running setup.py (path:/tmp/pip-OWgRWB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:04:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:04:34 INFO - Running setup.py (path:/tmp/pip-2ftex7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:04:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:04:34 INFO - Running setup.py (path:/tmp/pip-Sj0bav-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:04:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:04:34 INFO - Running setup.py (path:/tmp/pip-Sup9JR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:04:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:04:35 INFO - Running setup.py (path:/tmp/pip-sV4yPG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:04:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:04:35 INFO - Running setup.py (path:/tmp/pip-H1PqAN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:04:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:04:35 INFO - Running setup.py (path:/tmp/pip-uiJDPW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:04:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:04:35 INFO - Running setup.py (path:/tmp/pip-k6RdCk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:04:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:04:35 INFO - Running setup.py (path:/tmp/pip-zzkkg9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:04:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:04:35 INFO - Running setup.py (path:/tmp/pip-1u06u1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:04:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:04:35 INFO - Running setup.py (path:/tmp/pip-cNL3hY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:04:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:04:35 INFO - Running setup.py (path:/tmp/pip-2Nh_6D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:04:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:04:35 INFO - Running setup.py (path:/tmp/pip-ThoZIJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:04:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:04:35 INFO - Running setup.py (path:/tmp/pip-W2Tjm5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:04:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:04:35 INFO - Running setup.py (path:/tmp/pip-vqelp4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:04:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:04:35 INFO - Running setup.py (path:/tmp/pip-9LUNw7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:04:35 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:04:35 INFO - Running setup.py install for manifestparser 09:04:36 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:04:36 INFO - Running setup.py install for mozcrash 09:04:36 INFO - Running setup.py install for mozdebug 09:04:36 INFO - Running setup.py install for mozdevice 09:04:36 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:04:36 INFO - Running setup.py install for mozfile 09:04:36 INFO - Running setup.py install for mozhttpd 09:04:36 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:04:36 INFO - Running setup.py install for mozinfo 09:04:36 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:04:36 INFO - Running setup.py install for mozInstall 09:04:36 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:04:36 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:04:36 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:04:36 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:04:36 INFO - Running setup.py install for mozleak 09:04:36 INFO - Running setup.py install for mozlog 09:04:36 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:04:36 INFO - Running setup.py install for moznetwork 09:04:37 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:04:37 INFO - Running setup.py install for mozprocess 09:04:37 INFO - Running setup.py install for mozprofile 09:04:37 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:04:37 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:04:37 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:04:37 INFO - Running setup.py install for mozrunner 09:04:37 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:04:37 INFO - Running setup.py install for mozscreenshot 09:04:37 INFO - Running setup.py install for moztest 09:04:37 INFO - Running setup.py install for mozversion 09:04:37 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:04:37 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:04:37 INFO - Cleaning up... 09:04:37 INFO - Return code: 0 09:04:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:04:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:04:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:04:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:04:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:04:37 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 0x1c311f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dfaa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f9da60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fa1090>, '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 0x1fa1d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f9bf90>, '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': 'd2a8c569965ab8367867b893000001a1-1504281831.413524-429801474', '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 09:04:37 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 09:04:37 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 09:04:37 INFO - Using env: {'DISPLAY': ':0', 09:04:37 INFO - 'HOME': '/home/cltbld', 09:04:37 INFO - 'LANG': 'en_US.UTF-8', 09:04:37 INFO - 'LANGUAGE': 'en_US:en', 09:04:37 INFO - 'LOGNAME': 'cltbld', 09:04:37 INFO - 'MAIL': '/var/mail/cltbld', 09:04:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:04:37 INFO - 'MOZ_NO_REMOTE': '1', 09:04:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:04:37 INFO - 'NO_EM_RESTART': '1', 09:04:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:04:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:04:37 INFO - 'PWD': '/builds/slave/test', 09:04:37 INFO - 'SHELL': '/bin/bash', 09:04:37 INFO - 'SHLVL': '1', 09:04:37 INFO - 'TERM': 'linux', 09:04:37 INFO - 'TMOUT': '86400', 09:04:37 INFO - 'USER': 'cltbld', 09:04:37 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1504281831.413524-429801474', 09:04:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:04:37 INFO - '_': '/tools/buildbot/bin/python'} 09:04:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:04:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:04:37 INFO - Running setup.py (path:/tmp/pip-bNZBJ6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:04:37 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)) 09:04:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:04:37 INFO - Running setup.py (path:/tmp/pip-cVqCL9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:04:38 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)) 09:04:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:04:38 INFO - Running setup.py (path:/tmp/pip-8gMmDY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:04:38 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)) 09:04:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:04:38 INFO - Running setup.py (path:/tmp/pip-dx6HR2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:04:38 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)) 09:04:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:04:38 INFO - Running setup.py (path:/tmp/pip-S9XSyw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:04:38 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)) 09:04:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:04:38 INFO - Running setup.py (path:/tmp/pip-EhU8l1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:04:38 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)) 09:04:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:04:38 INFO - Running setup.py (path:/tmp/pip-5pIZAZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:04:38 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)) 09:04:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:04:38 INFO - Running setup.py (path:/tmp/pip-KSkb3e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:04:38 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)) 09:04:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:04:38 INFO - Running setup.py (path:/tmp/pip-cECvcE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:04:38 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)) 09:04:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:04:38 INFO - Running setup.py (path:/tmp/pip-MvxGLG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:04:38 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)) 09:04:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:04:38 INFO - Running setup.py (path:/tmp/pip-jZ2Qds-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:04:38 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)) 09:04:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:04:38 INFO - Running setup.py (path:/tmp/pip-4wv6ej-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:04:38 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)) 09:04:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:04:38 INFO - Running setup.py (path:/tmp/pip-ZaH5Pp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:04:38 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)) 09:04:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:04:38 INFO - Running setup.py (path:/tmp/pip-L0OXyJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:04:38 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)) 09:04:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:04:38 INFO - Running setup.py (path:/tmp/pip-8K1wf3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:04:38 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)) 09:04:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:04:39 INFO - Running setup.py (path:/tmp/pip-IVAx8f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:04:39 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)) 09:04:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:04:39 INFO - Running setup.py (path:/tmp/pip-nexhQ6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:04:39 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)) 09:04:39 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)) 09:04:39 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)) 09:04:39 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)) 09:04:39 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)) 09:04:39 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)) 09:04:39 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)) 09:04:39 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:04:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:04:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:04:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:04:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:04:40 INFO - Downloading blessings-1.6.tar.gz 09:04:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:04:40 INFO - Installing collected packages: blessings 09:04:40 INFO - Running setup.py install for blessings 09:04:40 INFO - Successfully installed blessings 09:04:40 INFO - Cleaning up... 09:04:40 INFO - Return code: 0 09:04:40 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:04:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:04:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:04:40 INFO - Reading from file tmpfile_stdout 09:04:40 INFO - Current package versions: 09:04:40 INFO - argparse == 1.2.1 09:04:40 INFO - blessings == 1.6 09:04:40 INFO - blobuploader == 1.2.4 09:04:40 INFO - docopt == 0.6.1 09:04:40 INFO - functools32 == 3.2.3-2 09:04:40 INFO - jsonschema == 2.5.1 09:04:40 INFO - manifestparser == 1.1 09:04:40 INFO - mozInstall == 1.13 09:04:40 INFO - mozcrash == 1.0 09:04:40 INFO - mozdebug == 0.1 09:04:40 INFO - mozdevice == 0.51 09:04:40 INFO - mozfile == 1.2 09:04:40 INFO - mozhttpd == 0.7 09:04:40 INFO - mozinfo == 0.10 09:04:40 INFO - mozleak == 0.1 09:04:40 INFO - mozlog == 3.5 09:04:40 INFO - moznetwork == 0.27 09:04:40 INFO - mozprocess == 0.25 09:04:40 INFO - mozprofile == 0.28 09:04:40 INFO - mozrunner == 6.13 09:04:40 INFO - mozscreenshot == 0.1 09:04:40 INFO - mozsystemmonitor == 0.3 09:04:40 INFO - moztest == 0.8 09:04:40 INFO - mozversion == 1.4 09:04:40 INFO - psutil == 3.1.1 09:04:40 INFO - requests == 1.2.3 09:04:40 INFO - wsgiref == 0.1.2 09:04:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:04:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:04:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:04:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:04:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:04:40 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 0x1c311f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dfaa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f9da60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fa1090>, '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 0x1fa1d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f9bf90>, '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': 'd2a8c569965ab8367867b893000001a1-1504281831.413524-429801474', '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 09:04:40 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 09:04:40 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 09:04:40 INFO - Using env: {'DISPLAY': ':0', 09:04:40 INFO - 'HOME': '/home/cltbld', 09:04:40 INFO - 'LANG': 'en_US.UTF-8', 09:04:40 INFO - 'LANGUAGE': 'en_US:en', 09:04:40 INFO - 'LOGNAME': 'cltbld', 09:04:40 INFO - 'MAIL': '/var/mail/cltbld', 09:04:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:04:40 INFO - 'MOZ_NO_REMOTE': '1', 09:04:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:04:40 INFO - 'NO_EM_RESTART': '1', 09:04:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:04:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:04:40 INFO - 'PWD': '/builds/slave/test', 09:04:40 INFO - 'SHELL': '/bin/bash', 09:04:40 INFO - 'SHLVL': '1', 09:04:40 INFO - 'TERM': 'linux', 09:04:40 INFO - 'TMOUT': '86400', 09:04:40 INFO - 'USER': 'cltbld', 09:04:40 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1504281831.413524-429801474', 09:04:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:04:40 INFO - '_': '/tools/buildbot/bin/python'} 09:04:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:04:40 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)) 09:04:40 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)) 09:04:40 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)) 09:04:40 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)) 09:04:40 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)) 09:04:40 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)) 09:04:40 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)) 09:04:40 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)) 09:04:40 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)) 09:04:40 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 09:04:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:04:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:04:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:04:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:04:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 09:04:42 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)) 09:04:42 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)) 09:04:42 INFO - Installing collected packages: simplejson 09:04:42 INFO - Running setup.py install for simplejson 09:04:42 INFO - building 'simplejson._speedups' extension 09:04:42 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 09:04:43 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 09:04:43 INFO - Successfully installed simplejson 09:04:43 INFO - Cleaning up... 09:04:43 INFO - Return code: 0 09:04:43 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:04:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:04:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:04:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:04:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:04:43 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c311f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dfaa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f9da60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fa1090>, '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 0x1fa1d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f9bf90>, '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': 'd2a8c569965ab8367867b893000001a1-1504281831.413524-429801474', '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 09:04:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 09:04:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:04:43 INFO - Using env: {'DISPLAY': ':0', 09:04:43 INFO - 'HOME': '/home/cltbld', 09:04:43 INFO - 'LANG': 'en_US.UTF-8', 09:04:43 INFO - 'LANGUAGE': 'en_US:en', 09:04:43 INFO - 'LOGNAME': 'cltbld', 09:04:43 INFO - 'MAIL': '/var/mail/cltbld', 09:04:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:04:43 INFO - 'MOZ_NO_REMOTE': '1', 09:04:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:04:43 INFO - 'NO_EM_RESTART': '1', 09:04:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:04:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:04:43 INFO - 'PWD': '/builds/slave/test', 09:04:43 INFO - 'SHELL': '/bin/bash', 09:04:43 INFO - 'SHLVL': '1', 09:04:43 INFO - 'TERM': 'linux', 09:04:43 INFO - 'TMOUT': '86400', 09:04:43 INFO - 'USER': 'cltbld', 09:04:43 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1504281831.413524-429801474', 09:04:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:04:43 INFO - '_': '/tools/buildbot/bin/python'} 09:04:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:04:43 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:04:43 INFO - Cleaning up... 09:04:43 INFO - Return code: 0 09:04:43 INFO - Running post-action listener: _resource_record_post_action 09:04:43 INFO - Running post-action listener: _start_resource_monitoring 09:04:43 INFO - Starting resource monitoring. 09:04:43 INFO - [mozharness: 2017-09-01 16:04:43.652390Z] Finished create-virtualenv step (success) 09:04:43 INFO - [mozharness: 2017-09-01 16:04:43.652916Z] Running install step. 09:04:43 INFO - Running pre-action listener: _resource_record_pre_action 09:04:43 INFO - Running main action method: install 09:04:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:04:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:04:43 INFO - Reading from file tmpfile_stdout 09:04:43 INFO - Detecting whether we're running mozinstall >=1.0... 09:04:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:04:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:04:44 INFO - Reading from file tmpfile_stdout 09:04:44 INFO - Output received: 09:04:44 INFO - Usage: mozinstall [options] installer 09:04:44 INFO - Options: 09:04:44 INFO - -h, --help show this help message and exit 09:04:44 INFO - -d DEST, --destination=DEST 09:04:44 INFO - Directory to install application into. [default: 09:04:44 INFO - "/builds/slave/test"] 09:04:44 INFO - --app=APP Application being installed. [default: firefox] 09:04:44 INFO - mkdir: /builds/slave/test/build/application 09:04:44 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'] 09:04:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 09:05:00 INFO - Reading from file tmpfile_stdout 09:05:00 INFO - Output received: 09:05:00 INFO - /builds/slave/test/build/application/firefox/firefox 09:05:00 INFO - Running post-action listener: _resource_record_post_action 09:05:00 INFO - [mozharness: 2017-09-01 16:05:00.463438Z] Finished install step (success) 09:05:00 INFO - [mozharness: 2017-09-01 16:05:00.463697Z] Running setup-mitmproxy step. 09:05:00 INFO - Running pre-action listener: _resource_record_pre_action 09:05:00 INFO - Running main action method: setup_mitmproxy 09:05:00 INFO - Skipping: mitmproxy is not required 09:05:00 INFO - Running post-action listener: _resource_record_post_action 09:05:00 INFO - [mozharness: 2017-09-01 16:05:00.464817Z] Finished setup-mitmproxy step (success) 09:05:00 INFO - [mozharness: 2017-09-01 16:05:00.465037Z] Running run-tests step. 09:05:00 INFO - Running pre-action listener: _resource_record_pre_action 09:05:00 INFO - Running pre-action listener: _set_gcov_prefix 09:05:00 INFO - Running main action method: run_tests 09:05:00 WARNING - Try message not found. 09:05:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:05:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:05:00 INFO - Python 2.7.3 09:05:00 INFO - Return code: 0 09:05:00 INFO - grabbing minidump binary from tooltool 09:05: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')]} 09:05:00 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 0x1fa1090>, '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 0x1fa1d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f9bf90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:05:00 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 09:05:00 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 09:05:00 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 09:05:00 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:05:00 INFO - Return code: 0 09:05:00 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 09:05:00 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:05:00 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:05:00 INFO - ENV: RUST_BACKTRACE is now full 09:05:00 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 09:05:00 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:05:00 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/P0kX4SRdS6ifTGg50JASWg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-043', '--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 09:05:00 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/P0kX4SRdS6ifTGg50JASWg/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-043 --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 09:05:00 INFO - Using env: {'DISPLAY': ':0', 09:05:00 INFO - 'HOME': '/home/cltbld', 09:05:00 INFO - 'LANG': 'en_US.UTF-8', 09:05:00 INFO - 'LANGUAGE': 'en_US:en', 09:05:00 INFO - 'LOGNAME': 'cltbld', 09:05:00 INFO - 'MAIL': '/var/mail/cltbld', 09:05:00 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:05:00 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:05:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:05:00 INFO - 'MOZ_NO_REMOTE': '1', 09:05:00 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:05:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:05:00 INFO - 'NO_EM_RESTART': '1', 09:05:00 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:05:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:05:00 INFO - 'PWD': '/builds/slave/test', 09:05:00 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:05:00 INFO - 'RUST_BACKTRACE': 'full', 09:05:00 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 09:05:00 INFO - 'SHELL': '/bin/bash', 09:05:00 INFO - 'SHLVL': '1', 09:05:00 INFO - 'STYLO_FORCE_ENABLED': '1', 09:05:00 INFO - 'TERM': 'linux', 09:05:00 INFO - 'TMOUT': '86400', 09:05:00 INFO - 'USER': 'cltbld', 09:05:00 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1504281831.413524-429801474', 09:05:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:05:00 INFO - '_': '/tools/buildbot/bin/python'} 09:05:00 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/P0kX4SRdS6ifTGg50JASWg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-043', '--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 09:05:00 INFO - mozversion application_buildid: 20170901150340 09:05:00 INFO - mozversion application_changeset: cc85091ab81c7eb865d2cfb31539702c1bb7a18d 09:05:00 INFO - mozversion application_display_name: Nightly 09:05:00 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:05:00 INFO - mozversion application_name: Firefox 09:05:00 INFO - mozversion application_remotingname: firefox 09:05:00 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 09:05:00 INFO - mozversion application_vendor: Mozilla 09:05:00 INFO - mozversion application_version: 57.0a1 09:05:00 INFO - mozversion platform_buildid: 20170901150340 09:05:00 INFO - mozversion platform_changeset: cc85091ab81c7eb865d2cfb31539702c1bb7a18d 09:05:00 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 09:05:00 INFO - mozversion platform_version: 57.0a1 09:05:00 INFO - using testdate: 1504281900 09:05:00 INFO - actual date: 1504281900 09:05:00 INFO - starting webserver on 'localhost:48991' 09:05:00 INFO - SUITE-START | Running 2 tests 09:05:00 INFO - TEST-START | dromaeo_css 09:05:00 INFO - Initialising browser for dromaeo_css test... 09:05:00 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:48991/getInfo.html -profile /tmp/tmpfv2VA8/profile 09:05:00 INFO - TEST-INFO | started process 30253 (/builds/slave/test/build/application/firefox/firefox http://localhost:48991/getInfo.html) 09:05:07 INFO - TEST-INFO | 30253: exit 0 09:05:07 INFO - Browser initialized. 09:05:07 INFO - Running cycle 1/1 for dromaeo_css test... 09:05:07 INFO - Using env: {'DISPLAY': ':0', 09:05:07 INFO - 'HOME': '/home/cltbld', 09:05:07 INFO - 'JSGC_DISABLE_POISONING': '1', 09:05:07 INFO - 'LANG': 'en_US.UTF-8', 09:05:07 INFO - 'LANGUAGE': 'en_US:en', 09:05:07 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 09:05:07 INFO - 'LOGNAME': 'cltbld', 09:05:07 INFO - 'MAIL': '/var/mail/cltbld', 09:05:07 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:05:07 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:05:07 INFO - 'MOZ_CRASHREPORTER': '1', 09:05:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:05:07 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 09:05:07 INFO - 'MOZ_NO_REMOTE': '1', 09:05:07 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:05:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:05:07 INFO - 'NO_EM_RESTART': '1', 09:05:07 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:05:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:05:07 INFO - 'PWD': '/builds/slave/test', 09:05:07 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:05:07 INFO - 'RUST_BACKTRACE': 'full', 09:05:07 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 09:05:07 INFO - 'SHELL': '/bin/bash', 09:05:07 INFO - 'SHLVL': '1', 09:05:07 INFO - 'STYLO_FORCE_ENABLED': '1', 09:05:07 INFO - 'TERM': 'linux', 09:05:07 INFO - 'TMOUT': '86400', 09:05:07 INFO - 'USER': 'cltbld', 09:05:07 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1504281831.413524-429801474', 09:05:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:05:07 INFO - '_': '/tools/buildbot/bin/python'} 09:05:07 INFO - TEST-INFO | started process 30450 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpfv2VA8/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 09:05:08 INFO - PID 30450 | 09:05:08 INFO - PID 30450 | (/builds/slave/test/build/application/firefox/firefox:30501): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 09:05:08 INFO - PID 30450 | 09:05:18 INFO - PID 30450 | RSS: Main: 171065344 09:05:18 INFO - PID 30450 | 09:08:17 INFO - PID 30450 | Cycle 1(1): loaded http://localhost:48991/tests/dromaeo/cssquery-dojo.html (next: http://localhost:48991/tests/dromaeo/cssquery-ext.html) 09:08:18 INFO - PID 30450 | RSS: Main: 174346240 09:08:18 INFO - PID 30450 | 09:11:20 INFO - PID 30450 | Cycle 1(1): loaded http://localhost:48991/tests/dromaeo/cssquery-ext.html (next: http://localhost:48991/tests/dromaeo/cssquery-jquery.html) 09:11:21 INFO - PID 30450 | RSS: Main: 170196992 09:11:21 INFO - PID 30450 | 09:14:22 INFO - PID 30450 | Cycle 1(1): loaded http://localhost:48991/tests/dromaeo/cssquery-jquery.html (next: http://localhost:48991/tests/dromaeo/cssquery-mootools.html) 09:14:23 INFO - PID 30450 | RSS: Main: 172621824 09:14:23 INFO - PID 30450 | 09:17:25 INFO - PID 30450 | Cycle 1(1): loaded http://localhost:48991/tests/dromaeo/cssquery-mootools.html (next: http://localhost:48991/tests/dromaeo/cssquery-prototype.html) 09:17:25 INFO - PID 30450 | RSS: Main: 170946560 09:17:25 INFO - PID 30450 | 09:20:29 INFO - PID 30450 | Cycle 1(1): loaded http://localhost:48991/tests/dromaeo/cssquery-prototype.html (next: http://localhost:48991/tests/dromaeo/cssquery-yui.html) 09:20:30 INFO - PID 30450 | RSS: Main: 172437504 09:20:30 INFO - PID 30450 | 09:23:31 INFO - PID 30450 | Cycle 1(1): loaded http://localhost:48991/tests/dromaeo/cssquery-yui.html (next: http://localhost:48991/tests/dromaeo/cssquery-dojo.html) 09:23:32 INFO - PID 30450 | RSS: Main: 175321088 09:23:32 INFO - PID 30450 | 09:23:32 INFO - PID 30450 | __start_tp_report 09:23:32 INFO - PID 30450 | _x_x_mozilla_page_load 09:23:32 INFO - PID 30450 | _x_x_mozilla_page_load_details 09:23:32 INFO - PID 30450 | |i|pagename|runs| 09:23:32 INFO - PID 30450 | |0;dojo.html;3267.7875938064008;3305;3326.1850846542593;3340.098173493157;3359.1266270769606;6202.131701561785;6242.937570624293;6435.710393032313;6551.213854337486;6589.077529145911;6482.513811464148;6495.4478869296045;6544.312847151052;6550.050242714803;6599.076129341896;5704.48659620634;5911.054231322989;5937.88124237515;5967.194428752128;6032.306284777263;5804.680742559158;5918.556108291874;6021.036634138539;6023.307319658252;6037.879242415149;5901.262342207224;5910.172575839386;5981.371955944606;5992.041273396258;5994.610350327215;5716.971415142939;5818.156367326714;5871.266091738533;5951.1073338999495;5973.193618861442;5795.478406943395;5852.590318677694;5853.707314634251;5875;5884.293884733859;6328.746850125989;6338.87322253557;6404.743810247584;6407.615543067431;6437.066625339299;6766.627835469048;6781.491388145909;6836.179658441019;6879.793606191823;6880.931190688079;6509.739610415578;6602.471802255808;6932.23745388007;6978;6996.7201311947465;6448.451881590024;6480.995445705923;6481.611103333815;6493.318201588812;6520.510961677893;6906.723731050803;7109.964450177716;7134.393576546052;7191.89212161818;7228.1326240850385;6650.600963942179;6669.366410191024;6669.599824010575;6671.032700258435;6673.332666733385;6771.864562708718;6788.1175447191545;6793.048973143764;6793.830154246085;6797.150356205474;6614.305497922745;6668.066470694107;6679.966600167065;6689;6757.425618822357;6688;6859.657017149123;6867.484938629623;6934.861302773917;6944.479164062715;5295.947040529545;5389.595780316414;5419.83740487786;5486.231927530149;5487.039768040657;4734.881627959328;4808;4837.7097374157665;4865.756712164378;4868.172410690191;6103;6132.325444201134;6138.754449821957;6140.478059364915;6151.538634602333;6954.3045695430055;6955.721771129098;7054.5061845671335;7055.9294407056295;7061.435085193172;5994.28068631767;6056.303525094582;6067.302260240041;6145.8156255312415;6159.4456498914415;5990.31111422192;5998.310194327621;6004.009338459193;6006.279246490449;6014.007688731311;6678.9332106679285;6768.086308348408;6783.491238157158;6795.2185498667295;6795.490338224653;6907.89638155418;6980.965095174593;6996.265392133956;7002.124734408199;7020.543664661781;6703.765368212089;6736.292689267556;6779.050932869501;6809.591424514545;6825.35159159879;6535.705893234721;6619.602823830585;6782.660866956731;6794.3545363190415;6940.09778728762;6911.861762764616;6913.93086069153;6916.481263905328;6922.965385173042;6935.479839011993;6697.9953007049335;6701.363370479797;6735.124433823572;6740;6746.628935408599;6647.102641143577;6647.368499992493;6728.6972086255255;6744.0558321834005;6753.696083676148;6440.033994900802;6570.211574611078;6579.769708060195;6637.435817955335;6660.800175994728;6536.738530458919;6731.057651928832;6871.896921546081;6874.209465911485;6875.621840798604;6570.342965703391;6641.037049627874;6645.335466453509;6657.933420665731;6675.33246675348 09:23:32 INFO - PID 30450 | |1;ext.html;798.7803354077628;843.8227972125853;871.0244526130734;1139.5157058250247;1154.9595764148257;29349.70650293499;39580.802095989515;40431.393529097084;40445.595544044554;40494.392584111265;40266;40612.18775624493;40684.79657601715;40688;40712.59287407125;7237.167725711558;7613.69545218192;7834.686612535492;7834.921650783476;7841.96079019607;7597.658105385243;7713.074431068279;7714.26714462128;7715.961420192891;7724.497907635986;7717.073951125844;7722.7297044603165;7753.457257991943;7766.106897706752;7771.650275737606;7710.267524585155;7730;7734.497257678233;7744.876992836035;7759.7672069838;7640.885386719204;7693.384529237704;7698.4226183035935;7700;7700.961495192544;35731.28537429263;35737;35822;35874;36073;3472.913177170591;3507.789532628025;3508.0878971467346;3510.1926556891804;3510.57873055235;29565.40869182626;29622.555661665094;29651.70348296511;29975.100746977627;30026.84986575053;5165;5186.429492755794;5193.792248310063;5195.9480405195845;5201.661891977009;193324;194177;194430;194873;195190;46438.30342544865;46457.07085858331;46574.76712616415;46602.30096548554;47347.76326118347;49177.508224918005;49268.753656232206;49303.753481233085;49322.75338623284;49355.75322123438;12503.437345319482;14344.641383965485;14500.274986250644;14523.346449409803;14531.055481393676;10135.290529663;11817.645470635724;11927.343996080299;11954.820677689842;11992.880071199352;13354.198748075147;13366.599002029954;13501.729965400637;13556.525521606696;13631.863681363258;5453.182022696627;5536.944630553643;5581;5600.915986260209;5623.746931388097;1008.7276435362411;1026.0560284538242;1028.4600584692946;1033.7674023344687;1037.64719995202;22092.23262139025;22240.110395584317;22772.31683049511;23175.652365214537;23325.416864578183;1112.816385296417;1117.284937639911;1118.8713297970837;1122.4668282565715;1122.680036189682;12025.518979240733;12138.939305303593;12250.509979600716;12267.509299627915;12329.321887296106;225981;226610;227565;227588;227589;48410.75794621004;48988.510114898396;49044.75477622589;49089.509104908495;49203.26195107076;1416.0795482936173;1426.2227086238024;1452.4262916148061;1456.0899437851342;1465.5163795947526;21814.672779907996;22146.557068858856;22208.77791222067;22353.105875764788;22449.775502244767;34276.48585271173;35445;35455.82272088623;35488;35494;199557;199719;199877;200134;200497;29002;29549.409011820073;29575.408491830472;32213.677863221066;32309.35381292408;40097;40771.38842917418;40934;40975;41639.583604164785;29901.700982989893;30240.09279721612;30450.39099217959;30671.53992690156;31389.372212556078;29917.55123673103;30002.549961750155;30144.09567712972;30428.695713042587;31054.68945310518;34220.31559368849;34266.82866585651;34416.48375274423;34649.30701385908;36139.63860361468;31393.52909706402;31547.684523154476;31828.840855795574;31831.522527161647;32521.837390812896;23296.417589560395;23500.647490287316;24208.273751787474;24276.393090172885;24304.392390190387 09:23:32 INFO - PID 30450 | |2;jquery.html;556.5603173492941;564.7910273198914;567.6934455394089;571.4143003421495;582.2750675409116;21916.1233550658;22354.552908941852;22654.54690906181;22674;22697;25294;25729.22812315629;25873.611895821577;25878.96484140632;25902.611460828055;6524.151860258172;6669.399754022137;6672.59964402135;6673.899891501631;6680.66596670167;6531.836704082412;6736.730530778763;7088.432925365984;7100.325469080455;7105.32499412555;7069.328413800681;7080.85838283234;7083.574985500886;7085.397741192005;7087.220405755363;7066.364027237548;7069.929300706978;7096.645167741593;7120;7148.785536433916;6979.546329488567;6989.860202795967;7001;7015.4036906862975;7022.964885175541;30505.23736906595;31237.687623123704;31991.52012719811;32077.1980700482;32151.678483215102;2087.4155236533793;2136.4872430616697;2138.2088627207877;2138.700581918533;2139.176417079436;20186.39440816778;20887.8955605221;20894.895525522275;20932.5813483731;20983.8950805245;2431.7203521595065;2573.3823882268307;2593.6757905261843;2611.2166350094894;2640.537905866485;138709.30645346708;142264.57735422617;142479.28760356133;142528;142586.28706856398;20194.596108077756;21038.684419733716;21540.246091386725;22096.668549971764;22136.557268854533;42126;42151.36772948408;42403.575964239964;42442.36336454956;42482.78758606249;10968.341899486057;11073.778524429466;11112.277701949348;11234.3259404436;11306.773864522664;7010.439164866799;9133.771655708528;9246.4914429707;9284.071592840797;9361.157495825408;9816.411015339103;11195.2723073;11285.379304138187;11525.82711259315;11704.063674905987;3622.4385220290897;3790.8673196437994;3805.904852378713;4106;4180.519240287406;944.8582712593029;957.8850537935492;971.7424882406168;977.1010670183448;989.2086330935223;18986.240550378105;19208.80791192098;19416.320428784926;19536.339411149926;19801.20795168206;1017.5014243020867;1030.0420608833706;1036.2694300518117;1042.8852826189113;1045.1429827541494;10665.84001239982;12771.61685149447;13988.860111398755;14008.299585020708;14111;504029;505223;520575;530912;534732;117639;118802;120553;124157;124548.37725811494;1200.6758175292623;1201.6635342104225;1209.3953023488257;1218.3968935377045;1230.6308107567872;20715.68926466074;20861;20918.47703807417;21485.14059437605;21915.890420547796;62470;62783;63371;64400;64787.67606162128;77705.2229477698;78095;79099;80001.59999199967;80893;16524.173791310146;17139.143042848056;17823;20950;22028.88985555062;23262;23280.76719232786;23435.296941091794;23536.176233831735;23564.528709426057;17437.912810435868;18276.81723182751;18423.53941151482;18458.169382377557;18469.9076504622;17196.914015430342;17394.217260223573;17507.82492175062;17844.375446859296;18017.099145042434;18258.72611910849;18499.260029598667;18597.90701046486;18644.720329195345;18768.343107991153;20584.58830823405;20645.52253729562;20709.378718638465;20784.1686332545;20796.68804967957;17701.911490442464;17935.551611209823;18154.001529915455;18166.72749908779;18513.166907488925 09:23:32 INFO - PID 30450 | |3;mootools.html;1292.7931530955045;1336.4921329894642;1339.879410853023;1362.8432730236025;1363.7068030373473;15055.849441505581;15632.531024069282;15650.295736691833;15654.608634784136;15752.606184845385;15550.84449155508;15864.762028569581;15885.84114158858;15889;15894;2801.341684704098;2868.569714542825;2874.583185438121;2875.7986940914143;2876.1084063940248;2531.190019193859;2718.5242582547976;2784.0812531864535;2804.5372513535244;2810.3676672748675;2795.55271156615;2841.8863245470156;2842.6588809342807;2842.729940655645;2846.544552871541;2687.556553168725;2696.36635390683;2724.278066312429;2737.575675770259;2741.7943654226015;2627.671541057368;2634.9473010539873;2639.7624213820654;2643.8810253538636;2649.0198626508122;15172.772408413883;15506.069635821887;15524.534263971986;15534.378624854991;15592.142432166227;8107.716229931924;8352.331813454908;8384.413091083627;8386.077531471534;8394.286485648727;5224.615476898625;5426.593005524602;5563.276774019392;5589.161625756169;5590.021746194399;1964.6856502959458;1966.488712934633;1985.1364656374378;2030.4111807575784;2050.3541384463847;26298;26468.999999999807;26469.470610587872;26483.602745958633;26517.3370665733;6647.368499992493;6681.432078273401;6800.183977922582;6805.353491418307;6988.475864310095;17441.04074275927;17450;17485.21316540733;17488.912555437397;17497;7958.602069896482;7968.243016913384;7972.960135199403;7988.680452781824;8024.2376974187355;7056.858862822714;7249.891251631229;7325.413966882743;7337.779866604011;7350.228226036296;5020.071286811952;5263.736813159327;5280.181571856291;5289.497497737709;5342.839714808562;1383.4742797736997;1426.3367534096694;1453.302414840861;1505.7816616590533;1511.6523199664139;919.4621146629297;926.7219834049758;929.6699671616614;937.7514958535993;939.056248470283;7567.091948966049;7783.610819459004;7880.369570434352;7924.920750792534;7980.84038319242;965.6427121964917;984.4487087231173;996.7259003773875;999.4552968632113;1029.2383636109375;4690.390249267573;4782.856514304576;4855.490173531728;4890.828820991248;4899.338589290472;21221.15115395367;21540.353789386343;21612.89193554022;22173.113075476802;22252.777472225393;20994;21723.34829955104;21765.891170544364;21773.564528709336;21854.344369668936;10603.893961060445;11046.668599942015;11127.387993660426;11156.442177891073;11205;8665.480071195749;8700.695475658322;8741.125887411208;8757.693480728143;8795.340349473687;17782.733259001383;18223.81776182221;18430;18475.983820889982;18523;18581.535461613566;18876.905615471835;19070.61858762789;19150.52123696863;19309.324173653855;12507.624771256877;12560.246385216917;12646.367681616066;12750.171238869443;12792.6801829955;13949.651258718613;13975.650608734863;13986.44054237819;14030.719385612434;14034.578962631138;11213.215074945001;11229.157813163882;11315.547378105113;11326.433678315887;11431.942840285745;11300.547978080785;11609.129315301218;11702.2978621283;11707.531698731955;11880.76238475243;9356.391834531007;9530.523473826419;9530.95234523823;9535.141837234682;9539.904600953902;6912.758053468105;6929.376356127973;6943.270956549692;6970.616616086164;7027.22700502934;6881.933300338455;6957.965210173917;6974.511784175059;6993.370596646327;7019.543729657556 09:23:32 INFO - PID 30450 | |4;prototype.html;501.98557661244223;502.7721340266537;515.680278227499;517.7566543724449;520.5367223171378;6285.402886725765;6292.276388215351;6331.461825744812;6371.968140159304;6394.793796140336;6094.847628809271;6177.907331390033;6219.414049417393;6233.750649973996;6338.112664227;4992.201247800353;5087.135187018197;5099.872503187413;5117.181250999841;5180.740962951856;5038.093143234217;5044.419891712445;5059.620528460362;5060.266261392096;5100.438951715327;4785.66500344976;4932.605391568683;5041.445441001487;5052.115879721053;5093.312402825608;4980.178270585349;5017.774200161001;5029.245613158019;5063.8480845574695;5122.3853137623355;4495.190865644183;4503.211937910853;4511.751853648048;4528.25283828168;4540.636749060067;6168;6183.6598987055695;6195.473384762301;6229.6573688447115;6244.937550624481;5724.169995350692;5762.193292938992;5771.297467247172;5836.328822185461;5849.736761845727;6457.192850893638;6544.607323560602;6549.836254093638;6623.801285961381;6625.289837690909;4915.451632735712;4920.75396230187;4931.211006238985;4943;4977.626677999165;6990.650467476657;7033.366996970246;7039.430607828157;7053.647317634201;7082.964585177041;5006.974965125224;5014.07239660664;5043.915558154941;5090.618203634742;5107.7446127693465;5133.589312854962;5140.228965655107;5142.528793064666;5165.66423182492;5195.376554813447;5789.247397838339;5825.931794212994;5844.970775146097;5846.736896839652;5855.560832937546;6609.3060228675295;6613.404793568602;6653.63405012729;6671.166104236971;6721.060020884646;1635.1660653067022;1665.1840598107083;1714.7342161964916;1749.5013921032698;1770.7609472721276;1182.5920057580122;1212.1878341511208;1232.642533665245;1238.7393007760693;1243.521244320943;5852.736626851802;5852.853678658068;5873.383294754075;5874.706264686749;6004.3395226525045;6575.769848055295;6778.118844550177;6793.524453288322;6841.589504629738;6884.896726549106;5960.940390596125;5967.8209653710455;5987.08413307746;6027.367126451747;6030.155778191057;3569.964300356963;3624.0611272968836;3632.7093832493338;3633.3278343506536;3656.5612126544984;6623.383763413424;6690.565113267622;6745.258021617717;6767.864642707118;6772.424343930723;7101.644917754092;7115.612455571114;7120.465965052642;7137.89293160603;7189;6628.83427914306;6714.764983225564;6716.265033151714;6738.35985581369;6752.291009444036;6882.552634078769;6911.067005954132;6945.930540694529;6999.860002800017;7014.473914456538;6778.661066946732;6795.626240556817;6838.487113466609;6884.51808373409;6923.9653801730665;6678.2320033194865;6699.162604674416;6711.4966377520905;6755.527113102035;6765.42493888025;4964.975175124101;4968.627352948471;5005.624578156726;5046.116929537389;5051.4443411224;6900.447964162955;6925.515213934977;6949.409300209539;6952.791416257521;7000.299970002959;6595.054458934722;6630.5027122965;6685.364890335411;6718.2609912908565;6734.528582999339;6543.443807276244;6582.308857570078;6644.169478815148;6664.300248474035;6669;6360.745570177141;6385.457236134795;6570.441512471493;6588;6593;6633.270340262471;6660.23696611413;6684.997250412477;6689.431398331197;6757.763478278237;6379.649119298298;6383.904241436289;6415.486761059011;6453.45145662624;6519.315471875385 09:23:32 INFO - PID 30450 | |5;yui.html;896.8834051573294;904.9049849765776;911.2345629671075;915.9924083508138;921.31822451386;19060.332888348894;19980;20041.59916801663;20098.095585698662;20618.278360257395;20180.49548761278;20665.586688266227;20842.270520531783;20932.58134837306;20959.161633534677;959.7216807125925;972.0765272990652;980.289290264556;980.2892902645597;981.8772653418323;945.6028468043438;984.0208992052917;989.915857152143;990.8910019897842;991.1377101921339;1031.6956497833119;1032.1639472027682;1032.334144476812;1032.5972870580442;1034.896510348963;1018.2261481274248;1020.4897551224387;1020.5764607687801;1020.9795804083914;1021.3514418344331;755.4296506137886;755.6410704915177;755.7166062726477;756.092688773474;756.2588663110183;17828.3760068397;18362.816371836245;19752.11115499806;19864.106115224706;19925.9003704982;21149.15403383863;21434.356969290948;21690;21760.02079906408;21845;17019.40432084871;17210.397636082802;17343.392981245597;17349.39277125307;17356.47930562085;3699.0197597636648;3719.776813391205;3727.5154229950203;3728.1984373359587;3728.608496107897;129377.35311323477;130199;131546;132362.3381883094;134804;16072.598185045234;16194.190290485429;16561.42035028768;16587.83412165863;16615.66768664644;28323;28883.855580721964;28957.56563651547;29320.853395732884;29444.411111777645;7110.680019399139;7487.737929172562;8154.714584989497;8328.042275138316;8372.413931024892;4112.485939257593;4182.330827067654;4195.622393984556;4206.894827629334;4218.388333691598;5171.68969861802;5233.215017747369;5310.787568497217;5319.494648008433;5329.946700532945;1076.359566058194;1081.4214395298477;1107.4850194659523;1110.3726394587195;1111.1610733895857;669.4610838275138;678.7929681447107;685.6331862453663;686.543448606671;695.0234919937496;10400;10700.892991070144;11065.114790816715;11455.255408398427;11981.580644677395;608.330836080317;609.1411110334407;624.2321944008894;624.4723208888573;643.5141468191486;5152.227165925066;5224.346956630421;5237.502437268454;5249.842504724865;5274.815381461707;105908;108190;109445;110227;110521.44739276252;97034;97880;98057;99767;103528.48235758924;93874.53062734642;94424.52787736154;94909.52545237368;95241;96648.51675741577;11540.192186547198;11743.17797754149;11769.646910592695;11772;11945.522179112739;40318.39522407108;40848.59151408448;41119.38320925248;43208.35187472127;43245.13509729725;53311.667208320105;53629.7318513405;53944.73027634837;54147.729261355016;71280.64359678168;18348.541286467946;18439;18450.723239151153;18502.167402466654;18643.347482838035;22097.005634746154;22269.665955011013;22940.770592293862;23275.88362058179;23386.415339616644;15993.680126397174;16543.669126617642;16759.32962681479;16872.493825185265;17904.820951790836;18138.274469021093;18408.81591184071;18699.626007480045;18726.15732292023;18803;16433.342666293214;16450.34198632089;16778.32886684519;17596.208170632097;18056.72914906251;12685.429155687832;13072.281024543361;13191.142575732547;13260.602181934557;13283.66790830237;12485.500579977062;12771.552995645108;12855.742885142057;13096.934515327363;13112.08215424911 09:23:32 INFO - PID 30450 | __end_tp_report 09:23:32 INFO - PID 30450 | __start_cc_report 09:23:32 INFO - PID 30450 | _x_x_mozilla_cycle_collect,377 09:23:32 INFO - PID 30450 | __end_cc_report 09:23:32 INFO - PID 30450 | __startTimestamp1504283012144__endTimestamp 09:23:32 INFO - PID 30450 | 09:23:32 INFO - PID 30450 | ------- Summary: start ------- 09:23:32 INFO - PID 30450 | Number of tests: 6 09:23:32 INFO - PID 30450 | 09:23:32 INFO - PID 30450 | [#0] dojo.html Cycles:165 Average:6348.01 Median:6602.47 stddev:736.45 (11.2%) stddev-sans-first:697.94 09:23:32 INFO - PID 30450 | Values: 3267.8 3305.0 3326.2 3340.1 3359.1 6202.1 6242.9 6435.7 6551.2 6589.1 6482.5 6495.4 6544.3 6550.1 6599.1 5704.5 5911.1 5937.9 5967.2 6032.3 5804.7 5918.6 6021.0 6023.3 6037.9 5901.3 5910.2 5981.4 5992.0 5994.6 5717.0 5818.2 5871.3 5951.1 5973.2 5795.5 5852.6 5853.7 5875.0 5884.3 6328.7 6338.9 6404.7 6407.6 6437.1 6766.6 6781.5 6836.2 6879.8 6880.9 6509.7 6602.5 6932.2 6978.0 6996.7 6448.5 6481.0 6481.6 6493.3 6520.5 6906.7 7110.0 7134.4 7191.9 7228.1 6650.6 6669.4 6669.6 6671.0 6673.3 6771.9 6788.1 6793.0 6793.8 6797.2 6614.3 6668.1 6680.0 6689.0 6757.4 6688.0 6859.7 6867.5 6934.9 6944.5 5295.9 5389.6 5419.8 5486.2 5487.0 4734.9 4808.0 4837.7 4865.8 4868.2 6103.0 6132.3 6138.8 6140.5 6151.5 6954.3 6955.7 7054.5 7055.9 7061.4 5994.3 6056.3 6067.3 6145.8 6159.4 5990.3 5998.3 6004.0 6006.3 6014.0 6678.9 6768.1 6783.5 6795.2 6795.5 6907.9 6981.0 6996.3 7002.1 7020.5 6703.8 6736.3 6779.1 6809.6 6825.4 6535.7 6619.6 6782.7 6794.4 6940.1 6911.9 6913.9 6916.5 6923.0 6935.5 6698.0 6701.4 6735.1 6740.0 6746.6 6647.1 6647.4 6728.7 6744.1 6753.7 6440.0 6570.2 6579.8 6637.4 6660.8 6536.7 6731.1 6871.9 6874.2 6875.6 6570.3 6641.0 6645.3 6657.9 6675.3 09:23:32 INFO - PID 30450 | 09:23:32 INFO - PID 30450 | [#1] ext.html Cycles:180 Average:36726.53 Median:32261.52 stddev:53795.43 (166.7%) stddev-sans-first:53878.70 09:23:32 INFO - PID 30450 | Values: 798.8 843.8 871.0 1139.5 1155.0 29349.7 39580.8 40431.4 40445.6 40494.4 40266.0 40612.2 40684.8 40688.0 40712.6 7237.2 7613.7 7834.7 7834.9 7842.0 7597.7 7713.1 7714.3 7716.0 7724.5 7717.1 7722.7 7753.5 7766.1 7771.7 7710.3 7730.0 7734.5 7744.9 7759.8 7640.9 7693.4 7698.4 7700.0 7701.0 35731.3 35737.0 35822.0 35874.0 36073.0 3472.9 3507.8 3508.1 3510.2 3510.6 29565.4 29622.6 29651.7 29975.1 30026.8 5165.0 5186.4 5193.8 5195.9 5201.7 193324.0 194177.0 194430.0 194873.0 195190.0 46438.3 46457.1 46574.8 46602.3 47347.8 49177.5 49268.8 49303.8 49322.8 49355.8 12503.4 14344.6 14500.3 14523.3 14531.1 10135.3 11817.6 11927.3 11954.8 11992.9 13354.2 13366.6 13501.7 13556.5 13631.9 5453.2 5536.9 5581.0 5600.9 5623.7 1008.7 1026.1 1028.5 1033.8 1037.6 22092.2 22240.1 22772.3 23175.7 23325.4 1112.8 1117.3 1118.9 1122.5 1122.7 12025.5 12138.9 12250.5 12267.5 12329.3 225981.0 226610.0 227565.0 227588.0 227589.0 48410.8 48988.5 49044.8 49089.5 49203.3 1416.1 1426.2 1452.4 1456.1 1465.5 21814.7 22146.6 22208.8 22353.1 22449.8 34276.5 35445.0 35455.8 35488.0 35494.0 199557.0 199719.0 199877.0 200134.0 200497.0 29002.0 29549.4 29575.4 32213.7 32309.4 40097.0 40771.4 40934.0 40975.0 41639.6 29901.7 30240.1 30450.4 30671.5 31389.4 29917.6 30002.5 30144.1 30428.7 31054.7 34220.3 34266.8 34416.5 34649.3 36139.6 31393.5 31547.7 31828.8 31831.5 32521.8 23296.4 23500.6 24208.3 24276.4 24304.4 09:23:32 INFO - PID 30450 | 09:23:32 INFO - PID 30450 | [#2] jquery.html Cycles:180 Average:37674.29 Median:22504.55 stddev:87276.37 (387.8%) stddev-sans-first:87476.70 09:23:32 INFO - PID 30450 | Values: 556.6 564.8 567.7 571.4 582.3 21916.1 22354.6 22654.5 22674.0 22697.0 25294.0 25729.2 25873.6 25879.0 25902.6 6524.2 6669.4 6672.6 6673.9 6680.7 6531.8 6736.7 7088.4 7100.3 7105.3 7069.3 7080.9 7083.6 7085.4 7087.2 7066.4 7069.9 7096.6 7120.0 7148.8 6979.5 6989.9 7001.0 7015.4 7023.0 30505.2 31237.7 31991.5 32077.2 32151.7 2087.4 2136.5 2138.2 2138.7 2139.2 20186.4 20887.9 20894.9 20932.6 20983.9 2431.7 2573.4 2593.7 2611.2 2640.5 138709.3 142264.6 142479.3 142528.0 142586.3 20194.6 21038.7 21540.2 22096.7 22136.6 42126.0 42151.4 42403.6 42442.4 42482.8 10968.3 11073.8 11112.3 11234.3 11306.8 7010.4 9133.8 9246.5 9284.1 9361.2 9816.4 11195.3 11285.4 11525.8 11704.1 3622.4 3790.9 3805.9 4106.0 4180.5 944.9 957.9 971.7 977.1 989.2 18986.2 19208.8 19416.3 19536.3 19801.2 1017.5 1030.0 1036.3 1042.9 1045.1 10665.8 12771.6 13988.9 14008.3 14111.0 504029.0 505223.0 520575.0 530912.0 534732.0 117639.0 118802.0 120553.0 124157.0 124548.4 1200.7 1201.7 1209.4 1218.4 1230.6 20715.7 20861.0 20918.5 21485.1 21915.9 62470.0 62783.0 63371.0 64400.0 64787.7 77705.2 78095.0 79099.0 80001.6 80893.0 16524.2 17139.1 17823.0 20950.0 22028.9 23262.0 23280.8 23435.3 23536.2 23564.5 17437.9 18276.8 18423.5 18458.2 18469.9 17196.9 17394.2 17507.8 17844.4 18017.1 18258.7 18499.3 18597.9 18644.7 18768.3 20584.6 20645.5 20709.4 20784.2 20796.7 17701.9 17935.6 18154.0 18166.7 18513.2 09:23:32 INFO - PID 30450 | 09:23:32 INFO - PID 30450 | [#3] mootools.html Cycles:180 Average:9400.42 Median:14583.18 stddev:6721.79 (46.1%) stddev-sans-first:6713.04 09:23:32 INFO - PID 30450 | Values: 1292.8 1336.5 1339.9 1362.8 1363.7 15055.8 15632.5 15650.3 15654.6 15752.6 15550.8 15864.8 15885.8 15889.0 15894.0 2801.3 2868.6 2874.6 2875.8 2876.1 2531.2 2718.5 2784.1 2804.5 2810.4 2795.6 2841.9 2842.7 2842.7 2846.5 2687.6 2696.4 2724.3 2737.6 2741.8 2627.7 2634.9 2639.8 2643.9 2649.0 15172.8 15506.1 15524.5 15534.4 15592.1 8107.7 8352.3 8384.4 8386.1 8394.3 5224.6 5426.6 5563.3 5589.2 5590.0 1964.7 1966.5 1985.1 2030.4 2050.4 26298.0 26469.0 26469.5 26483.6 26517.3 6647.4 6681.4 6800.2 6805.4 6988.5 17441.0 17450.0 17485.2 17488.9 17497.0 7958.6 7968.2 7973.0 7988.7 8024.2 7056.9 7249.9 7325.4 7337.8 7350.2 5020.1 5263.7 5280.2 5289.5 5342.8 1383.5 1426.3 1453.3 1505.8 1511.7 919.5 926.7 929.7 937.8 939.1 7567.1 7783.6 7880.4 7924.9 7980.8 965.6 984.4 996.7 999.5 1029.2 4690.4 4782.9 4855.5 4890.8 4899.3 21221.2 21540.4 21612.9 22173.1 22252.8 20994.0 21723.3 21765.9 21773.6 21854.3 10603.9 11046.7 11127.4 11156.4 11205.0 8665.5 8700.7 8741.1 8757.7 8795.3 17782.7 18223.8 18430.0 18476.0 18523.0 18581.5 18876.9 19070.6 19150.5 19309.3 12507.6 12560.2 12646.4 12750.2 12792.7 13949.7 13975.7 13986.4 14030.7 14034.6 11213.2 11229.2 11315.5 11326.4 11431.9 11300.5 11609.1 11702.3 11707.5 11880.8 9356.4 9530.5 9531.0 9535.1 9539.9 6912.8 6929.4 6943.3 6970.6 7027.2 6881.9 6958.0 6974.5 6993.4 7019.5 09:23:32 INFO - PID 30450 | 09:23:32 INFO - PID 30450 | [#4] prototype.html Cycles:180 Average:5614.87 Median:6224.54 stddev:1595.68 (25.6%) stddev-sans-first:1553.32 09:23:32 INFO - PID 30450 | Values: 502.0 502.8 515.7 517.8 520.5 6285.4 6292.3 6331.5 6372.0 6394.8 6094.8 6177.9 6219.4 6233.8 6338.1 4992.2 5087.1 5099.9 5117.2 5180.7 5038.1 5044.4 5059.6 5060.3 5100.4 4785.7 4932.6 5041.4 5052.1 5093.3 4980.2 5017.8 5029.2 5063.8 5122.4 4495.2 4503.2 4511.8 4528.3 4540.6 6168.0 6183.7 6195.5 6229.7 6244.9 5724.2 5762.2 5771.3 5836.3 5849.7 6457.2 6544.6 6549.8 6623.8 6625.3 4915.5 4920.8 4931.2 4943.0 4977.6 6990.7 7033.4 7039.4 7053.6 7083.0 5007.0 5014.1 5043.9 5090.6 5107.7 5133.6 5140.2 5142.5 5165.7 5195.4 5789.2 5825.9 5845.0 5846.7 5855.6 6609.3 6613.4 6653.6 6671.2 6721.1 1635.2 1665.2 1714.7 1749.5 1770.8 1182.6 1212.2 1232.6 1238.7 1243.5 5852.7 5852.9 5873.4 5874.7 6004.3 6575.8 6778.1 6793.5 6841.6 6884.9 5960.9 5967.8 5987.1 6027.4 6030.2 3570.0 3624.1 3632.7 3633.3 3656.6 6623.4 6690.6 6745.3 6767.9 6772.4 7101.6 7115.6 7120.5 7137.9 7189.0 6628.8 6714.8 6716.3 6738.4 6752.3 6882.6 6911.1 6945.9 6999.9 7014.5 6778.7 6795.6 6838.5 6884.5 6924.0 6678.2 6699.2 6711.5 6755.5 6765.4 4965.0 4968.6 5005.6 5046.1 5051.4 6900.4 6925.5 6949.4 6952.8 7000.3 6595.1 6630.5 6685.4 6718.3 6734.5 6543.4 6582.3 6644.2 6664.3 6669.0 6360.7 6385.5 6570.4 6588.0 6593.0 6633.3 6660.2 6685.0 6689.4 6757.8 6379.6 6383.9 6415.5 6453.5 6519.3 09:23:32 INFO - PID 30450 | 09:23:32 INFO - PID 30450 | [#5] yui.html Cycles:180 Average:23743.31 Median:21054.16 stddev:32775.92 (155.7%) stddev-sans-first:32822.97 09:23:32 INFO - PID 30450 | Values: 896.9 904.9 911.2 916.0 921.3 19060.3 19980.0 20041.6 20098.1 20618.3 20180.5 20665.6 20842.3 20932.6 20959.2 959.7 972.1 980.3 980.3 981.9 945.6 984.0 989.9 990.9 991.1 1031.7 1032.2 1032.3 1032.6 1034.9 1018.2 1020.5 1020.6 1021.0 1021.4 755.4 755.6 755.7 756.1 756.3 17828.4 18362.8 19752.1 19864.1 19925.9 21149.2 21434.4 21690.0 21760.0 21845.0 17019.4 17210.4 17343.4 17349.4 17356.5 3699.0 3719.8 3727.5 3728.2 3728.6 129377.4 130199.0 131546.0 132362.3 134804.0 16072.6 16194.2 16561.4 16587.8 16615.7 28323.0 28883.9 28957.6 29320.9 29444.4 7110.7 7487.7 8154.7 8328.0 8372.4 4112.5 4182.3 4195.6 4206.9 4218.4 5171.7 5233.2 5310.8 5319.5 5329.9 1076.4 1081.4 1107.5 1110.4 1111.2 669.5 678.8 685.6 686.5 695.0 10400.0 10700.9 11065.1 11455.3 11981.6 608.3 609.1 624.2 624.5 643.5 5152.2 5224.3 5237.5 5249.8 5274.8 105908.0 108190.0 109445.0 110227.0 110521.4 97034.0 97880.0 98057.0 99767.0 103528.5 93874.5 94424.5 94909.5 95241.0 96648.5 11540.2 11743.2 11769.6 11772.0 11945.5 40318.4 40848.6 41119.4 43208.4 43245.1 53311.7 53629.7 53944.7 54147.7 71280.6 18348.5 18439.0 18450.7 18502.2 18643.3 22097.0 22269.7 22940.8 23275.9 23386.4 15993.7 16543.7 16759.3 16872.5 17904.8 18138.3 18408.8 18699.6 18726.2 18803.0 16433.3 16450.3 16778.3 17596.2 18056.7 12685.4 13072.3 13191.1 13260.6 13283.7 12485.5 12771.6 12855.7 13096.9 13112.1 09:23:32 INFO - PID 30450 | -------- Summary: end -------- 09:23:32 INFO - PID 30450 | 09:23:32 INFO - TEST-INFO | 30450: exit 0 09:23:32 INFO - TEST-OK | dromaeo_css | took 1112049ms 09:23:32 INFO - TEST-START | kraken 09:23:32 INFO - Initialising browser for kraken test... 09:23:32 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:48991/getInfo.html -profile /tmp/tmppERGxV/profile 09:23:32 INFO - TEST-INFO | started process 30834 (/builds/slave/test/build/application/firefox/firefox http://localhost:48991/getInfo.html) 09:23:38 INFO - TEST-INFO | 30834: exit 0 09:23:39 INFO - Browser initialized. 09:23:39 INFO - Running cycle 1/1 for kraken test... 09:23:39 INFO - Using env: {'DISPLAY': ':0', 09:23:39 INFO - 'HOME': '/home/cltbld', 09:23:39 INFO - 'JSGC_DISABLE_POISONING': '1', 09:23:39 INFO - 'LANG': 'en_US.UTF-8', 09:23:39 INFO - 'LANGUAGE': 'en_US:en', 09:23:39 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 09:23:39 INFO - 'LOGNAME': 'cltbld', 09:23:39 INFO - 'MAIL': '/var/mail/cltbld', 09:23:39 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:23:39 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:23:39 INFO - 'MOZ_CRASHREPORTER': '1', 09:23:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:23:39 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 09:23:39 INFO - 'MOZ_NO_REMOTE': '1', 09:23:39 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:23:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:23:39 INFO - 'NO_EM_RESTART': '1', 09:23:39 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:23:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:23:39 INFO - 'PWD': '/builds/slave/test', 09:23:39 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:23:39 INFO - 'RUST_BACKTRACE': 'full', 09:23:39 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 09:23:39 INFO - 'SHELL': '/bin/bash', 09:23:39 INFO - 'SHLVL': '1', 09:23:39 INFO - 'STYLO_FORCE_ENABLED': '1', 09:23:39 INFO - 'TERM': 'linux', 09:23:39 INFO - 'TMOUT': '86400', 09:23:39 INFO - 'USER': 'cltbld', 09:23:39 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1504281831.413524-429801474', 09:23:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:23:39 INFO - '_': '/tools/buildbot/bin/python'} 09:23:39 INFO - TEST-INFO | started process 31033 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmppERGxV/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 09:23:40 INFO - PID 31033 | 09:23:40 INFO - PID 31033 | (/builds/slave/test/build/application/firefox/firefox:31084): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 09:23:40 INFO - PID 31033 | 09:23:50 INFO - PID 31033 | RSS: Main: 167698432 09:23:50 INFO - PID 31033 | 09:23:52 INFO - PID 31033 | Cycle 1(1): loaded http://localhost:48991/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:48991/tests/kraken/driver.html?testName=audio-beat-detection) 09:23:53 INFO - PID 31033 | RSS: Main: 172027904 09:23:53 INFO - PID 31033 | 09:23:56 INFO - PID 31033 | Cycle 1(1): loaded http://localhost:48991/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:48991/tests/kraken/driver.html?testName=audio-dft) 09:23:57 INFO - PID 31033 | RSS: Main: 172982272 09:23:57 INFO - PID 31033 | 09:24:00 INFO - PID 31033 | Cycle 1(1): loaded http://localhost:48991/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:48991/tests/kraken/driver.html?testName=audio-fft) 09:24:00 INFO - PID 31033 | RSS: Main: 167403520 09:24:00 INFO - PID 31033 | 09:24:03 INFO - PID 31033 | Cycle 1(1): loaded http://localhost:48991/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:48991/tests/kraken/driver.html?testName=audio-oscillator) 09:24:03 INFO - PID 31033 | RSS: Main: 167899136 09:24:03 INFO - PID 31033 | 09:24:05 INFO - PID 31033 | Cycle 1(1): loaded http://localhost:48991/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:48991/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:24:06 INFO - PID 31033 | RSS: Main: 168255488 09:24:06 INFO - PID 31033 | 09:24:09 INFO - PID 31033 | Cycle 1(1): loaded http://localhost:48991/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:48991/tests/kraken/driver.html?testName=imaging-darkroom) 09:24:10 INFO - PID 31033 | RSS: Main: 170754048 09:24:10 INFO - PID 31033 | 09:24:13 INFO - PID 31033 | Cycle 1(1): loaded http://localhost:48991/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:48991/tests/kraken/driver.html?testName=imaging-desaturate) 09:24:14 INFO - PID 31033 | RSS: Main: 171253760 09:24:14 INFO - PID 31033 | 09:24:17 INFO - PID 31033 | Cycle 1(1): loaded http://localhost:48991/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:48991/tests/kraken/driver.html?testName=json-parse-financial) 09:24:17 INFO - PID 31033 | RSS: Main: 171753472 09:24:17 INFO - PID 31033 | 09:24:19 INFO - PID 31033 | Cycle 1(1): loaded http://localhost:48991/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:48991/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:24:20 INFO - PID 31033 | RSS: Main: 171524096 09:24:20 INFO - PID 31033 | 09:24:21 INFO - PID 31033 | Cycle 1(1): loaded http://localhost:48991/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:48991/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:24:22 INFO - PID 31033 | RSS: Main: 171417600 09:24:22 INFO - PID 31033 | 09:24:24 INFO - PID 31033 | Cycle 1(1): loaded http://localhost:48991/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:48991/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:24:25 INFO - PID 31033 | RSS: Main: 171520000 09:24:25 INFO - PID 31033 | 09:24:27 INFO - PID 31033 | Cycle 1(1): loaded http://localhost:48991/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:48991/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:24:28 INFO - PID 31033 | RSS: Main: 171667456 09:24:28 INFO - PID 31033 | 09:24:31 INFO - PID 31033 | Cycle 1(1): loaded http://localhost:48991/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:48991/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:24:32 INFO - PID 31033 | RSS: Main: 172175360 09:24:32 INFO - PID 31033 | 09:24:33 INFO - PID 31033 | Cycle 1(1): loaded http://localhost:48991/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:48991/tests/kraken/driver.html?testName=ai-astar) 09:24:34 INFO - PID 31033 | RSS: Main: 173457408 09:24:34 INFO - PID 31033 | 09:24:34 INFO - PID 31033 | __start_tp_report 09:24:34 INFO - PID 31033 | _x_x_mozilla_page_load 09:24:34 INFO - PID 31033 | _x_x_mozilla_page_load_details 09:24:34 INFO - PID 31033 | |i|pagename|runs| 09:24:34 INFO - PID 31033 | |0;ai-astar;98;108;102;107;106;109;110;99;110;109 09:24:34 INFO - PID 31033 | |1;audio-beat-detection;150;115;155;181;117;141;146;126;170;118 09:24:34 INFO - PID 31033 | |2;audio-dft;161;162;162;159;161;164;163;164;164;161 09:24:34 INFO - PID 31033 | |3;audio-fft;74;74;75;190;74;74;73;73;149;76 09:24:34 INFO - PID 31033 | |4;audio-oscillator;78;79;128;80;79;79;130;79;80;137 09:24:34 INFO - PID 31033 | |5;imaging-gaussian-blur;114;115;115;114;114;114;114;114;114;114 09:24:34 INFO - PID 31033 | |6;imaging-darkroom;149;148;149;148;148;148;147;149;147;147 09:24:34 INFO - PID 31033 | |7;imaging-desaturate;93;94;95;94;93;94;94;94;94;93 09:24:34 INFO - PID 31033 | |8;json-parse-financial;53;54;54;55;58;56;54;54;53;55 09:24:34 INFO - PID 31033 | |9;json-stringify-tinderbox;39;39;38;38;38;39;39;39;39;38 09:24:34 INFO - PID 31033 | |10;stanford-crypto-aes;74;74;77;77;74;75;73;74;80;74 09:24:34 INFO - PID 31033 | |11;stanford-crypto-ccm;124;123;119;125;117;123;117;119;131;161 09:24:34 INFO - PID 31033 | |12;stanford-crypto-pbkdf2;177;186;181;169;171;175;171;168;168;169 09:24:34 INFO - PID 31033 | |13;stanford-crypto-sha256-iterative;66;68;72;65;65;66;65;65;74;63 09:24:34 INFO - PID 31033 | __end_tp_report 09:24:34 INFO - PID 31033 | __start_cc_report 09:24:34 INFO - PID 31033 | _x_x_mozilla_cycle_collect,969 09:24:34 INFO - PID 31033 | __end_cc_report 09:24:34 INFO - PID 31033 | __startTimestamp1504283074120__endTimestamp 09:24:34 INFO - PID 31033 | 09:24:34 INFO - PID 31033 | ------- Summary: start ------- 09:24:34 INFO - PID 31033 | Number of tests: 14 09:24:34 INFO - PID 31033 | 09:24:34 INFO - PID 31033 | [#0] ai-astar Cycles:10 Average:105.80 Median:109.50 stddev:4.52 (4.1%) stddev-sans-first:3.81 09:24:34 INFO - PID 31033 | Values: 98.0 108.0 102.0 107.0 106.0 109.0 110.0 99.0 110.0 109.0 09:24:34 INFO - PID 31033 | 09:24:34 INFO - PID 31033 | [#1] audio-beat-detection Cycles:10 Average:141.90 Median:148.00 stddev:22.95 (15.5%) stddev-sans-first:24.16 09:24:34 INFO - PID 31033 | Values: 150.0 115.0 155.0 181.0 117.0 141.0 146.0 126.0 170.0 118.0 09:24:34 INFO - PID 31033 | 09:24:34 INFO - PID 31033 | [#2] audio-dft Cycles:10 Average:162.10 Median:162.50 stddev:1.66 (1.0%) stddev-sans-first:1.72 09:24:34 INFO - PID 31033 | Values: 161.0 162.0 162.0 159.0 161.0 164.0 163.0 164.0 164.0 161.0 09:24:34 INFO - PID 31033 | 09:24:34 INFO - PID 31033 | [#3] audio-fft Cycles:10 Average:93.20 Median:74.00 stddev:41.37 (55.9%) stddev-sans-first:43.29 09:24:34 INFO - PID 31033 | Values: 74.0 74.0 75.0 190.0 74.0 74.0 73.0 73.0 149.0 76.0 09:24:34 INFO - PID 31033 | 09:24:34 INFO - PID 31033 | [#4] audio-oscillator Cycles:10 Average:94.90 Median:79.00 stddev:25.48 (32.2%) stddev-sans-first:26.28 09:24:34 INFO - PID 31033 | Values: 78.0 79.0 128.0 80.0 79.0 79.0 130.0 79.0 80.0 137.0 09:24:34 INFO - PID 31033 | 09:24:34 INFO - PID 31033 | [#5] imaging-gaussian-blur Cycles:10 Average:114.20 Median:114.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 09:24:34 INFO - PID 31033 | Values: 114.0 115.0 115.0 114.0 114.0 114.0 114.0 114.0 114.0 114.0 09:24:34 INFO - PID 31033 | 09:24:34 INFO - PID 31033 | [#6] imaging-darkroom Cycles:10 Average:148.00 Median:148.00 stddev:0.82 (0.6%) stddev-sans-first:0.78 09:24:34 INFO - PID 31033 | Values: 149.0 148.0 149.0 148.0 148.0 148.0 147.0 149.0 147.0 147.0 09:24:34 INFO - PID 31033 | 09:24:34 INFO - PID 31033 | [#7] imaging-desaturate Cycles:10 Average:93.80 Median:94.00 stddev:0.63 (0.7%) stddev-sans-first:0.60 09:24:34 INFO - PID 31033 | Values: 93.0 94.0 95.0 94.0 93.0 94.0 94.0 94.0 94.0 93.0 09:24:34 INFO - PID 31033 | 09:24:34 INFO - PID 31033 | [#8] json-parse-financial Cycles:10 Average:54.60 Median:54.50 stddev:1.51 (2.8%) stddev-sans-first:1.48 09:24:34 INFO - PID 31033 | Values: 53.0 54.0 54.0 55.0 58.0 56.0 54.0 54.0 53.0 55.0 09:24:34 INFO - PID 31033 | 09:24:34 INFO - PID 31033 | [#9] json-stringify-tinderbox Cycles:10 Average:38.60 Median:39.00 stddev:0.52 (1.3%) stddev-sans-first:0.53 09:24:34 INFO - PID 31033 | Values: 39.0 39.0 38.0 38.0 38.0 39.0 39.0 39.0 39.0 38.0 09:24:34 INFO - PID 31033 | 09:24:34 INFO - PID 31033 | [#10] stanford-crypto-aes Cycles:10 Average:75.20 Median:74.50 stddev:2.15 (2.9%) stddev-sans-first:2.24 09:24:34 INFO - PID 31033 | Values: 74.0 74.0 77.0 77.0 74.0 75.0 73.0 74.0 80.0 74.0 09:24:34 INFO - PID 31033 | 09:24:34 INFO - PID 31033 | [#11] stanford-crypto-ccm Cycles:10 Average:125.90 Median:123.50 stddev:13.05 (10.6%) stddev-sans-first:13.82 09:24:34 INFO - PID 31033 | Values: 124.0 123.0 119.0 125.0 117.0 123.0 117.0 119.0 131.0 161.0 09:24:34 INFO - PID 31033 | 09:24:34 INFO - PID 31033 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:173.50 Median:173.00 stddev:6.15 (3.6%) stddev-sans-first:6.39 09:24:34 INFO - PID 31033 | Values: 177.0 186.0 181.0 169.0 171.0 175.0 171.0 168.0 168.0 169.0 09:24:34 INFO - PID 31033 | 09:24:34 INFO - PID 31033 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.90 Median:66.00 stddev:3.48 (5.3%) stddev-sans-first:3.67 09:24:34 INFO - PID 31033 | Values: 66.0 68.0 72.0 65.0 65.0 66.0 65.0 65.0 74.0 63.0 09:24:34 INFO - PID 31033 | -------- Summary: end -------- 09:24:34 INFO - PID 31033 | 09:24:34 INFO - PID 31033 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 09:24:34 INFO - TEST-INFO | 31033: exit 0 09:24:34 INFO - TEST-OK | kraken | took 61979ms 09:24:34 INFO - SUITE-END | took 1174s 09:24:35 INFO - Completed test suite (00:19:34) 09:24:35 INFO - javascript benchmark 09:24:35 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8694.448365404249, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3267.7875938064008, 3305.0, 3326.1850846542593, 3340.098173493157, 3359.1266270769606, 6202.131701561785, 6242.937570624293, 6435.710393032313, 6551.213854337486, 6589.077529145911, 6482.513811464148, 6495.4478869296045, 6544.312847151052, 6550.050242714803, 6599.076129341896, 5704.48659620634, 5911.054231322989, 5937.88124237515, 5967.194428752128, 6032.306284777263, 5804.680742559158, 5918.556108291874, 6021.036634138539, 6023.307319658252, 6037.879242415149, 5901.262342207224, 5910.172575839386, 5981.371955944606, 5992.041273396258, 5994.610350327215, 5716.971415142939, 5818.156367326714, 5871.266091738533, 5951.1073338999495, 5973.193618861442, 5795.478406943395, 5852.590318677694, 5853.707314634251, 5875.0, 5884.293884733859, 6328.746850125989, 6338.87322253557, 6404.743810247584, 6407.615543067431, 6437.066625339299, 6766.627835469048, 6781.491388145909, 6836.179658441019, 6879.793606191823, 6880.931190688079, 6509.739610415578, 6602.471802255808, 6932.23745388007, 6978.0, 6996.7201311947465, 6448.451881590024, 6480.995445705923, 6481.611103333815, 6493.318201588812, 6520.510961677893, 6906.723731050803, 7109.964450177716, 7134.393576546052, 7191.89212161818, 7228.1326240850385, 6650.600963942179, 6669.366410191024, 6669.599824010575, 6671.032700258435, 6673.332666733385, 6771.864562708718, 6788.1175447191545, 6793.048973143764, 6793.830154246085, 6797.150356205474, 6614.305497922745, 6668.066470694107, 6679.966600167065, 6689.0, 6757.425618822357, 6688.0, 6859.657017149123, 6867.484938629623, 6934.861302773917, 6944.479164062715, 5295.947040529545, 5389.595780316414, 5419.83740487786, 5486.231927530149, 5487.039768040657, 4734.881627959328, 4808.0, 4837.7097374157665, 4865.756712164378, 4868.172410690191, 6103.0, 6132.325444201134, 6138.754449821957, 6140.478059364915, 6151.538634602333, 6954.3045695430055, 6955.721771129098, 7054.5061845671335, 7055.9294407056295, 7061.435085193172, 5994.28068631767, 6056.303525094582, 6067.302260240041, 6145.8156255312415, 6159.4456498914415, 5990.31111422192, 5998.310194327621, 6004.009338459193, 6006.279246490449, 6014.007688731311, 6678.9332106679285, 6768.086308348408, 6783.491238157158, 6795.2185498667295, 6795.490338224653, 6907.89638155418, 6980.965095174593, 6996.265392133956, 7002.124734408199, 7020.543664661781, 6703.765368212089, 6736.292689267556, 6779.050932869501, 6809.591424514545, 6825.35159159879, 6535.705893234721, 6619.602823830585, 6782.660866956731, 6794.3545363190415, 6940.09778728762, 6911.861762764616, 6913.93086069153, 6916.481263905328, 6922.965385173042, 6935.479839011993, 6697.9953007049335, 6701.363370479797, 6735.124433823572, 6740.0, 6746.628935408599, 6647.102641143577, 6647.368499992493, 6728.6972086255255, 6744.0558321834005, 6753.696083676148, 6440.033994900802, 6570.211574611078, 6579.769708060195, 6637.435817955335, 6660.800175994728, 6536.738530458919, 6731.057651928832, 6871.896921546081, 6874.209465911485, 6875.621840798604, 6570.342965703391, 6641.037049627874, 6645.335466453509, 6657.933420665731, 6675.33246675348], "value": 6294.259241617193, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [798.7803354077628, 843.8227972125853, 871.0244526130734, 1139.5157058250247, 1154.9595764148257, 29349.70650293499, 39580.802095989515, 40431.393529097084, 40445.595544044554, 40494.392584111265, 40266.0, 40612.18775624493, 40684.79657601715, 40688.0, 40712.59287407125, 7237.167725711558, 7613.69545218192, 7834.686612535492, 7834.921650783476, 7841.96079019607, 7597.658105385243, 7713.074431068279, 7714.26714462128, 7715.961420192891, 7724.497907635986, 7717.073951125844, 7722.7297044603165, 7753.457257991943, 7766.106897706752, 7771.650275737606, 7710.267524585155, 7730.0, 7734.497257678233, 7744.876992836035, 7759.7672069838, 7640.885386719204, 7693.384529237704, 7698.4226183035935, 7700.0, 7700.961495192544, 35731.28537429263, 35737.0, 35822.0, 35874.0, 36073.0, 3472.913177170591, 3507.789532628025, 3508.0878971467346, 3510.1926556891804, 3510.57873055235, 29565.40869182626, 29622.555661665094, 29651.70348296511, 29975.100746977627, 30026.84986575053, 5165.0, 5186.429492755794, 5193.792248310063, 5195.9480405195845, 5201.661891977009, 193324.0, 194177.0, 194430.0, 194873.0, 195190.0, 46438.30342544865, 46457.07085858331, 46574.76712616415, 46602.30096548554, 47347.76326118347, 49177.508224918005, 49268.753656232206, 49303.753481233085, 49322.75338623284, 49355.75322123438, 12503.437345319482, 14344.641383965485, 14500.274986250644, 14523.346449409803, 14531.055481393676, 10135.290529663, 11817.645470635724, 11927.343996080299, 11954.820677689842, 11992.880071199352, 13354.198748075147, 13366.599002029954, 13501.729965400637, 13556.525521606696, 13631.863681363258, 5453.182022696627, 5536.944630553643, 5581.0, 5600.915986260209, 5623.746931388097, 1008.7276435362411, 1026.0560284538242, 1028.4600584692946, 1033.7674023344687, 1037.64719995202, 22092.23262139025, 22240.110395584317, 22772.31683049511, 23175.652365214537, 23325.416864578183, 1112.816385296417, 1117.284937639911, 1118.8713297970837, 1122.4668282565715, 1122.680036189682, 12025.518979240733, 12138.939305303593, 12250.509979600716, 12267.509299627915, 12329.321887296106, 225981.0, 226610.0, 227565.0, 227588.0, 227589.0, 48410.75794621004, 48988.510114898396, 49044.75477622589, 49089.509104908495, 49203.26195107076, 1416.0795482936173, 1426.2227086238024, 1452.4262916148061, 1456.0899437851342, 1465.5163795947526, 21814.672779907996, 22146.557068858856, 22208.77791222067, 22353.105875764788, 22449.775502244767, 34276.48585271173, 35445.0, 35455.82272088623, 35488.0, 35494.0, 199557.0, 199719.0, 199877.0, 200134.0, 200497.0, 29002.0, 29549.409011820073, 29575.408491830472, 32213.677863221066, 32309.35381292408, 40097.0, 40771.38842917418, 40934.0, 40975.0, 41639.583604164785, 29901.700982989893, 30240.09279721612, 30450.39099217959, 30671.53992690156, 31389.372212556078, 29917.55123673103, 30002.549961750155, 30144.09567712972, 30428.695713042587, 31054.68945310518, 34220.31559368849, 34266.82866585651, 34416.48375274423, 34649.30701385908, 36139.63860361468, 31393.52909706402, 31547.684523154476, 31828.840855795574, 31831.522527161647, 32521.837390812896, 23296.417589560395, 23500.647490287316, 24208.273751787474, 24276.393090172885, 24304.392390190387], "value": 16702.32381427646, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [556.5603173492941, 564.7910273198914, 567.6934455394089, 571.4143003421495, 582.2750675409116, 21916.1233550658, 22354.552908941852, 22654.54690906181, 22674.0, 22697.0, 25294.0, 25729.22812315629, 25873.611895821577, 25878.96484140632, 25902.611460828055, 6524.151860258172, 6669.399754022137, 6672.59964402135, 6673.899891501631, 6680.66596670167, 6531.836704082412, 6736.730530778763, 7088.432925365984, 7100.325469080455, 7105.32499412555, 7069.328413800681, 7080.85838283234, 7083.574985500886, 7085.397741192005, 7087.220405755363, 7066.364027237548, 7069.929300706978, 7096.645167741593, 7120.0, 7148.785536433916, 6979.546329488567, 6989.860202795967, 7001.0, 7015.4036906862975, 7022.964885175541, 30505.23736906595, 31237.687623123704, 31991.52012719811, 32077.1980700482, 32151.678483215102, 2087.4155236533793, 2136.4872430616697, 2138.2088627207877, 2138.700581918533, 2139.176417079436, 20186.39440816778, 20887.8955605221, 20894.895525522275, 20932.5813483731, 20983.8950805245, 2431.7203521595065, 2573.3823882268307, 2593.6757905261843, 2611.2166350094894, 2640.537905866485, 138709.30645346708, 142264.57735422617, 142479.28760356133, 142528.0, 142586.28706856398, 20194.596108077756, 21038.684419733716, 21540.246091386725, 22096.668549971764, 22136.557268854533, 42126.0, 42151.36772948408, 42403.575964239964, 42442.36336454956, 42482.78758606249, 10968.341899486057, 11073.778524429466, 11112.277701949348, 11234.3259404436, 11306.773864522664, 7010.439164866799, 9133.771655708528, 9246.4914429707, 9284.071592840797, 9361.157495825408, 9816.411015339103, 11195.2723073, 11285.379304138187, 11525.82711259315, 11704.063674905987, 3622.4385220290897, 3790.8673196437994, 3805.904852378713, 4106.0, 4180.519240287406, 944.8582712593029, 957.8850537935492, 971.7424882406168, 977.1010670183448, 989.2086330935223, 18986.240550378105, 19208.80791192098, 19416.320428784926, 19536.339411149926, 19801.20795168206, 1017.5014243020867, 1030.0420608833706, 1036.2694300518117, 1042.8852826189113, 1045.1429827541494, 10665.84001239982, 12771.61685149447, 13988.860111398755, 14008.299585020708, 14111.0, 504029.0, 505223.0, 520575.0, 530912.0, 534732.0, 117639.0, 118802.0, 120553.0, 124157.0, 124548.37725811494, 1200.6758175292623, 1201.6635342104225, 1209.3953023488257, 1218.3968935377045, 1230.6308107567872, 20715.68926466074, 20861.0, 20918.47703807417, 21485.14059437605, 21915.890420547796, 62470.0, 62783.0, 63371.0, 64400.0, 64787.67606162128, 77705.2229477698, 78095.0, 79099.0, 80001.59999199967, 80893.0, 16524.173791310146, 17139.143042848056, 17823.0, 20950.0, 22028.88985555062, 23262.0, 23280.76719232786, 23435.296941091794, 23536.176233831735, 23564.528709426057, 17437.912810435868, 18276.81723182751, 18423.53941151482, 18458.169382377557, 18469.9076504622, 17196.914015430342, 17394.217260223573, 17507.82492175062, 17844.375446859296, 18017.099145042434, 18258.72611910849, 18499.260029598667, 18597.90701046486, 18644.720329195345, 18768.343107991153, 20584.58830823405, 20645.52253729562, 20709.378718638465, 20784.1686332545, 20796.68804967957, 17701.911490442464, 17935.551611209823, 18154.001529915455, 18166.72749908779, 18513.166907488925], "value": 13189.64020098186, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1292.7931530955045, 1336.4921329894642, 1339.879410853023, 1362.8432730236025, 1363.7068030373473, 15055.849441505581, 15632.531024069282, 15650.295736691833, 15654.608634784136, 15752.606184845385, 15550.84449155508, 15864.762028569581, 15885.84114158858, 15889.0, 15894.0, 2801.341684704098, 2868.569714542825, 2874.583185438121, 2875.7986940914143, 2876.1084063940248, 2531.190019193859, 2718.5242582547976, 2784.0812531864535, 2804.5372513535244, 2810.3676672748675, 2795.55271156615, 2841.8863245470156, 2842.6588809342807, 2842.729940655645, 2846.544552871541, 2687.556553168725, 2696.36635390683, 2724.278066312429, 2737.575675770259, 2741.7943654226015, 2627.671541057368, 2634.9473010539873, 2639.7624213820654, 2643.8810253538636, 2649.0198626508122, 15172.772408413883, 15506.069635821887, 15524.534263971986, 15534.378624854991, 15592.142432166227, 8107.716229931924, 8352.331813454908, 8384.413091083627, 8386.077531471534, 8394.286485648727, 5224.615476898625, 5426.593005524602, 5563.276774019392, 5589.161625756169, 5590.021746194399, 1964.6856502959458, 1966.488712934633, 1985.1364656374378, 2030.4111807575784, 2050.3541384463847, 26298.0, 26468.999999999807, 26469.470610587872, 26483.602745958633, 26517.3370665733, 6647.368499992493, 6681.432078273401, 6800.183977922582, 6805.353491418307, 6988.475864310095, 17441.04074275927, 17450.0, 17485.21316540733, 17488.912555437397, 17497.0, 7958.602069896482, 7968.243016913384, 7972.960135199403, 7988.680452781824, 8024.2376974187355, 7056.858862822714, 7249.891251631229, 7325.413966882743, 7337.779866604011, 7350.228226036296, 5020.071286811952, 5263.736813159327, 5280.181571856291, 5289.497497737709, 5342.839714808562, 1383.4742797736997, 1426.3367534096694, 1453.302414840861, 1505.7816616590533, 1511.6523199664139, 919.4621146629297, 926.7219834049758, 929.6699671616614, 937.7514958535993, 939.056248470283, 7567.091948966049, 7783.610819459004, 7880.369570434352, 7924.920750792534, 7980.84038319242, 965.6427121964917, 984.4487087231173, 996.7259003773875, 999.4552968632113, 1029.2383636109375, 4690.390249267573, 4782.856514304576, 4855.490173531728, 4890.828820991248, 4899.338589290472, 21221.15115395367, 21540.353789386343, 21612.89193554022, 22173.113075476802, 22252.777472225393, 20994.0, 21723.34829955104, 21765.891170544364, 21773.564528709336, 21854.344369668936, 10603.893961060445, 11046.668599942015, 11127.387993660426, 11156.442177891073, 11205.0, 8665.480071195749, 8700.695475658322, 8741.125887411208, 8757.693480728143, 8795.340349473687, 17782.733259001383, 18223.81776182221, 18430.0, 18475.983820889982, 18523.0, 18581.535461613566, 18876.905615471835, 19070.61858762789, 19150.52123696863, 19309.324173653855, 12507.624771256877, 12560.246385216917, 12646.367681616066, 12750.171238869443, 12792.6801829955, 13949.651258718613, 13975.650608734863, 13986.44054237819, 14030.719385612434, 14034.578962631138, 11213.215074945001, 11229.157813163882, 11315.547378105113, 11326.433678315887, 11431.942840285745, 11300.547978080785, 11609.129315301218, 11702.2978621283, 11707.531698731955, 11880.76238475243, 9356.391834531007, 9530.523473826419, 9530.95234523823, 9535.141837234682, 9539.904600953902, 6912.758053468105, 6929.376356127973, 6943.270956549692, 6970.616616086164, 7027.22700502934, 6881.933300338455, 6957.965210173917, 6974.511784175059, 6993.370596646327, 7019.543729657556], "value": 6718.449940036198, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [501.98557661244223, 502.7721340266537, 515.680278227499, 517.7566543724449, 520.5367223171378, 6285.402886725765, 6292.276388215351, 6331.461825744812, 6371.968140159304, 6394.793796140336, 6094.847628809271, 6177.907331390033, 6219.414049417393, 6233.750649973996, 6338.112664227, 4992.201247800353, 5087.135187018197, 5099.872503187413, 5117.181250999841, 5180.740962951856, 5038.093143234217, 5044.419891712445, 5059.620528460362, 5060.266261392096, 5100.438951715327, 4785.66500344976, 4932.605391568683, 5041.445441001487, 5052.115879721053, 5093.312402825608, 4980.178270585349, 5017.774200161001, 5029.245613158019, 5063.8480845574695, 5122.3853137623355, 4495.190865644183, 4503.211937910853, 4511.751853648048, 4528.25283828168, 4540.636749060067, 6168.0, 6183.6598987055695, 6195.473384762301, 6229.6573688447115, 6244.937550624481, 5724.169995350692, 5762.193292938992, 5771.297467247172, 5836.328822185461, 5849.736761845727, 6457.192850893638, 6544.607323560602, 6549.836254093638, 6623.801285961381, 6625.289837690909, 4915.451632735712, 4920.75396230187, 4931.211006238985, 4943.0, 4977.626677999165, 6990.650467476657, 7033.366996970246, 7039.430607828157, 7053.647317634201, 7082.964585177041, 5006.974965125224, 5014.07239660664, 5043.915558154941, 5090.618203634742, 5107.7446127693465, 5133.589312854962, 5140.228965655107, 5142.528793064666, 5165.66423182492, 5195.376554813447, 5789.247397838339, 5825.931794212994, 5844.970775146097, 5846.736896839652, 5855.560832937546, 6609.3060228675295, 6613.404793568602, 6653.63405012729, 6671.166104236971, 6721.060020884646, 1635.1660653067022, 1665.1840598107083, 1714.7342161964916, 1749.5013921032698, 1770.7609472721276, 1182.5920057580122, 1212.1878341511208, 1232.642533665245, 1238.7393007760693, 1243.521244320943, 5852.736626851802, 5852.853678658068, 5873.383294754075, 5874.706264686749, 6004.3395226525045, 6575.769848055295, 6778.118844550177, 6793.524453288322, 6841.589504629738, 6884.896726549106, 5960.940390596125, 5967.8209653710455, 5987.08413307746, 6027.367126451747, 6030.155778191057, 3569.964300356963, 3624.0611272968836, 3632.7093832493338, 3633.3278343506536, 3656.5612126544984, 6623.383763413424, 6690.565113267622, 6745.258021617717, 6767.864642707118, 6772.424343930723, 7101.644917754092, 7115.612455571114, 7120.465965052642, 7137.89293160603, 7189.0, 6628.83427914306, 6714.764983225564, 6716.265033151714, 6738.35985581369, 6752.291009444036, 6882.552634078769, 6911.067005954132, 6945.930540694529, 6999.860002800017, 7014.473914456538, 6778.661066946732, 6795.626240556817, 6838.487113466609, 6884.51808373409, 6923.9653801730665, 6678.2320033194865, 6699.162604674416, 6711.4966377520905, 6755.527113102035, 6765.42493888025, 4964.975175124101, 4968.627352948471, 5005.624578156726, 5046.116929537389, 5051.4443411224, 6900.447964162955, 6925.515213934977, 6949.409300209539, 6952.791416257521, 7000.299970002959, 6595.054458934722, 6630.5027122965, 6685.364890335411, 6718.2609912908565, 6734.528582999339, 6543.443807276244, 6582.308857570078, 6644.169478815148, 6664.300248474035, 6669.0, 6360.745570177141, 6385.457236134795, 6570.441512471493, 6588.0, 6593.0, 6633.270340262471, 6660.23696611413, 6684.997250412477, 6689.431398331197, 6757.763478278237, 6379.649119298298, 6383.904241436289, 6415.486761059011, 6453.45145662624, 6519.315471875385], "value": 5138.897241657131, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [896.8834051573294, 904.9049849765776, 911.2345629671075, 915.9924083508138, 921.31822451386, 19060.332888348894, 19980.0, 20041.59916801663, 20098.095585698662, 20618.278360257395, 20180.49548761278, 20665.586688266227, 20842.270520531783, 20932.58134837306, 20959.161633534677, 959.7216807125925, 972.0765272990652, 980.289290264556, 980.2892902645597, 981.8772653418323, 945.6028468043438, 984.0208992052917, 989.915857152143, 990.8910019897842, 991.1377101921339, 1031.6956497833119, 1032.1639472027682, 1032.334144476812, 1032.5972870580442, 1034.896510348963, 1018.2261481274248, 1020.4897551224387, 1020.5764607687801, 1020.9795804083914, 1021.3514418344331, 755.4296506137886, 755.6410704915177, 755.7166062726477, 756.092688773474, 756.2588663110183, 17828.3760068397, 18362.816371836245, 19752.11115499806, 19864.106115224706, 19925.9003704982, 21149.15403383863, 21434.356969290948, 21690.0, 21760.02079906408, 21845.0, 17019.40432084871, 17210.397636082802, 17343.392981245597, 17349.39277125307, 17356.47930562085, 3699.0197597636648, 3719.776813391205, 3727.5154229950203, 3728.1984373359587, 3728.608496107897, 129377.35311323477, 130199.0, 131546.0, 132362.3381883094, 134804.0, 16072.598185045234, 16194.190290485429, 16561.42035028768, 16587.83412165863, 16615.66768664644, 28323.0, 28883.855580721964, 28957.56563651547, 29320.853395732884, 29444.411111777645, 7110.680019399139, 7487.737929172562, 8154.714584989497, 8328.042275138316, 8372.413931024892, 4112.485939257593, 4182.330827067654, 4195.622393984556, 4206.894827629334, 4218.388333691598, 5171.68969861802, 5233.215017747369, 5310.787568497217, 5319.494648008433, 5329.946700532945, 1076.359566058194, 1081.4214395298477, 1107.4850194659523, 1110.3726394587195, 1111.1610733895857, 669.4610838275138, 678.7929681447107, 685.6331862453663, 686.543448606671, 695.0234919937496, 10400.0, 10700.892991070144, 11065.114790816715, 11455.255408398427, 11981.580644677395, 608.330836080317, 609.1411110334407, 624.2321944008894, 624.4723208888573, 643.5141468191486, 5152.227165925066, 5224.346956630421, 5237.502437268454, 5249.842504724865, 5274.815381461707, 105908.0, 108190.0, 109445.0, 110227.0, 110521.44739276252, 97034.0, 97880.0, 98057.0, 99767.0, 103528.48235758924, 93874.53062734642, 94424.52787736154, 94909.52545237368, 95241.0, 96648.51675741577, 11540.192186547198, 11743.17797754149, 11769.646910592695, 11772.0, 11945.522179112739, 40318.39522407108, 40848.59151408448, 41119.38320925248, 43208.35187472127, 43245.13509729725, 53311.667208320105, 53629.7318513405, 53944.73027634837, 54147.729261355016, 71280.64359678168, 18348.541286467946, 18439.0, 18450.723239151153, 18502.167402466654, 18643.347482838035, 22097.005634746154, 22269.665955011013, 22940.770592293862, 23275.88362058179, 23386.415339616644, 15993.680126397174, 16543.669126617642, 16759.32962681479, 16872.493825185265, 17904.820951790836, 18138.274469021093, 18408.81591184071, 18699.626007480045, 18726.15732292023, 18803.0, 16433.342666293214, 16450.34198632089, 16778.32886684519, 17596.208170632097, 18056.72914906251, 12685.429155687832, 13072.281024543361, 13191.142575732547, 13260.602181934557, 13283.66790830237, 12485.500579977062, 12771.552995645108, 12855.742885142057, 13096.934515327363, 13112.08215424911], "value": 9022.631047205436, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1488.6000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 108.0, 102.0, 107.0, 106.0, 109.0, 110.0, 99.0, 110.0, 109.0], "value": 105.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 115.0, 155.0, 181.0, 117.0, 141.0, 146.0, 126.0, 170.0, 118.0], "value": 141.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 162.0, 162.0, 159.0, 161.0, 164.0, 163.0, 164.0, 164.0, 161.0], "value": 162.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 74.0, 75.0, 190.0, 74.0, 74.0, 73.0, 73.0, 149.0, 76.0], "value": 93.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 79.0, 128.0, 80.0, 79.0, 79.0, 130.0, 79.0, 80.0, 137.0], "value": 94.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 115.0, 115.0, 114.0, 114.0, 114.0, 114.0, 114.0, 114.0, 114.0], "value": 114.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 148.0, 149.0, 148.0, 148.0, 148.0, 147.0, 149.0, 147.0, 147.0], "value": 148.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 94.0, 95.0, 94.0, 93.0, 94.0, 94.0, 94.0, 94.0, 93.0], "value": 93.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 54.0, 54.0, 55.0, 58.0, 56.0, 54.0, 54.0, 53.0, 55.0], "value": 54.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [39.0, 39.0, 38.0, 38.0, 38.0, 39.0, 39.0, 39.0, 39.0, 38.0], "value": 38.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 74.0, 77.0, 77.0, 74.0, 75.0, 73.0, 74.0, 80.0, 74.0], "value": 75.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [124.0, 123.0, 119.0, 125.0, 117.0, 123.0, 117.0, 119.0, 131.0, 161.0], "value": 125.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [177.0, 186.0, 181.0, 169.0, 171.0, 175.0, 171.0, 168.0, 168.0, 169.0], "value": 173.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 68.0, 72.0, 65.0, 65.0, 66.0, 65.0, 65.0, 74.0, 63.0], "value": 66.9, "unit": "score"}]}]} 09:24:35 INFO - Return code: 0 09:24:35 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:24:35 INFO - # TBPL SUCCESS # 09:24:35 INFO - Running post-action listener: _package_coverage_data 09:24:35 INFO - Running post-action listener: _resource_record_post_action 09:24:35 INFO - [mozharness: 2017-09-01 16:24:35.401379Z] Finished run-tests step (success) 09:24:35 INFO - Running post-run listener: _resource_record_post_run 09:24:35 INFO - Total resource usage - Wall time: 1191s; CPU: 13.0%; Read bytes: 4096; Write bytes: 405962752; Read time: 16; Write time: 222412 09:24:35 INFO - TinderboxPrint: CPU usage
13.1% 09:24:35 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 16 09:24:35 INFO - TinderboxPrint: I/O write bytes / time
405,962,752 / 222,412 09:24:35 INFO - TinderboxPrint: CPU idle
8,250.1 (86.9%) 09:24:35 INFO - TinderboxPrint: CPU user
1,193.5 (12.6%) 09:24:35 INFO - TinderboxPrint: Swap in / out
0 / 0 09:24:35 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 115322880; Read time: 0; Write time: 74372 09:24:35 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 09:24:35 INFO - run-tests - Wall time: 1175s; CPU: 13.0%; Read bytes: 4096; Write bytes: 282591232; Read time: 16; Write time: 147212 09:24:36 INFO - Running post-run listener: _upload_blobber_files 09:24:36 INFO - Blob upload gear active. 09:24:36 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:24:36 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 09:24:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:24:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:24:36 INFO - (blobuploader) - INFO - Open directory for files ... 09:24:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 09:24:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:24:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:24:37 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 09:24:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:24:37 INFO - (blobuploader) - INFO - Done attempting. 09:24:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 09:24:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:24:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:24:38 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 09:24:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:24:38 INFO - (blobuploader) - INFO - Done attempting. 09:24:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:24:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:24:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:24:42 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:24:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:24:42 INFO - (blobuploader) - INFO - Done attempting. 09:24:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 09:24:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:24:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:24:43 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 09:24:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:24:43 INFO - (blobuploader) - INFO - Done attempting. 09:24:43 INFO - (blobuploader) - INFO - Iteration through files over. 09:24:43 INFO - Return code: 0 09:24:43 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:24:43 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:24:43 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c43df74c807dac2643e89d9a255c97e197fb902c37e7723ad7101d99c7af34f0d0beef7b5957f5f5ed0c48606e515974214b2d7aba169de2164b5ef8634ddcf1", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6847217cd4ca61b5626ade95b8f4a25e9c24b547d3daf8a51c24ea07d9a4482cde3df40118b95437c420af44f65172bbf2ba476ff0a92dfc0cdcbebd9a265e00", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/51556d3acb80db4b08473cfb151fcf2d2074fad39314811e56cbd8959e8a4aa243a1b42ccb1fce955f651e683b96d35f01a526ddd057ca9320777c726c95297f", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0f58c27c3e185199be621f9c368f68c3c15b97a13e71692e14a4b0437b95ec44d87cee17321fddabbcfd0fedb843b74411466887e6d0fa9e74aa4635e4144506"} 09:24:43 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:24:43 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:24:43 INFO - Contents: 09:24:43 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c43df74c807dac2643e89d9a255c97e197fb902c37e7723ad7101d99c7af34f0d0beef7b5957f5f5ed0c48606e515974214b2d7aba169de2164b5ef8634ddcf1", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6847217cd4ca61b5626ade95b8f4a25e9c24b547d3daf8a51c24ea07d9a4482cde3df40118b95437c420af44f65172bbf2ba476ff0a92dfc0cdcbebd9a265e00", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/51556d3acb80db4b08473cfb151fcf2d2074fad39314811e56cbd8959e8a4aa243a1b42ccb1fce955f651e683b96d35f01a526ddd057ca9320777c726c95297f", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0f58c27c3e185199be621f9c368f68c3c15b97a13e71692e14a4b0437b95ec44d87cee17321fddabbcfd0fedb843b74411466887e6d0fa9e74aa4635e4144506"} 09:24:43 INFO - Running post-run listener: copy_logs_to_upload_dir 09:24:43 INFO - Copying logs to upload dir... 09:24:43 INFO - mkdir: /builds/slave/test/build/upload/logs 09:24:43 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1249.501736 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 49 secs) (at 2017-09-01 09:24:43.547230) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-01 09:24:43.550904) ========= 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=d2a8c569965ab8367867b893000001a1-1504281831.413524-429801474 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/c43df74c807dac2643e89d9a255c97e197fb902c37e7723ad7101d99c7af34f0d0beef7b5957f5f5ed0c48606e515974214b2d7aba169de2164b5ef8634ddcf1", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6847217cd4ca61b5626ade95b8f4a25e9c24b547d3daf8a51c24ea07d9a4482cde3df40118b95437c420af44f65172bbf2ba476ff0a92dfc0cdcbebd9a265e00", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/51556d3acb80db4b08473cfb151fcf2d2074fad39314811e56cbd8959e8a4aa243a1b42ccb1fce955f651e683b96d35f01a526ddd057ca9320777c726c95297f", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0f58c27c3e185199be621f9c368f68c3c15b97a13e71692e14a4b0437b95ec44d87cee17321fddabbcfd0fedb843b74411466887e6d0fa9e74aa4635e4144506"} build_url:https://queue.taskcluster.net/v1/task/P0kX4SRdS6ifTGg50JASWg/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011526 build_url: 'https://queue.taskcluster.net/v1/task/P0kX4SRdS6ifTGg50JASWg/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c43df74c807dac2643e89d9a255c97e197fb902c37e7723ad7101d99c7af34f0d0beef7b5957f5f5ed0c48606e515974214b2d7aba169de2164b5ef8634ddcf1", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6847217cd4ca61b5626ade95b8f4a25e9c24b547d3daf8a51c24ea07d9a4482cde3df40118b95437c420af44f65172bbf2ba476ff0a92dfc0cdcbebd9a265e00", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/51556d3acb80db4b08473cfb151fcf2d2074fad39314811e56cbd8959e8a4aa243a1b42ccb1fce955f651e683b96d35f01a526ddd057ca9320777c726c95297f", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0f58c27c3e185199be621f9c368f68c3c15b97a13e71692e14a4b0437b95ec44d87cee17321fddabbcfd0fedb843b74411466887e6d0fa9e74aa4635e4144506"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-01 09:24:43.578714) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-01 09:24:43.579046) ========= 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=d2a8c569965ab8367867b893000001a1-1504281831.413524-429801474 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004476 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-01 09:24:43.664669) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-01 09:24:43.665005) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-01 09:24:43.665324) ========= ========= Total master_lag: 0.19 =========