builder: mozilla-central_ubuntu64_hw_test-dromaeojs-stylo-disabled-e10s-pgo slave: talos-linux64-ix-045 starttime: 1504826658.78 results: success (0) revision: b4c1ad9565ee9d00d96501c4a83083daf25c1413 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-07 16:24:18.780045) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-07 16:24:18.780968) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-07 16:24:18.781319) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-07 16:24:18.799061) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-07 16:24:18.799412) ========= 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=4300f881736b422476174874000001bc-1504826657.945308-1168561671 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-07 16:24:18-- 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% 46.1M=0s 2017-09-07 16:24:18 (46.1 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.116223 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-07 16:24:18.933168) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-07 16:24:18.933539) ========= 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=4300f881736b422476174874000001bc-1504826657.945308-1168561671 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017937 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-07 16:24:18.983536) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-09-07 16:24:18.983934) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev b4c1ad9565ee9d00d96501c4a83083daf25c1413 --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 b4c1ad9565ee9d00d96501c4a83083daf25c1413 --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=4300f881736b422476174874000001bc-1504826657.945308-1168561671 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-07 16:24:19,047 truncating revision to first 12 chars 2017-09-07 16:24:19,047 Setting DEBUG logging. 2017-09-07 16:24:19,047 attempt 1/10 2017-09-07 16:24:19,047 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/b4c1ad9565ee?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-07 16:24:20,143 unpacking tar archive at: mozilla-central-b4c1ad9565ee/testing/mozharness/ program finished with exit code 0 elapsedTime=1.373853 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-09-07 16:24:20.375366) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-07 16:24:20.375740) ========= script_repo_revision: b4c1ad9565ee9d00d96501c4a83083daf25c1413 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-07 16:24:20.376174) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-07 16:24:20.376477) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-07 16:24:20.391730) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 45 secs) (at 2017-09-07 16:24:20.392423) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-stylo-disabled-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-stylo-disabled-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=4300f881736b422476174874000001bc-1504826657.945308-1168561671 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 16:24:20 INFO - MultiFileLogger online at 20170907 16:24:20 in /builds/slave/test 16:24:20 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-stylo-disabled-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 16:24:20 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:24:20 INFO - {'append_to_log': False, 16:24:20 INFO - 'base_work_dir': '/builds/slave/test', 16:24:20 INFO - 'blob_upload_branch': 'Firefox', 16:24:20 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:24:20 INFO - 'branch': 'Firefox', 16:24:20 INFO - 'buildbot_json_path': 'buildprops.json', 16:24:20 INFO - 'code_coverage': False, 16:24:20 INFO - 'config_files': ('talos/linux_config.py',), 16:24:20 INFO - 'default_actions': ('clobber', 16:24:20 INFO - 'read-buildbot-config', 16:24:20 INFO - 'download-and-extract', 16:24:20 INFO - 'populate-webroot', 16:24:20 INFO - 'create-virtualenv', 16:24:20 INFO - 'install', 16:24:20 INFO - 'setup-mitmproxy', 16:24:20 INFO - 'run-tests'), 16:24:20 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:24:20 INFO - 'disable_ccov_upload': False, 16:24:20 INFO - 'disable_stylo': False, 16:24:20 INFO - 'download_minidump_stackwalk': True, 16:24:20 INFO - 'download_symbols': 'ondemand', 16:24:20 INFO - 'enable_stylo': False, 16:24:20 INFO - 'enable_webrender': False, 16:24:20 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 16:24:20 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:24:20 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:24:20 INFO - 'gecko_profile': False, 16:24:20 INFO - 'gecko_profile_interval': 0, 16:24:20 INFO - 'installer_path': 'installer.exe', 16:24:20 INFO - 'log_level': 'info', 16:24:20 INFO - 'log_name': 'talos', 16:24:20 INFO - 'log_to_console': True, 16:24:20 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 16:24:20 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 16:24:20 INFO - 'opt_config_files': (), 16:24:20 INFO - 'pip_index': False, 16:24:20 INFO - 'suite': 'dromaeojs-stylo-disabled-e10s', 16:24:20 INFO - 'system_bits': '32', 16:24:20 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:24:20 INFO - 'title': 'talos-linux64-ix-045', 16:24:20 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:24:20 INFO - 'use_talos_json': True, 16:24:20 INFO - 'verify': 'False', 16:24:20 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:24:20 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:24:20 INFO - 'work_dir': 'build'} 16:24:20 INFO - [mozharness: 2017-09-07 23:24:20.602370Z] Running clobber step. 16:24:20 INFO - Running pre-action listener: _resource_record_pre_action 16:24:20 INFO - Running main action method: clobber 16:24:20 INFO - rmtree: /builds/slave/test/build 16:24:20 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:24:22 INFO - Running post-action listener: _resource_record_post_action 16:24:22 INFO - [mozharness: 2017-09-07 23:24:22.191246Z] Finished clobber step (success) 16:24:22 INFO - [mozharness: 2017-09-07 23:24:22.191344Z] Running read-buildbot-config step. 16:24:22 INFO - Running pre-action listener: _resource_record_pre_action 16:24:22 INFO - Running main action method: read_buildbot_config 16:24:22 INFO - Using buildbot properties: 16:24:22 INFO - { 16:24:22 INFO - "project": "", 16:24:22 INFO - "product": "firefox", 16:24:22 INFO - "who": "archaeopteryx@coole-files.de", 16:24:22 INFO - "installer_path": "public/build/target.tar.bz2", 16:24:22 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-stylo-disabled-e10s", 16:24:22 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 16:24:22 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs-stylo-disabled-e10s", 16:24:22 INFO - "stage_platform": "linux64-pgo", 16:24:22 INFO - "basedir": "/builds/slave/test", 16:24:22 INFO - "buildnumber": 5, 16:24:22 INFO - "platform": "ubuntu64_hw", 16:24:22 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 16:24:22 INFO - "slavebuilddir": "test", 16:24:22 INFO - "taskId": "X5AoiGwGTYaI7Yk9yUo70w", 16:24:22 INFO - "branch": "mozilla-central", 16:24:22 INFO - "script_repo_revision": "production", 16:24:22 INFO - "revision": "b4c1ad9565ee9d00d96501c4a83083daf25c1413", 16:24:22 INFO - "slavename": "talos-linux64-ix-045", 16:24:22 INFO - "repo_path": "mozilla-central" 16:24:22 INFO - } 16:24:22 INFO - Finding installer, test and symbols from parent task. 16:24:22 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/X5AoiGwGTYaI7Yk9yUo70w'}, attempt #1 16:24:22 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/X5AoiGwGTYaI7Yk9yUo70w'}, attempt #1 16:24:23 INFO - Task dependencies: Jlx0Q_90SDmX-HVjCVREGw 16:24:23 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Jlx0Q_90SDmX-HVjCVREGw'}, attempt #1 16:24:23 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/Jlx0Q_90SDmX-HVjCVREGw/artifacts/public/build/target.tar.bz2 16:24:23 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/Jlx0Q_90SDmX-HVjCVREGw/artifacts/public/build/target.test_packages.json 16:24:23 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/Jlx0Q_90SDmX-HVjCVREGw/artifacts/public/build/target.crashreporter-symbols.zip 16:24:23 INFO - Running post-action listener: _resource_record_post_action 16:24:23 INFO - [mozharness: 2017-09-07 23:24:23.571053Z] Finished read-buildbot-config step (success) 16:24:23 INFO - [mozharness: 2017-09-07 23:24:23.571271Z] Running download-and-extract step. 16:24:23 INFO - Running pre-action listener: _resource_record_pre_action 16:24:23 INFO - Running main action method: download_and_extract 16:24:23 INFO - mkdir: /builds/slave/test/build/tests 16:24: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')]} 16:24:23 INFO - trying https://queue.taskcluster.net/v1/task/Jlx0Q_90SDmX-HVjCVREGw/artifacts/public/build/target.test_packages.json 16:24:23 INFO - Downloading https://queue.taskcluster.net/v1/task/Jlx0Q_90SDmX-HVjCVREGw/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 16:24:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Jlx0Q_90SDmX-HVjCVREGw/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 16:24:24 INFO - Downloaded 989 bytes. 16:24:24 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 16:24:24 INFO - Using the following test package requirements: 16:24:24 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 16:24:24 INFO - u'common': [u'target.common.tests.zip'], 16:24:24 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 16:24:24 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 16:24:24 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 16:24:24 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 16:24:24 INFO - u'mozbase': [u'target.common.tests.zip'], 16:24:24 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 16:24:24 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 16:24:24 INFO - u'web-platform': [u'target.common.tests.zip', 16:24:24 INFO - u'target.web-platform.tests.tar.gz'], 16:24:24 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 16:24:24 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 16:24:24 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Jlx0Q_90SDmX-HVjCVREGw/artifacts/public/build/target.common.tests.zip 16:24:24 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Jlx0Q_90SDmX-HVjCVREGw/artifacts/public/build/target.common.tests.zip'}, attempt #1 16:24:24 INFO - Fetch https://queue.taskcluster.net/v1/task/Jlx0Q_90SDmX-HVjCVREGw/artifacts/public/build/target.common.tests.zip into memory 16:24:25 INFO - Content-Length response header: 55689823 16:24:25 INFO - Bytes received: 55689823 16:24:36 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 16:24:36 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Jlx0Q_90SDmX-HVjCVREGw/artifacts/public/build/target.common.tests.zip 16:24:36 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Jlx0Q_90SDmX-HVjCVREGw/artifacts/public/build/target.common.tests.zip'}, attempt #1 16:24:36 INFO - Fetch https://queue.taskcluster.net/v1/task/Jlx0Q_90SDmX-HVjCVREGw/artifacts/public/build/target.common.tests.zip into memory 16:24:37 INFO - Content-Length response header: 55689823 16:24:37 INFO - Bytes received: 55689823 16:24:49 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Jlx0Q_90SDmX-HVjCVREGw/artifacts/public/build/target.talos.tests.zip 16:24:49 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Jlx0Q_90SDmX-HVjCVREGw/artifacts/public/build/target.talos.tests.zip'}, attempt #1 16:24:49 INFO - Fetch https://queue.taskcluster.net/v1/task/Jlx0Q_90SDmX-HVjCVREGw/artifacts/public/build/target.talos.tests.zip into memory 16:24:50 INFO - Content-Length response header: 14496665 16:24:50 INFO - Bytes received: 14496665 16:24:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:24:50 INFO - trying https://queue.taskcluster.net/v1/task/Jlx0Q_90SDmX-HVjCVREGw/artifacts/public/build/target.tar.bz2 16:24:50 INFO - Downloading https://queue.taskcluster.net/v1/task/Jlx0Q_90SDmX-HVjCVREGw/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 16:24:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Jlx0Q_90SDmX-HVjCVREGw/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 16:24:51 INFO - Downloaded 63416205 bytes. 16:24:51 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Jlx0Q_90SDmX-HVjCVREGw/artifacts/public/build/target.tar.bz2 16:24:51 INFO - mkdir: /builds/slave/test/properties 16:24:51 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:24:51 INFO - Writing to file /builds/slave/test/properties/build_url 16:24:51 INFO - Contents: 16:24:51 INFO - build_url:https://queue.taskcluster.net/v1/task/Jlx0Q_90SDmX-HVjCVREGw/artifacts/public/build/target.tar.bz2 16:24:51 INFO - Running post-action listener: _resource_record_post_action 16:24:51 INFO - Running post-action listener: find_tests_for_verification 16:24:51 INFO - Running post-action listener: set_extra_try_arguments 16:24:51 INFO - [mozharness: 2017-09-07 23:24:51.887029Z] Finished download-and-extract step (success) 16:24:51 INFO - [mozharness: 2017-09-07 23:24:51.887128Z] Running populate-webroot step. 16:24:51 INFO - Running pre-action listener: _resource_record_pre_action 16:24:51 INFO - Running main action method: populate_webroot 16:24:51 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:24:51 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 16:24:51 INFO - 'tests': ['tresize', 16:24:51 INFO - 'tcanvasmark']}, 16:24:51 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:24:51 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 16:24:51 INFO - 'tests': ['dromaeo_css', 16:24:51 INFO - 'kraken']}, 16:24:51 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 16:24:51 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:24:51 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 16:24:51 INFO - 'talos_options': ['--disable-stylo'], 16:24:51 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:24:51 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 16:24:51 INFO - 'tests': ['damp', 'tps']}, 16:24:51 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 16:24:51 INFO - 'talos_options': ['--disable-stylo'], 16:24:51 INFO - 'tests': ['damp', 'tps']}, 16:24:51 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:24:51 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 16:24:51 INFO - 'tests': ['dromaeo_dom']}, 16:24:51 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 16:24:51 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 16:24:51 INFO - 'tests': ['basic_compositor_video', 16:24:51 INFO - 'glvideo']}, 16:24:51 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 16:24:51 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 16:24:51 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 16:24:51 INFO - 'talos_options': ['--disable-stylo'], 16:24:51 INFO - 'tests': ['ts_paint_webext', 16:24:51 INFO - 'tp5o_webext']}, 16:24:51 INFO - 'other-e10s': {'tests': ['a11yr', 16:24:51 INFO - 'ts_paint', 16:24:51 INFO - 'tpaint', 16:24:51 INFO - 'sessionrestore', 16:24:51 INFO - 'sessionrestore_many_windows', 16:24:51 INFO - 'sessionrestore_no_auto_restore', 16:24:51 INFO - 'tabpaint']}, 16:24:51 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 16:24:51 INFO - 'tests': ['a11yr', 16:24:51 INFO - 'ts_paint', 16:24:51 INFO - 'tpaint', 16:24:51 INFO - 'sessionrestore', 16:24:51 INFO - 'sessionrestore_many_windows', 16:24:51 INFO - 'sessionrestore_no_auto_restore', 16:24:51 INFO - 'tabpaint']}, 16:24:51 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 16:24:51 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 16:24:51 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 16:24:51 INFO - 'tests': ['bloom_basic_singleton']}, 16:24:51 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 16:24:51 INFO - 'tests': ['bloom_basic']}, 16:24:51 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:24:51 INFO - 'tsvgr_opacity', 16:24:51 INFO - 'tart', 16:24:51 INFO - 'tscrollx', 16:24:51 INFO - 'tsvg_static']}, 16:24:51 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 16:24:51 INFO - 'tests': ['tsvgx', 16:24:51 INFO - 'tsvgr_opacity', 16:24:51 INFO - 'tart', 16:24:51 INFO - 'tscrollx', 16:24:51 INFO - 'tsvg_static']}, 16:24:51 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 16:24:51 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 16:24:51 INFO - 'talos_options': ['--disable-stylo'], 16:24:51 INFO - 'tests': ['tp5o']}, 16:24:51 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 16:24:51 INFO - 'talos_options': ['--mitmproxy', 16:24:51 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 16:24:51 INFO - '--firstNonBlankPaint'], 16:24:51 INFO - 'tests': ['tp6_google', 16:24:51 INFO - 'tp6_youtube', 16:24:51 INFO - 'tp6_amazon', 16:24:51 INFO - 'tp6_facebook']}, 16:24:51 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 16:24:51 INFO - 'talos_options': ['--disable-stylo', 16:24:51 INFO - '--mitmproxy', 16:24:51 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 16:24:51 INFO - '--firstNonBlankPaint'], 16:24:51 INFO - 'tests': ['tp6_google', 16:24:51 INFO - 'tp6_youtube', 16:24:51 INFO - 'tp6_amazon', 16:24:51 INFO - 'tp6_facebook']}, 16:24:51 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 16:24:51 INFO - 'talos_options': ['--stylo-threads=1', 16:24:51 INFO - '--mitmproxy', 16:24:51 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 16:24:51 INFO - '--firstNonBlankPaint'], 16:24:51 INFO - 'tests': ['tp6_google', 16:24:51 INFO - 'tp6_youtube', 16:24:51 INFO - 'tp6_amazon', 16:24:51 INFO - 'tp6_facebook']}, 16:24:51 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 16:24:51 INFO - 'talos_options': ['--xperf_path', 16:24:51 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:24:51 INFO - 'tests': ['tp5n']}, 16:24:51 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 16:24:51 INFO - 'talos_options': ['--disable-stylo', 16:24:51 INFO - '--xperf_path', 16:24:51 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:24:51 INFO - 'tests': ['tp5n']}}} 16:24:51 INFO - Running post-action listener: _resource_record_post_action 16:24:51 INFO - [mozharness: 2017-09-07 23:24:51.902359Z] Finished populate-webroot step (success) 16:24:51 INFO - [mozharness: 2017-09-07 23:24:51.902449Z] Running create-virtualenv step. 16:24:51 INFO - Running pre-action listener: _resource_record_pre_action 16:24:51 INFO - Running main action method: create_virtualenv 16:24:51 INFO - Creating virtualenv /builds/slave/test/build/venv 16:24:51 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 16:24:51 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 16:24:51 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 16:24:51 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:24:51 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:24:52 INFO - Installing distribute.............................................................................................................................................................................................done. 16:24:52 INFO - Installing pip...............done. 16:24:52 INFO - Return code: 0 16:24:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 16:24:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 16:24:52 INFO - Reading from file tmpfile_stdout 16:24:52 INFO - Output received: 16:24:52 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 16:24:52 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:24:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:24:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:24:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:24:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:24:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cf51f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ebea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2061a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2065090>, '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 0x2065d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x205ff90>, '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': '4300f881736b422476174874000001bc-1504826657.945308-1168561671', '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 16:24:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 16:24:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 16:24:52 INFO - Using env: {'DISPLAY': ':0', 16:24:52 INFO - 'HOME': '/home/cltbld', 16:24:52 INFO - 'LANG': 'en_US.UTF-8', 16:24:52 INFO - 'LANGUAGE': 'en_US:en', 16:24:52 INFO - 'LOGNAME': 'cltbld', 16:24:52 INFO - 'MAIL': '/var/mail/cltbld', 16:24:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:24:52 INFO - 'MOZ_NO_REMOTE': '1', 16:24:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:24:52 INFO - 'NO_EM_RESTART': '1', 16:24:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:24:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:24:52 INFO - 'PWD': '/builds/slave/test', 16:24:52 INFO - 'SHELL': '/bin/bash', 16:24:52 INFO - 'SHLVL': '1', 16:24:52 INFO - 'TERM': 'linux', 16:24:52 INFO - 'TMOUT': '86400', 16:24:52 INFO - 'USER': 'cltbld', 16:24:52 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1504826657.945308-1168561671', 16:24:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:24:52 INFO - '_': '/tools/buildbot/bin/python'} 16:24:54 INFO - Ignoring indexes: http://pypi.python.org/simple/ 16:24:54 INFO - Downloading/unpacking pip>=1.5 16:24:54 INFO - Running setup.py egg_info for package pip 16:24:54 INFO - warning: no files found matching 'pip/cacert.pem' 16:24:54 INFO - warning: no files found matching '*.html' under directory 'docs' 16:24:54 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 16:24:54 INFO - no previously-included directories found matching 'docs/_build/_sources' 16:24:54 INFO - Installing collected packages: pip 16:24:54 INFO - Found existing installation: pip 1.1 16:24:54 INFO - Uninstalling pip: 16:24:54 INFO - Successfully uninstalled pip 16:24:54 INFO - Running setup.py install for pip 16:24:54 INFO - warning: no files found matching 'pip/cacert.pem' 16:24:54 INFO - warning: no files found matching '*.html' under directory 'docs' 16:24:54 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 16:24:54 INFO - no previously-included directories found matching 'docs/_build/_sources' 16:24:54 INFO - Installing pip script to /builds/slave/test/build/venv/bin 16:24:54 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 16:24:54 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 16:24:54 INFO - Successfully installed pip 16:24:54 INFO - Cleaning up... 16:24:54 INFO - Return code: 0 16:24:54 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:24:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:24:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:24:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:24:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:24:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1cf51f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ebea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2061a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2065090>, '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 0x2065d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x205ff90>, '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': '4300f881736b422476174874000001bc-1504826657.945308-1168561671', '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 16:24:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 16:24:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 16:24:54 INFO - Using env: {'DISPLAY': ':0', 16:24:54 INFO - 'HOME': '/home/cltbld', 16:24:54 INFO - 'LANG': 'en_US.UTF-8', 16:24:54 INFO - 'LANGUAGE': 'en_US:en', 16:24:54 INFO - 'LOGNAME': 'cltbld', 16:24:54 INFO - 'MAIL': '/var/mail/cltbld', 16:24:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:24:54 INFO - 'MOZ_NO_REMOTE': '1', 16:24:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:24:54 INFO - 'NO_EM_RESTART': '1', 16:24:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:24:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:24:54 INFO - 'PWD': '/builds/slave/test', 16:24:54 INFO - 'SHELL': '/bin/bash', 16:24:54 INFO - 'SHLVL': '1', 16:24:54 INFO - 'TERM': 'linux', 16:24:54 INFO - 'TMOUT': '86400', 16:24:54 INFO - 'USER': 'cltbld', 16:24:54 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1504826657.945308-1168561671', 16:24:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:24:54 INFO - '_': '/tools/buildbot/bin/python'} 16:24:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:24:54 INFO - Downloading/unpacking psutil>=3.1.1 16:24:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:24:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:24:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:24:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:24:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:24:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:24:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:24:55 INFO - Installing collected packages: psutil 16:24:55 INFO - Running setup.py install for psutil 16:24:55 INFO - building 'psutil._psutil_linux' extension 16:24:55 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 16:24:55 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 16:24:55 INFO - building 'psutil._psutil_posix' extension 16:24:55 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 16:24:55 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 16:24:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:24:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:24:56 INFO - Successfully installed psutil 16:24:56 INFO - Cleaning up... 16:24:56 INFO - Return code: 0 16:24:56 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:24: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')]} 16:24:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:24: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')]} 16:24:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:24:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cf51f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ebea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2061a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2065090>, '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 0x2065d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x205ff90>, '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': '4300f881736b422476174874000001bc-1504826657.945308-1168561671', '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 16:24:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 16:24:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 16:24:56 INFO - Using env: {'DISPLAY': ':0', 16:24:56 INFO - 'HOME': '/home/cltbld', 16:24:56 INFO - 'LANG': 'en_US.UTF-8', 16:24:56 INFO - 'LANGUAGE': 'en_US:en', 16:24:56 INFO - 'LOGNAME': 'cltbld', 16:24:56 INFO - 'MAIL': '/var/mail/cltbld', 16:24:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:24:56 INFO - 'MOZ_NO_REMOTE': '1', 16:24:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:24:56 INFO - 'NO_EM_RESTART': '1', 16:24:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:24:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:24:56 INFO - 'PWD': '/builds/slave/test', 16:24:56 INFO - 'SHELL': '/bin/bash', 16:24:56 INFO - 'SHLVL': '1', 16:24:56 INFO - 'TERM': 'linux', 16:24:56 INFO - 'TMOUT': '86400', 16:24:56 INFO - 'USER': 'cltbld', 16:24:56 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1504826657.945308-1168561671', 16:24:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:24:56 INFO - '_': '/tools/buildbot/bin/python'} 16:24:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:24:56 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:24:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:24:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:24:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:24:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:24:57 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:24:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:24:57 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:24:57 INFO - Installing collected packages: mozsystemmonitor 16:24:57 INFO - Running setup.py install for mozsystemmonitor 16:24:57 INFO - Successfully installed mozsystemmonitor 16:24:57 INFO - Cleaning up... 16:24:57 INFO - Return code: 0 16:24:57 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:24:57 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')]} 16:24:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:24:57 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')]} 16:24:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:24:57 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 0x1cf51f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ebea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2061a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2065090>, '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 0x2065d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x205ff90>, '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': '4300f881736b422476174874000001bc-1504826657.945308-1168561671', '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 16:24:57 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 16:24:57 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 16:24:57 INFO - Using env: {'DISPLAY': ':0', 16:24:57 INFO - 'HOME': '/home/cltbld', 16:24:57 INFO - 'LANG': 'en_US.UTF-8', 16:24:57 INFO - 'LANGUAGE': 'en_US:en', 16:24:57 INFO - 'LOGNAME': 'cltbld', 16:24:57 INFO - 'MAIL': '/var/mail/cltbld', 16:24:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:24:57 INFO - 'MOZ_NO_REMOTE': '1', 16:24:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:24:57 INFO - 'NO_EM_RESTART': '1', 16:24:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:24:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:24:57 INFO - 'PWD': '/builds/slave/test', 16:24:57 INFO - 'SHELL': '/bin/bash', 16:24:57 INFO - 'SHLVL': '1', 16:24:57 INFO - 'TERM': 'linux', 16:24:57 INFO - 'TMOUT': '86400', 16:24:57 INFO - 'USER': 'cltbld', 16:24:57 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1504826657.945308-1168561671', 16:24:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:24:57 INFO - '_': '/tools/buildbot/bin/python'} 16:24:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:24:57 INFO - Downloading/unpacking jsonschema==2.5.1 16:24:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:24:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:24:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:24:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:24:58 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:24:58 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:24:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:24:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:24:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:24:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:24:58 INFO - Downloading functools32-3.2.3-2.tar.gz 16:24:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:24:59 INFO - warning: no files found matching '*.txt' 16:24:59 INFO - no previously-included directories found matching 'build' 16:24:59 INFO - no previously-included directories found matching 'dist' 16:24:59 INFO - no previously-included directories found matching '.git*' 16:24:59 INFO - Installing collected packages: jsonschema, functools32 16:24:59 INFO - Running setup.py install for functools32 16:24:59 INFO - warning: no files found matching '*.txt' 16:24:59 INFO - no previously-included directories found matching 'build' 16:24:59 INFO - no previously-included directories found matching 'dist' 16:24:59 INFO - no previously-included directories found matching '.git*' 16:24:59 INFO - Successfully installed jsonschema functools32 16:24:59 INFO - Cleaning up... 16:24:59 INFO - Return code: 0 16:24:59 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:24:59 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')]} 16:24:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:24:59 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')]} 16:24:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:24:59 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 0x1cf51f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ebea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2061a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2065090>, '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 0x2065d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x205ff90>, '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': '4300f881736b422476174874000001bc-1504826657.945308-1168561671', '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 16:24:59 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 16:24:59 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 16:24:59 INFO - Using env: {'DISPLAY': ':0', 16:24:59 INFO - 'HOME': '/home/cltbld', 16:24:59 INFO - 'LANG': 'en_US.UTF-8', 16:24:59 INFO - 'LANGUAGE': 'en_US:en', 16:24:59 INFO - 'LOGNAME': 'cltbld', 16:24:59 INFO - 'MAIL': '/var/mail/cltbld', 16:24:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:24:59 INFO - 'MOZ_NO_REMOTE': '1', 16:24:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:24:59 INFO - 'NO_EM_RESTART': '1', 16:24:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:24:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:24:59 INFO - 'PWD': '/builds/slave/test', 16:24:59 INFO - 'SHELL': '/bin/bash', 16:24:59 INFO - 'SHLVL': '1', 16:24:59 INFO - 'TERM': 'linux', 16:24:59 INFO - 'TMOUT': '86400', 16:24:59 INFO - 'USER': 'cltbld', 16:24:59 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1504826657.945308-1168561671', 16:24:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:24:59 INFO - '_': '/tools/buildbot/bin/python'} 16:24:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:24:59 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:24:59 INFO - Cleaning up... 16:24:59 INFO - Return code: 0 16:24:59 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:24:59 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')]} 16:24:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:24:59 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')]} 16:24:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:24:59 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 0x1cf51f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ebea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2061a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2065090>, '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 0x2065d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x205ff90>, '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': '4300f881736b422476174874000001bc-1504826657.945308-1168561671', '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 16:24:59 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 16:24:59 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 16:24:59 INFO - Using env: {'DISPLAY': ':0', 16:24:59 INFO - 'HOME': '/home/cltbld', 16:24:59 INFO - 'LANG': 'en_US.UTF-8', 16:24:59 INFO - 'LANGUAGE': 'en_US:en', 16:24:59 INFO - 'LOGNAME': 'cltbld', 16:24:59 INFO - 'MAIL': '/var/mail/cltbld', 16:24:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:24:59 INFO - 'MOZ_NO_REMOTE': '1', 16:24:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:24:59 INFO - 'NO_EM_RESTART': '1', 16:24:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:24:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:24:59 INFO - 'PWD': '/builds/slave/test', 16:24:59 INFO - 'SHELL': '/bin/bash', 16:24:59 INFO - 'SHLVL': '1', 16:24:59 INFO - 'TERM': 'linux', 16:24:59 INFO - 'TMOUT': '86400', 16:24:59 INFO - 'USER': 'cltbld', 16:24:59 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1504826657.945308-1168561671', 16:24:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:24:59 INFO - '_': '/tools/buildbot/bin/python'} 16:24:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:24:59 INFO - Downloading/unpacking blobuploader==1.2.4 16:24:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:24:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:24:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:24:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:25:00 INFO - Downloading blobuploader-1.2.4.tar.gz 16:25:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:25:00 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:25:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:25:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:25:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:25:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:25:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:25:01 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:25:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:25:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:25:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:25:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:25:01 INFO - Downloading docopt-0.6.1.tar.gz 16:25:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:25:01 INFO - Installing collected packages: blobuploader, requests, docopt 16:25:01 INFO - Running setup.py install for blobuploader 16:25:01 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:25:01 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:25:01 INFO - Running setup.py install for requests 16:25:01 INFO - Running setup.py install for docopt 16:25:01 INFO - Successfully installed blobuploader requests docopt 16:25:01 INFO - Cleaning up... 16:25:01 INFO - Return code: 0 16:25:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:25:01 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')]} 16:25:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:25:01 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')]} 16:25:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:25:01 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 0x1cf51f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ebea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2061a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2065090>, '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 0x2065d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x205ff90>, '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': '4300f881736b422476174874000001bc-1504826657.945308-1168561671', '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 16:25:01 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 16:25:01 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 16:25:01 INFO - Using env: {'DISPLAY': ':0', 16:25:01 INFO - 'HOME': '/home/cltbld', 16:25:01 INFO - 'LANG': 'en_US.UTF-8', 16:25:01 INFO - 'LANGUAGE': 'en_US:en', 16:25:01 INFO - 'LOGNAME': 'cltbld', 16:25:01 INFO - 'MAIL': '/var/mail/cltbld', 16:25:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:25:01 INFO - 'MOZ_NO_REMOTE': '1', 16:25:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:25:01 INFO - 'NO_EM_RESTART': '1', 16:25:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:25:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:25:01 INFO - 'PWD': '/builds/slave/test', 16:25:01 INFO - 'SHELL': '/bin/bash', 16:25:01 INFO - 'SHLVL': '1', 16:25:01 INFO - 'TERM': 'linux', 16:25:01 INFO - 'TMOUT': '86400', 16:25:01 INFO - 'USER': 'cltbld', 16:25:01 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1504826657.945308-1168561671', 16:25:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:25:01 INFO - '_': '/tools/buildbot/bin/python'} 16:25:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:25:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:25:02 INFO - Running setup.py (path:/tmp/pip-CzxrRN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:25:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:25:02 INFO - Running setup.py (path:/tmp/pip-q5GYyY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:25:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:25:02 INFO - Running setup.py (path:/tmp/pip-VBg9s4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:25:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:25:02 INFO - Running setup.py (path:/tmp/pip-Rpbp5q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:25:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:25:02 INFO - Running setup.py (path:/tmp/pip-O4aKA0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:25:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:25:02 INFO - Running setup.py (path:/tmp/pip-dY1KMF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:25:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:25:02 INFO - Running setup.py (path:/tmp/pip-YHJl4P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:25:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:25:02 INFO - Running setup.py (path:/tmp/pip-K8MZC3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:25:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:25:02 INFO - Running setup.py (path:/tmp/pip-tOnfS7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:25:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:25:02 INFO - Running setup.py (path:/tmp/pip-lWN1rU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:25:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:25:02 INFO - Running setup.py (path:/tmp/pip-q4dBbq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:25:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:25:02 INFO - Running setup.py (path:/tmp/pip-idU9_v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:25:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:25:02 INFO - Running setup.py (path:/tmp/pip-KAgjHE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:25:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:25:03 INFO - Running setup.py (path:/tmp/pip-nG40Nn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:25:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:25:03 INFO - Running setup.py (path:/tmp/pip-Vxbp3J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:25:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:25:03 INFO - Running setup.py (path:/tmp/pip-BpQBCB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:25:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:25:03 INFO - Running setup.py (path:/tmp/pip-UDmy_T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:25:03 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:25:03 INFO - Running setup.py install for manifestparser 16:25:03 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:25:03 INFO - Running setup.py install for mozcrash 16:25:03 INFO - Running setup.py install for mozdebug 16:25:03 INFO - Running setup.py install for mozdevice 16:25:03 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:25:03 INFO - Running setup.py install for mozfile 16:25:03 INFO - Running setup.py install for mozhttpd 16:25:03 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:25:03 INFO - Running setup.py install for mozinfo 16:25:04 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:25:04 INFO - Running setup.py install for mozInstall 16:25:04 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:25:04 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:25:04 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:25:04 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:25:04 INFO - Running setup.py install for mozleak 16:25:04 INFO - Running setup.py install for mozlog 16:25:04 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:25:04 INFO - Running setup.py install for moznetwork 16:25:04 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:25:04 INFO - Running setup.py install for mozprocess 16:25:04 INFO - Running setup.py install for mozprofile 16:25:04 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:25:04 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:25:04 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:25:04 INFO - Running setup.py install for mozrunner 16:25:04 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:25:04 INFO - Running setup.py install for mozscreenshot 16:25:04 INFO - Running setup.py install for moztest 16:25:04 INFO - Running setup.py install for mozversion 16:25:05 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:25:05 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:25:05 INFO - Cleaning up... 16:25:05 INFO - Return code: 0 16:25:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:25:05 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')]} 16:25:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:25:05 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')]} 16:25:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:25:05 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 0x1cf51f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ebea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2061a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2065090>, '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 0x2065d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x205ff90>, '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': '4300f881736b422476174874000001bc-1504826657.945308-1168561671', '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 16:25:05 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 16:25:05 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 16:25:05 INFO - Using env: {'DISPLAY': ':0', 16:25:05 INFO - 'HOME': '/home/cltbld', 16:25:05 INFO - 'LANG': 'en_US.UTF-8', 16:25:05 INFO - 'LANGUAGE': 'en_US:en', 16:25:05 INFO - 'LOGNAME': 'cltbld', 16:25:05 INFO - 'MAIL': '/var/mail/cltbld', 16:25:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:25:05 INFO - 'MOZ_NO_REMOTE': '1', 16:25:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:25:05 INFO - 'NO_EM_RESTART': '1', 16:25:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:25:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:25:05 INFO - 'PWD': '/builds/slave/test', 16:25:05 INFO - 'SHELL': '/bin/bash', 16:25:05 INFO - 'SHLVL': '1', 16:25:05 INFO - 'TERM': 'linux', 16:25:05 INFO - 'TMOUT': '86400', 16:25:05 INFO - 'USER': 'cltbld', 16:25:05 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1504826657.945308-1168561671', 16:25:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:25:05 INFO - '_': '/tools/buildbot/bin/python'} 16:25:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:25:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:25:05 INFO - Running setup.py (path:/tmp/pip-JbSfWj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:25:05 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)) 16:25:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:25:05 INFO - Running setup.py (path:/tmp/pip-A4uYSb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:25:05 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)) 16:25:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:25:05 INFO - Running setup.py (path:/tmp/pip-Vl5u_r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:25:05 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)) 16:25:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:25:05 INFO - Running setup.py (path:/tmp/pip-1mP3IL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:25:05 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)) 16:25:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:25:05 INFO - Running setup.py (path:/tmp/pip-Cs9Jp9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:25:05 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)) 16:25:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:25:05 INFO - Running setup.py (path:/tmp/pip-Mlk2HI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:25:05 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)) 16:25:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:25:05 INFO - Running setup.py (path:/tmp/pip-N5TMur-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:25:05 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)) 16:25:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:25:05 INFO - Running setup.py (path:/tmp/pip-1z4flf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:25:05 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)) 16:25:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:25:05 INFO - Running setup.py (path:/tmp/pip-D4_Ajr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:25:05 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)) 16:25:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:25:05 INFO - Running setup.py (path:/tmp/pip-2Youq0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:25:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.5 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:25:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:25:06 INFO - Running setup.py (path:/tmp/pip-novwhw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:25:06 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)) 16:25:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:25:06 INFO - Running setup.py (path:/tmp/pip-6s9QPS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:25:06 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)) 16:25:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:25:06 INFO - Running setup.py (path:/tmp/pip-8g5_fQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:25:06 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)) 16:25:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:25:06 INFO - Running setup.py (path:/tmp/pip-OqUkwp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:25:06 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)) 16:25:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:25:06 INFO - Running setup.py (path:/tmp/pip-k9DP4I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:25:06 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)) 16:25:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:25:06 INFO - Running setup.py (path:/tmp/pip-DfXk7u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:25:06 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)) 16:25:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:25:06 INFO - Running setup.py (path:/tmp/pip-7nsSHT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:25:06 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)) 16:25:06 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)) 16:25:06 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)) 16:25:06 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)) 16:25:06 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)) 16:25:06 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)) 16:25:06 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)) 16:25:06 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:25:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:25:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:25:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:25:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:25:07 INFO - Downloading blessings-1.6.tar.gz 16:25:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:25:07 INFO - Installing collected packages: blessings 16:25:07 INFO - Running setup.py install for blessings 16:25:07 INFO - Successfully installed blessings 16:25:07 INFO - Cleaning up... 16:25:07 INFO - Return code: 0 16:25:07 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:25:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:25:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:25:08 INFO - Reading from file tmpfile_stdout 16:25:08 INFO - Current package versions: 16:25:08 INFO - argparse == 1.2.1 16:25:08 INFO - blessings == 1.6 16:25:08 INFO - blobuploader == 1.2.4 16:25:08 INFO - docopt == 0.6.1 16:25:08 INFO - functools32 == 3.2.3-2 16:25:08 INFO - jsonschema == 2.5.1 16:25:08 INFO - manifestparser == 1.1 16:25:08 INFO - mozInstall == 1.13 16:25:08 INFO - mozcrash == 1.0 16:25:08 INFO - mozdebug == 0.1 16:25:08 INFO - mozdevice == 0.51 16:25:08 INFO - mozfile == 1.2 16:25:08 INFO - mozhttpd == 0.7 16:25:08 INFO - mozinfo == 0.10 16:25:08 INFO - mozleak == 0.1 16:25:08 INFO - mozlog == 3.5 16:25:08 INFO - moznetwork == 0.27 16:25:08 INFO - mozprocess == 0.25 16:25:08 INFO - mozprofile == 0.28 16:25:08 INFO - mozrunner == 6.13 16:25:08 INFO - mozscreenshot == 0.1 16:25:08 INFO - mozsystemmonitor == 0.3 16:25:08 INFO - moztest == 0.8 16:25:08 INFO - mozversion == 1.4 16:25:08 INFO - psutil == 3.1.1 16:25:08 INFO - requests == 1.2.3 16:25:08 INFO - wsgiref == 0.1.2 16:25:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:25:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:25:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:25:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:25:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:25:08 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 0x1cf51f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ebea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2061a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2065090>, '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 0x2065d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x205ff90>, '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': '4300f881736b422476174874000001bc-1504826657.945308-1168561671', '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 16:25:08 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 16:25:08 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 16:25:08 INFO - Using env: {'DISPLAY': ':0', 16:25:08 INFO - 'HOME': '/home/cltbld', 16:25:08 INFO - 'LANG': 'en_US.UTF-8', 16:25:08 INFO - 'LANGUAGE': 'en_US:en', 16:25:08 INFO - 'LOGNAME': 'cltbld', 16:25:08 INFO - 'MAIL': '/var/mail/cltbld', 16:25:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:25:08 INFO - 'MOZ_NO_REMOTE': '1', 16:25:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:25:08 INFO - 'NO_EM_RESTART': '1', 16:25:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:25:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:25:08 INFO - 'PWD': '/builds/slave/test', 16:25:08 INFO - 'SHELL': '/bin/bash', 16:25:08 INFO - 'SHLVL': '1', 16:25:08 INFO - 'TERM': 'linux', 16:25:08 INFO - 'TMOUT': '86400', 16:25:08 INFO - 'USER': 'cltbld', 16:25:08 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1504826657.945308-1168561671', 16:25:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:25:08 INFO - '_': '/tools/buildbot/bin/python'} 16:25:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:25:08 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)) 16:25:08 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)) 16:25:08 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)) 16:25:08 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)) 16:25:08 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)) 16:25:08 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)) 16:25:08 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)) 16:25:08 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)) 16:25:08 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)) 16:25:08 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 16:25:08 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 16:25:08 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 16:25:08 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 16:25:08 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 16:25:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 16:25:09 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)) 16:25:09 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)) 16:25:09 INFO - Installing collected packages: simplejson 16:25:09 INFO - Running setup.py install for simplejson 16:25:09 INFO - building 'simplejson._speedups' extension 16:25:09 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 16:25:10 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 16:25:10 INFO - Successfully installed simplejson 16:25:10 INFO - Cleaning up... 16:25:10 INFO - Return code: 0 16:25:10 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:25:10 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')]} 16:25:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:25:10 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')]} 16:25:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:25:10 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 0x1cf51f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ebea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2061a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2065090>, '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 0x2065d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x205ff90>, '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': '4300f881736b422476174874000001bc-1504826657.945308-1168561671', '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 16:25:10 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 16:25:10 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 16:25:10 INFO - Using env: {'DISPLAY': ':0', 16:25:10 INFO - 'HOME': '/home/cltbld', 16:25:10 INFO - 'LANG': 'en_US.UTF-8', 16:25:10 INFO - 'LANGUAGE': 'en_US:en', 16:25:10 INFO - 'LOGNAME': 'cltbld', 16:25:10 INFO - 'MAIL': '/var/mail/cltbld', 16:25:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:25:10 INFO - 'MOZ_NO_REMOTE': '1', 16:25:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:25:10 INFO - 'NO_EM_RESTART': '1', 16:25:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:25:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:25:10 INFO - 'PWD': '/builds/slave/test', 16:25:10 INFO - 'SHELL': '/bin/bash', 16:25:10 INFO - 'SHLVL': '1', 16:25:10 INFO - 'TERM': 'linux', 16:25:10 INFO - 'TMOUT': '86400', 16:25:10 INFO - 'USER': 'cltbld', 16:25:10 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1504826657.945308-1168561671', 16:25:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:25:10 INFO - '_': '/tools/buildbot/bin/python'} 16:25:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:25:10 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:25:10 INFO - Cleaning up... 16:25:10 INFO - Return code: 0 16:25:10 INFO - Running post-action listener: _resource_record_post_action 16:25:10 INFO - Running post-action listener: _start_resource_monitoring 16:25:10 INFO - Starting resource monitoring. 16:25:10 INFO - [mozharness: 2017-09-07 23:25:10.798849Z] Finished create-virtualenv step (success) 16:25:10 INFO - [mozharness: 2017-09-07 23:25:10.799364Z] Running install step. 16:25:10 INFO - Running pre-action listener: _resource_record_pre_action 16:25:10 INFO - Running main action method: install 16:25:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:25:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:25:11 INFO - Reading from file tmpfile_stdout 16:25:11 INFO - Detecting whether we're running mozinstall >=1.0... 16:25:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:25:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:25:11 INFO - Reading from file tmpfile_stdout 16:25:11 INFO - Output received: 16:25:11 INFO - Usage: mozinstall [options] installer 16:25:11 INFO - Options: 16:25:11 INFO - -h, --help show this help message and exit 16:25:11 INFO - -d DEST, --destination=DEST 16:25:11 INFO - Directory to install application into. [default: 16:25:11 INFO - "/builds/slave/test"] 16:25:11 INFO - --app=APP Application being installed. [default: firefox] 16:25:11 INFO - mkdir: /builds/slave/test/build/application 16:25:11 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'] 16:25:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 16:25:27 INFO - Reading from file tmpfile_stdout 16:25:27 INFO - Output received: 16:25:27 INFO - /builds/slave/test/build/application/firefox/firefox 16:25:27 INFO - Running post-action listener: _resource_record_post_action 16:25:27 INFO - [mozharness: 2017-09-07 23:25:27.498639Z] Finished install step (success) 16:25:27 INFO - [mozharness: 2017-09-07 23:25:27.498892Z] Running setup-mitmproxy step. 16:25:27 INFO - Running pre-action listener: _resource_record_pre_action 16:25:27 INFO - Running main action method: setup_mitmproxy 16:25:27 INFO - Skipping: mitmproxy is not required 16:25:27 INFO - Running post-action listener: _resource_record_post_action 16:25:27 INFO - [mozharness: 2017-09-07 23:25:27.500028Z] Finished setup-mitmproxy step (success) 16:25:27 INFO - [mozharness: 2017-09-07 23:25:27.500248Z] Running run-tests step. 16:25:27 INFO - Running pre-action listener: _resource_record_pre_action 16:25:27 INFO - Running pre-action listener: _set_gcov_prefix 16:25:27 INFO - Running main action method: run_tests 16:25:27 WARNING - Try message not found. 16:25:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:25:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:25:27 INFO - Python 2.7.3 16:25:27 INFO - Return code: 0 16:25:27 INFO - grabbing minidump binary from tooltool 16:25:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:25:27 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2065090>, '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 0x2065d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x205ff90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:25:27 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 16:25:27 INFO - Copy/paste: /tools/tooltool.py --url https://tooltool.mozilla-releng.net/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 16:25:27 INFO - Calling ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 16:25:27 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:25:27 INFO - Return code: 0 16:25:27 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 16:25:27 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:25:27 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:25:27 INFO - ENV: RUST_BACKTRACE is now full 16:25:27 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 16:25:27 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:25:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-stylo-disabled-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Jlx0Q_90SDmX-HVjCVREGw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-045', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-stylo-disabled-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-stylo-disabled-e10s_raw.log'] in /builds/slave/test/build 16:25:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-stylo-disabled-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Jlx0Q_90SDmX-HVjCVREGw/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-045 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-stylo-disabled-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-stylo-disabled-e10s_raw.log 16:25:27 INFO - Using env: {'DISPLAY': ':0', 16:25:27 INFO - 'HOME': '/home/cltbld', 16:25:27 INFO - 'LANG': 'en_US.UTF-8', 16:25:27 INFO - 'LANGUAGE': 'en_US:en', 16:25:27 INFO - 'LOGNAME': 'cltbld', 16:25:27 INFO - 'MAIL': '/var/mail/cltbld', 16:25:27 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:25:27 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:25:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:25:27 INFO - 'MOZ_NO_REMOTE': '1', 16:25:27 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:25:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:25:27 INFO - 'NO_EM_RESTART': '1', 16:25:27 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:25:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:25:27 INFO - 'PWD': '/builds/slave/test', 16:25:27 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:25:27 INFO - 'RUST_BACKTRACE': 'full', 16:25:27 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 16:25:27 INFO - 'SHELL': '/bin/bash', 16:25:27 INFO - 'SHLVL': '1', 16:25:27 INFO - 'TERM': 'linux', 16:25:27 INFO - 'TMOUT': '86400', 16:25:27 INFO - 'USER': 'cltbld', 16:25:27 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1504826657.945308-1168561671', 16:25:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:25:27 INFO - '_': '/tools/buildbot/bin/python'} 16:25:27 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-stylo-disabled-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Jlx0Q_90SDmX-HVjCVREGw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-045', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-stylo-disabled-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-stylo-disabled-e10s_raw.log'] with output_timeout 3600 16:25:27 INFO - mozversion application_buildid: 20170907220040 16:25:27 INFO - mozversion application_changeset: b4c1ad9565ee9d00d96501c4a83083daf25c1413 16:25:27 INFO - mozversion application_display_name: Nightly 16:25:27 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:25:27 INFO - mozversion application_name: Firefox 16:25:27 INFO - mozversion application_remotingname: firefox 16:25:27 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 16:25:27 INFO - mozversion application_vendor: Mozilla 16:25:27 INFO - mozversion application_version: 57.0a1 16:25:27 INFO - mozversion platform_buildid: 20170907220040 16:25:27 INFO - mozversion platform_changeset: b4c1ad9565ee9d00d96501c4a83083daf25c1413 16:25:27 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 16:25:27 INFO - mozversion platform_version: 57.0a1 16:25:27 INFO - using testdate: 1504826727 16:25:27 INFO - actual date: 1504826727 16:25:27 INFO - starting webserver on 'localhost:55899' 16:25:27 INFO - SUITE-START | Running 2 tests 16:25:27 INFO - TEST-START | dromaeo_css 16:25:27 INFO - Initialising browser for dromaeo_css test... 16:25:27 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:55899/getInfo.html -profile /tmp/tmpOrhuLK/profile 16:25:27 INFO - TEST-INFO | started process 5744 (/builds/slave/test/build/application/firefox/firefox http://localhost:55899/getInfo.html) 16:25:33 INFO - TEST-INFO | 5744: exit 0 16:25:33 INFO - Browser initialized. 16:25:33 INFO - Running cycle 1/1 for dromaeo_css test... 16:25:33 INFO - Using env: {'DISPLAY': ':0', 16:25:33 INFO - 'HOME': '/home/cltbld', 16:25:33 INFO - 'JSGC_DISABLE_POISONING': '1', 16:25:33 INFO - 'LANG': 'en_US.UTF-8', 16:25:33 INFO - 'LANGUAGE': 'en_US:en', 16:25:33 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 16:25:33 INFO - 'LOGNAME': 'cltbld', 16:25:33 INFO - 'MAIL': '/var/mail/cltbld', 16:25:33 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:25:33 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:25:33 INFO - 'MOZ_CRASHREPORTER': '1', 16:25:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:25:33 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 16:25:33 INFO - 'MOZ_NO_REMOTE': '1', 16:25:33 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:25:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:25:33 INFO - 'NO_EM_RESTART': '1', 16:25:33 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:25:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:25:33 INFO - 'PWD': '/builds/slave/test', 16:25:33 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:25:33 INFO - 'RUST_BACKTRACE': 'full', 16:25:33 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 16:25:33 INFO - 'SHELL': '/bin/bash', 16:25:33 INFO - 'SHLVL': '1', 16:25:33 INFO - 'STYLO_FORCE_DISABLED': '1', 16:25:33 INFO - 'TERM': 'linux', 16:25:33 INFO - 'TMOUT': '86400', 16:25:33 INFO - 'USER': 'cltbld', 16:25:33 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1504826657.945308-1168561671', 16:25:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:25:33 INFO - '_': '/tools/buildbot/bin/python'} 16:25:33 INFO - TEST-INFO | started process 5945 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpOrhuLK/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 16:25:34 INFO - PID 5945 | 16:25:34 INFO - PID 5945 | (/builds/slave/test/build/application/firefox/firefox:5996): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 16:25:34 INFO - PID 5945 | 16:25:45 INFO - PID 5945 | RSS: Main: 157253632 16:25:45 INFO - PID 5945 | 16:28:41 INFO - PID 5945 | Cycle 1(1): loaded http://localhost:55899/tests/dromaeo/cssquery-dojo.html (next: http://localhost:55899/tests/dromaeo/cssquery-ext.html) 16:28:43 INFO - PID 5945 | RSS: Main: 164364288 16:28:43 INFO - PID 5945 | 16:31:44 INFO - PID 5945 | Cycle 1(1): loaded http://localhost:55899/tests/dromaeo/cssquery-ext.html (next: http://localhost:55899/tests/dromaeo/cssquery-jquery.html) 16:31:45 INFO - PID 5945 | RSS: Main: 161009664 16:31:45 INFO - PID 5945 | 16:34:46 INFO - PID 5945 | Cycle 1(1): loaded http://localhost:55899/tests/dromaeo/cssquery-jquery.html (next: http://localhost:55899/tests/dromaeo/cssquery-mootools.html) 16:34:47 INFO - PID 5945 | RSS: Main: 163291136 16:34:47 INFO - PID 5945 | 16:37:49 INFO - PID 5945 | Cycle 1(1): loaded http://localhost:55899/tests/dromaeo/cssquery-mootools.html (next: http://localhost:55899/tests/dromaeo/cssquery-prototype.html) 16:37:49 INFO - PID 5945 | RSS: Main: 161886208 16:37:49 INFO - PID 5945 | 16:40:52 INFO - PID 5945 | Cycle 1(1): loaded http://localhost:55899/tests/dromaeo/cssquery-prototype.html (next: http://localhost:55899/tests/dromaeo/cssquery-yui.html) 16:40:53 INFO - PID 5945 | RSS: Main: 163360768 16:40:53 INFO - PID 5945 | 16:43:54 INFO - PID 5945 | Cycle 1(1): loaded http://localhost:55899/tests/dromaeo/cssquery-yui.html (next: http://localhost:55899/tests/dromaeo/cssquery-dojo.html) 16:43:55 INFO - PID 5945 | RSS: Main: 165609472 16:43:55 INFO - PID 5945 | 16:43:55 INFO - PID 5945 | __start_tp_report 16:43:55 INFO - PID 5945 | _x_x_mozilla_page_load 16:43:55 INFO - PID 5945 | _x_x_mozilla_page_load_details 16:43:55 INFO - PID 5945 | |i|pagename|runs| 16:43:55 INFO - PID 5945 | |0;dojo.html;3813.1420430403155;3836.827342769575;3850.7689538627683;3859.845606175753;3870.7290489665716;6223.2532096148525;6257.124002639628;6375.043743438487;6376.075469056984;6397.520185986046;6341.841453963653;6375.426211640952;6385.840353991152;6386.169797926274;6395.648239346834;5674.744636491358;5724.8282551523525;5729.656220626775;5748.45389687979;5762.769489220426;5717.256756621633;5758.971205143968;5774.3936886626925;5775.075987841946;5785.190073389729;155.97477511395573;5570.749316280777;5604.411536788619;5607.971960140214;5796.30444346679;5638.943610563882;5706.087026075849;5716.3426205986425;5718.685472299022;5746.02317606008;5638.126090455986;5640.407757185519;5695.117256825199;5695.5443564514735;5695.77216911323;6224.906626400608;6231.3145553989025;6276.403741644582;6366.236051673783;6381.457576106037;6832.419244364235;6836.077129587541;6936.965315173442;6941.548799328028;6945.027696122547;6801.523893327469;6892.724291028353;6899.793006209822;6904.551204171713;6991.650417479156;6304.401081897258;6340.841478963017;6365.522585806037;6371.426571608577;6386.808395748135;6989.965050174818;7070.8939365909555;7125.180604230476;7127.287271272935;7132.821679458055;6813.080234168423;6882.552634078769;6886.552374095668;6902.551334163364;6910.378065974087;6926.722931082701;6937.375636192767;7008.369246767816;7027.332403421667;7046.1544614645545;6727.93272067273;6796;6821.147356580427;6870.141232345957;6925.307469253135;6789.422899053537;6810.557313774688;6845.794626161223;6848.452123830081;6851.246362900077;4977.0792403405485;4996.300517927493;5021.271915572222;5032.79868805251;5058.064258112261;4599.05719327537;4620.2607582786595;4626.97686511572;4638.698484598491;4663.137319595886;6310.810675679737;6319.462845658079;6331.715072821734;6409.487241020707;6424.71088801005;6738.494612904052;6832.282610325944;6861.005154252605;6861.348171923659;6931.6880740366805;6205;6279.277883043417;6280.089387038854;6290.21372328459;6298.212723409574;5541.27963364768;5549.639273447213;5586.022446071921;5606.943930560642;5621.409751976065;7079.752208672672;7168.139823221142;7175.784726458214;7183.568985860865;7194.676239569232;6825;6836.829079273058;6904.964255361736;6949.617771022642;6963.303669632996;6983.266756990442;7082.822929426806;7085.220625731063;7115.359617634438;7207.783766487014;6638.933610663831;6642.236142843635;6645;6646;6646.468282537482;6769.796906092628;6819;6873.656317184221;6876.140482439695;6911.930880691129;6633.867322653617;6645.734170633314;6673.766418175341;6684.799456016327;6718.025886246369;6520.413162815369;6604.174478190226;6611.33886611335;6688.665566721742;6693.196816382066;6516.543841931209;6551.4103730664465;6551.934480655133;6555.836104097436;6558.1802274715665;6584.8024559263295;6636.900446493403;6676.065350850982;6678.265390806911;6681.2984636612455;6535.901961470677;6559.967200163968;6575.539712220098;6579.309172537006;6579.374959378852 16:43:55 INFO - PID 5945 | |1;ext.html;934.4159900062461;956.7368973532278;991.5686676295812;994.6916455898672;997.1275134257525;42018.36972445412;42763;42776.35835462463;42800;43055.56944430558;40098.39852402209;42262.15475690484;42608.57391426092;42933;43004.569954300525;7983.361331093513;8077.676892924276;8079.192080791932;8115.634796434175;8120.6345714443;7928.127905930343;8048.59757012147;8054.556999365032;8056;8058.717944871902;8080.11118776937;8102.7569172924905;8106.067802202734;8115.918840811575;8117.918820811805;7941.483803552751;7956.323712484388;7959.204079592052;8036.557989310586;8080.434369594102;7754.185810489874;7809.789482630202;7817.218278172194;7818.726344577913;7825.921740782576;38307.425388619195;38586;39069.21861562781;39095.60904390977;39130.413043804365;3627.873024444158;3657.725670574691;3659.762115462513;3667.174885650734;3701.0747313171705;31434;31634.209144771336;31677.20806979844;31715.048548543582;31785.205369865707;5165;5364.785408583652;5384.111621582434;5401.405845357047;5418.214358917935;210221;211668;211991;218179;233676;51590.74204628953;51628.74185629048;51640.48359516432;51892.22161667578;51899.48100519022;53689.46310536923;53699.194512081565;53922.7303863486;54013;54147.729261353445;16282.83717162837;16456.83543164577;16504.17479126063;16515.752263716055;16581.419650312182;13375.130616509896;13388.732225355438;13493;13496.190228586316;13500.122492037986;14088.929555352363;14153.150810951376;14179.007469477037;14230.928845355707;14325.140491570539;5256.185291279858;5306.204069389622;5340.6915305750335;5376.704281264568;5384.892302153935;1068.9465526723632;1071.3893080943787;1076.3810808784824;1081.605214096849;1084.669175901342;25214.747852521607;25269.87365063163;25357.492850142895;25556.488870222493;25577.488450230892;1187.2105050141558;1188.8157335612993;1192.9642110736693;1196.8503937007874;1202.0623913347604;13380.799288010687;13394.263315517741;13489.66275843092;13521.256330901895;13699.520516782064;291913;292752;293808;294679;294692;53379.46620533745;53390.466095339325;53584.732076339365;53588.732056340246;53707.19439208415;1480.6742516646536;1487.6950225203807;1515.5832146159894;1519.6276912156593;1538.4230913407473;24515.754842451348;24577.13980010691;24626;24896.54691036736;24911.626325604768;36081;36292.45561316529;39804.40293395659;40118.79940600395;40260.1947961045;239062;239374;239916;239971;240027;33451.83274083614;34003.319933600695;36008.63991360053;36042.4593631101;36196.81901590475;45561.31658025199;45988;46107.077858443314;46297;46342.073158537314;33625.495617566245;33817.15457113493;34045.65954340425;34439.31121377608;34486.31027379388;33473;33681.83159084287;33778.64885404556;34096.48855267123;34454;37996;39153.41269880897;39396.21207575775;39420.211595768495;39755.2048959025;32246;32279.677203227668;32359.51460728044;32543;32899.835500822344;24357;24742.50514989726;24899.502009960062;25264.621030684186;25700.614490782278 16:43:55 INFO - PID 5945 | |2;jquery.html;591.5326891755514;591.8875413671404;599.2808629644425;602.3494625804132;619.2166908860291;22775;23464.64803027956;23490.88254558727;23536.529269414623;23548.17581384652;23428.414289642766;24704.629430558554;24985.87507062467;25127.74872251277;25146;6715.529912906086;6911.101556797609;6917.51577389583;6927.584344939294;6934.479914006444;6513.837154071162;6804.285550017251;6822.931770682279;6833.9316606834045;6900.827479313007;6816.182058153028;6823.4200092991905;6829.556078854884;6832.453403727714;6836.658167091626;6575.967120164392;6806.659667016655;6838.076859623987;6838.384545390913;6853.17761868579;6364.681765911686;6369.490440764727;6371.490280777527;6383.202099737533;6395.712192951329;28468.572971405447;29010.41979160426;29025.85487072572;29055.56416653752;29096.418071638447;2303.0557471436796;2318.0843566791023;2319.7100362454694;2326.3021093672;2326.3835083702834;19927.20291188351;21012.579748404947;21126.683099753514;21145.577088458143;21213.257535986166;2466.2231397109854;2474.2948259745885;2541.5552278351215;2594.001309495839;2597.467519158568;137640;139596.30201849027;142706.28646856803;143119;147606;15216.326204117539;15375.461858834888;15479.613009674847;15803.20983950798;15874;24740;24777.13280035226;24797.752022479544;24807.25578232656;24867.129650462146;9918.859331176862;9935.155511781579;9936.552855121392;10057;10138.391696498235;9006.63973441055;9020.278377729766;9126.863097053549;9142.177204051668;9180.31147663928;8991.56135018394;9306.581203845843;9373.797157667943;9502.857457138149;9678.903210967941;2828.6339256284955;2843.3556792183053;2864.760154970655;2906.7238612331794;2961.060505368987;1023.8464230365356;1052.4579841381694;1062.9096526795154;1066.4294602387686;1075.516017791981;20974.370768876957;20976.79023209779;21028.894855525625;21334.35996920095;21352.572948540943;1134.9205555611193;1137.4483375562845;1138.6754774889116;1142.280363371071;1154.5958914380067;11335.829962550568;11424.771504569862;11531.192816502931;11554;11619.244749091451;522851;544005;548741;553227;555836;116643;120850.3957480207;121770;123112.38443807724;125089.37455312847;1209.3348658237935;1227.5519435406013;1261.1487246109023;1261.4890969157507;1267.702090008847;17937.641247175245;18112;18207.635847282716;18251;18496.81503184951;59860.401395985486;60819.391806081374;60830.47367868999;62222.68888655709;62646.37353626405;75679.24320756723;75764;76110.61944690463;76763.61618191874;76888;14732.484363047242;15002.924985375439;15023.47417840414;15050.172240526876;15187.696246075238;23023.88488057616;23138.07447702073;23316.067357305517;23326.416839579146;23416;19213.32753353626;19219.135138918504;19260;19356.4193074208;19390;16826.326946921985;16895;17259.827401725823;17626;17750.37873674415;19172.13725382389;19354.419367419;19422.611547769247;19433.931133787213;19459.902700486407;14593.124412535282;14676.77984830207;14720.116792992454;14725.116493010453;14738.705225895637;18781.53046173802;18849.340273090376;18867.245310187987;18872.811271887105;18887.811121888604 16:43:55 INFO - PID 5945 | |3;mootools.html;1428.2858570714643;1458;1464.1288433382133;1465.868071873531;1488.8436714145014;16093;16708.415205467812;16800.24398902049;16802.32790688374;16806.075665838373;16622.750658740097;16767.329306827713;16790.83209167908;16801.159942002912;16811.495655130337;2674.77264432523;2789.27478855497;2807.9438411231763;2809.2976755811046;2816.7887408444344;2678.7856971442334;2708.823926444785;2759.1998320487137;2760.4341110072487;2762.4337010912814;2637.3011152044724;2668.0395057779183;2675.759181673649;2676.8260063095936;2680.128958088619;2599.9350016249555;2633.4206474575562;2634.2229042432527;2638.3931695709903;2638.7493188147096;2532.645429639852;2550.85970271635;2570.742925707433;2571.9357016074746;2579.058643595093;16167.676646467122;16591;16601.750973735405;16602.169891505375;16608.584785380463;8361.414700970934;8628.439151455135;8650.394472386935;8685.044645089036;8690.130986901322;5545.417731138213;5679.091345384719;5864.560157988168;6020.337762846083;6043.425874541911;1969.3107412405686;1971.4775584470126;1977.7725561560317;2025.6252593270292;2050.3131450963947;28956.565651515244;28976;29216.561751573743;29336.559951600742;30156.69843301561;7136.286371362822;7291.963540182265;7301.379382752525;7330.120385553768;7400.296971787671;18702.532436688918;18738.625227495373;18766.624667506574;18774.436766897015;18781.90609046946;8163.959180204061;8242.835143297221;8245.711400101087;8259.463134896212;8347.833043339098;7580.734674286484;7618.771436856903;7743.9612801936755;7769.805754856174;7802.258785415376;5573.972130139324;5742.59801813869;5743.8564035899435;5795.623284486495;5807.738651760681;1650.983490165107;1661.7258152404959;1682.1841406917636;1691.053010314228;1711.5464401933498;891.8483857744069;912.7946212102356;920.1488623023677;924.9917589827121;927.4157280912982;8063.919360806317;8205.83588328242;8234.217749313806;8234.629441675139;8303.709370172015;741.8034220931457;751.6109494921426;763.7326935572617;767.2212704105337;780.4107898536569;4794.137055330004;5335.253064570963;5424.538914192338;5429.348478182565;5434.989054049947;23726.644100338508;24375;24667.62998555023;24699.87650061738;24791.25626231216;23474.413139671644;24114.758852411604;24531.264062078168;25136.37159071038;25142.24573262805;10597.788044239227;10849.78300433987;11800.46897889597;12058.638240852788;12482.937585312196;9231.169194772234;9270.953645231732;9404.670836520963;9476.526173691425;9684.70945871625;20737.68893466569;21096.156153753676;21123.577528449652;21205.151793928686;21346.679799802703;21558;21712.67430988568;21791.891040544695;21899.67150492776;21955.890220549434;12103.576374826838;12251.754964900474;12262.509499619915;12263;12268.754624907631;15375.846241537442;15396.538103856903;15530.534083977498;15547.455839045579;15630.06219626826;12156.392180390769;12244.632661020183;12366.81449778272;12605.87394126047;12753.744925101631;11752.882471175179;11846.348450834943;11916.582919597773;12112.939435302767;12142.75714485723;9273.489958052372;9410.058994100535;9445.102715242041;9496.287778416785;9501.42991420517;7644.877931785828;7852.646630901738;7921.485103468256;7942;7964.0443146820435;7972.681092756225;8008.559529225949;8141.185881411811;8196.959015204886;8200 16:43:55 INFO - PID 5945 | |4;prototype.html;555.572209398763;558.2045585041317;577.3187638586467;581.2211636407214;587.213134399904;6183.350748171439;6261.937380626192;6280.842978925518;6289;6301.180846489961;6075.969620151893;6168.814935551941;6194.566380353366;6261;6277.68611569422;5047.2176812593925;5076.492350764931;5080.110980578384;5108.540231379176;5116.386033675964;4880.292357608145;4995.6752811067345;5018.121828679985;5027.120253955562;5036.546710796028;4932.457429682732;4944.233643785219;4977.50224977503;4977.975110124463;5006.749662516878;4854.660173787836;4915.827946021908;4952.5047495250365;4967.751612419364;4981.5018498150075;4496.572825581565;4505.707129036602;4509.210888094603;4524.411826462539;4540.387047748544;6130.877382452326;6193.504519638418;6222.81331560054;6224.234338225399;6253.53098517613;5767.61577221468;5802.274715660543;5805.796797112123;5812.651240925558;5840.299164100293;6530.2816690164045;6572.835679108012;6595.967020164917;6622.205335359788;6625.701843417058;5029.170186919154;5039.496050394968;5041.294218809333;5047.545720885138;5048.469910659402;6658.6004839709285;6681.864083105884;6721.932780672179;6742.898856517108;6904.206016308139;5099.6175286852895;5100.1074811907765;5102.4642412546145;5116.360454943132;5181.844544663666;5179.715115668674;5193.324867767166;5199.350081239845;5224.9738751306;5266.4470230626;5778.624389414675;5793.420657934257;5835.9708201458725;5841.299044114733;5871.266091738533;6543.1821022372205;6547.116139321226;6584.868302634016;6609.405153536205;6657.46740260778;1762.4712586224082;1774.7426623139572;1801.25248022072;1804.1340156724846;1810.1311370542214;1240;1266.7909794883742;1275.661949583361;1299.13607451044;1310.7247478029722;6073.605215660968;6151.5386346024225;6209.565330426827;6260.248770147611;6309.466698648771;6788;6803.863922721518;6813.659317034128;6828.9317106829285;6836.4530837532875;6212.689365531705;6254.718537665725;6262.843428914313;6274.874502509924;6307.5584709070845;3525.6121826599056;3583.050491619723;3585.928281434357;3588.551431071116;3599.6400359963795;6793.898091528631;6930.584164950119;6989.650517474106;7021.613811240431;7101.325374089453;6856.725730970805;6884.896726549106;6912.205096413977;6983.854208296768;7013.859722805618;6807.183138023469;6822.658867056726;6946.930530694628;6973.930260697328;7062.329078737512;6647.368499992493;6669.1330127083165;6749.763758268437;6769.458443324621;6771.848785706343;6694.866102677822;6721.764738234138;6785.932140678728;6807.149106361705;6822.420094291842;7005.159380874327;7055.823604409931;7067.187273463617;7068.893966590608;7071.646417679199;5134.922976155286;5178.093833579184;5179.92230116541;5203.531682148473;5214.243934629533;6557.540972131905;6689.76585819494;6760.830979225559;6772.322767723188;6860.382565568923;6306.369363063841;6421.903671445026;6432.903506447501;6497.122888410004;6502.439414540381;6334.493240540838;6440.67796610177;6446.1628435895545;6448.806535803933;6462.127612772404;6336.4614007808;6384.776532821329;6402.903956440564;6441.710123044568;6479.838004049937;6514.413702766774;6548.639824809491;6567;6569.37590928861;6592.670366481752;6350.237971443456;6376.617402955838;6413.71138298787;6449.290578036506;6472.92866282785 16:43:55 INFO - PID 5945 | |5;yui.html;943.9480828554426;970.1462712812726;986.2997271936924;990.3661656539821;997.6109317366227;19098;19535;19546.902265488672;19613.31353402631;19670.40988770339;16626.33494660212;16818;17002.404915827916;17659.20533575989;17713;897.7306807957602;961.1493827962252;971;971.4220039076738;973.4062222044547;1003.2225025605178;1037.8028174646831;1048.4967215736451;1049.2917280835443;1051.2273478992934;1111.183280288987;1113.6436340371085;1114.2089116727134;1115.0577761791274;1115.4367044642486;1036.9585216591327;1106.9391183484904;1108.0415440643826;1110.0897264243324;1112.4382186995556;785.3520845302648;786.657803855319;788.613579346122;789.0491957191571;789.2305002622454;18966.051697415212;18989.620207595908;19024.619507609772;19054.333098341493;19096.80903190964;22054.117835286568;22255.777442225695;22729.77270227293;22777.202797901995;22804.31587052391;17280;17502.912485437617;17540.91229543844;17634.294628214855;17656.646867062715;4017.0961533654986;4019.3970904364287;4026.6376026157786;4035.1727895781296;4037.1118353962083;139259;139344;140901;142860.28569857188;145439;16439.506814795575;16973.660526789394;17031.14844257782;17069.146542673065;17105.486835394957;31496.37007259888;31656.208594785316;31786.36427271488;31819.84090079535;31944.68055319417;9241.21449676785;9362.06379362072;9365.063493650581;9374.156325930699;9408.482533460588;4515.164694531522;4581.427321584802;4592.15045216636;4668.789904454307;4670.719756814602;5877.882442351129;5972.820815375546;5984.611000284967;6002.429769171922;6005.489533389711;1025.6051420203275;1053.5311786255043;1067.893210678926;1074.7366895110747;1078.1051727066515;720.7621484909968;727.6289092562831;738.1400668221569;739.4454159380465;753.8906858505486;12672;12892;12940.417681204368;13395.129316594584;13547.729045419037;706.2372637551432;709.4643544124153;710.5878590417545;718.3786025088261;724.7535837815168;6016.789412370546;6082.75668973245;6105.297890742532;6197.504199664016;6273.498120150468;146331;148077;148415;149624;157815;104515.47742261393;106103.46948265209;127563;128936;133990.33004835108;108030.3795443069;109670.45164774284;110921;113112.43443782728;114664.42667786608;13349.466021359036;14291.928540357232;14313.713725725636;14337.475670010397;14462.20457874785;52395.21407178819;53084.73457632687;54845.72577137089;55595.44404555903;55819.72090139523;73033;73277.6336118316;74428.62785686037;75129.24870751222;76164.23835761793;19064.42806715801;19423.3201837935;19971.500712482306;20077.598448030665;20267;21459.46351341229;21605.891970540677;21909.342719718436;21946.561068778217;21987.34037978863;18240.726389103907;18844.152013159168;18881;18921.810781892003;19127.808721912603;17072;17098.145092745563;17157.227924743165;17370.652586947937;17398.13009349553;19828.801711983273;19847.404577862686;19862.60274794525;20019.39941801748;20135;14489.637759056108;14589.489367872073;14681.92659036698;14689.118652880861;14694.853051469348;15316.5449282318;15661.686766264838;15862.60343491422;15892;16082.67834643324 16:43:55 INFO - PID 5945 | __end_tp_report 16:43:55 INFO - PID 5945 | __start_cc_report 16:43:55 INFO - PID 5945 | _x_x_mozilla_cycle_collect,356 16:43:55 INFO - PID 5945 | __end_cc_report 16:43:55 INFO - PID 5945 | __startTimestamp1504827835190__endTimestamp 16:43:55 INFO - PID 5945 | 16:43:55 INFO - PID 5945 | ------- Summary: start ------- 16:43:55 INFO - PID 5945 | Number of tests: 6 16:43:55 INFO - PID 5945 | 16:43:55 INFO - PID 5945 | [#0] dojo.html Cycles:165 Average:6285.83 Median:6559.97 stddev:889.56 (13.6%) stddev-sans-first:870.89 16:43:55 INFO - PID 5945 | Values: 3813.1 3836.8 3850.8 3859.8 3870.7 6223.3 6257.1 6375.0 6376.1 6397.5 6341.8 6375.4 6385.8 6386.2 6395.6 5674.7 5724.8 5729.7 5748.5 5762.8 5717.3 5759.0 5774.4 5775.1 5785.2 156.0 5570.7 5604.4 5608.0 5796.3 5638.9 5706.1 5716.3 5718.7 5746.0 5638.1 5640.4 5695.1 5695.5 5695.8 6224.9 6231.3 6276.4 6366.2 6381.5 6832.4 6836.1 6937.0 6941.5 6945.0 6801.5 6892.7 6899.8 6904.6 6991.7 6304.4 6340.8 6365.5 6371.4 6386.8 6990.0 7070.9 7125.2 7127.3 7132.8 6813.1 6882.6 6886.6 6902.6 6910.4 6926.7 6937.4 7008.4 7027.3 7046.2 6727.9 6796.0 6821.1 6870.1 6925.3 6789.4 6810.6 6845.8 6848.5 6851.2 4977.1 4996.3 5021.3 5032.8 5058.1 4599.1 4620.3 4627.0 4638.7 4663.1 6310.8 6319.5 6331.7 6409.5 6424.7 6738.5 6832.3 6861.0 6861.3 6931.7 6205.0 6279.3 6280.1 6290.2 6298.2 5541.3 5549.6 5586.0 5606.9 5621.4 7079.8 7168.1 7175.8 7183.6 7194.7 6825.0 6836.8 6905.0 6949.6 6963.3 6983.3 7082.8 7085.2 7115.4 7207.8 6638.9 6642.2 6645.0 6646.0 6646.5 6769.8 6819.0 6873.7 6876.1 6911.9 6633.9 6645.7 6673.8 6684.8 6718.0 6520.4 6604.2 6611.3 6688.7 6693.2 6516.5 6551.4 6551.9 6555.8 6558.2 6584.8 6636.9 6676.1 6678.3 6681.3 6535.9 6560.0 6575.5 6579.3 6579.4 16:43:55 INFO - PID 5945 | 16:43:55 INFO - PID 5945 | [#1] ext.html Cycles:180 Average:42111.16 Median:19912.35 stddev:65678.01 (329.8%) stddev-sans-first:65789.49 16:43:55 INFO - PID 5945 | Values: 934.4 956.7 991.6 994.7 997.1 42018.4 42763.0 42776.4 42800.0 43055.6 40098.4 42262.2 42608.6 42933.0 43004.6 7983.4 8077.7 8079.2 8115.6 8120.6 7928.1 8048.6 8054.6 8056.0 8058.7 8080.1 8102.8 8106.1 8115.9 8117.9 7941.5 7956.3 7959.2 8036.6 8080.4 7754.2 7809.8 7817.2 7818.7 7825.9 38307.4 38586.0 39069.2 39095.6 39130.4 3627.9 3657.7 3659.8 3667.2 3701.1 31434.0 31634.2 31677.2 31715.0 31785.2 5165.0 5364.8 5384.1 5401.4 5418.2 210221.0 211668.0 211991.0 218179.0 233676.0 51590.7 51628.7 51640.5 51892.2 51899.5 53689.5 53699.2 53922.7 54013.0 54147.7 16282.8 16456.8 16504.2 16515.8 16581.4 13375.1 13388.7 13493.0 13496.2 13500.1 14088.9 14153.2 14179.0 14230.9 14325.1 5256.2 5306.2 5340.7 5376.7 5384.9 1068.9 1071.4 1076.4 1081.6 1084.7 25214.7 25269.9 25357.5 25556.5 25577.5 1187.2 1188.8 1193.0 1196.9 1202.1 13380.8 13394.3 13489.7 13521.3 13699.5 291913.0 292752.0 293808.0 294679.0 294692.0 53379.5 53390.5 53584.7 53588.7 53707.2 1480.7 1487.7 1515.6 1519.6 1538.4 24515.8 24577.1 24626.0 24896.5 24911.6 36081.0 36292.5 39804.4 40118.8 40260.2 239062.0 239374.0 239916.0 239971.0 240027.0 33451.8 34003.3 36008.6 36042.5 36196.8 45561.3 45988.0 46107.1 46297.0 46342.1 33625.5 33817.2 34045.7 34439.3 34486.3 33473.0 33681.8 33778.6 34096.5 34454.0 37996.0 39153.4 39396.2 39420.2 39755.2 32246.0 32279.7 32359.5 32543.0 32899.8 24357.0 24742.5 24899.5 25264.6 25700.6 16:43:55 INFO - PID 5945 | 16:43:55 INFO - PID 5945 | [#2] jquery.html Cycles:180 Average:37130.76 Median:23321.24 stddev:91405.26 (391.9%) stddev-sans-first:91620.50 16:43:55 INFO - PID 5945 | Values: 591.5 591.9 599.3 602.3 619.2 22775.0 23464.6 23490.9 23536.5 23548.2 23428.4 24704.6 24985.9 25127.7 25146.0 6715.5 6911.1 6917.5 6927.6 6934.5 6513.8 6804.3 6822.9 6833.9 6900.8 6816.2 6823.4 6829.6 6832.5 6836.7 6576.0 6806.7 6838.1 6838.4 6853.2 6364.7 6369.5 6371.5 6383.2 6395.7 28468.6 29010.4 29025.9 29055.6 29096.4 2303.1 2318.1 2319.7 2326.3 2326.4 19927.2 21012.6 21126.7 21145.6 21213.3 2466.2 2474.3 2541.6 2594.0 2597.5 137640.0 139596.3 142706.3 143119.0 147606.0 15216.3 15375.5 15479.6 15803.2 15874.0 24740.0 24777.1 24797.8 24807.3 24867.1 9918.9 9935.2 9936.6 10057.0 10138.4 9006.6 9020.3 9126.9 9142.2 9180.3 8991.6 9306.6 9373.8 9502.9 9678.9 2828.6 2843.4 2864.8 2906.7 2961.1 1023.8 1052.5 1062.9 1066.4 1075.5 20974.4 20976.8 21028.9 21334.4 21352.6 1134.9 1137.4 1138.7 1142.3 1154.6 11335.8 11424.8 11531.2 11554.0 11619.2 522851.0 544005.0 548741.0 553227.0 555836.0 116643.0 120850.4 121770.0 123112.4 125089.4 1209.3 1227.6 1261.1 1261.5 1267.7 17937.6 18112.0 18207.6 18251.0 18496.8 59860.4 60819.4 60830.5 62222.7 62646.4 75679.2 75764.0 76110.6 76763.6 76888.0 14732.5 15002.9 15023.5 15050.2 15187.7 23023.9 23138.1 23316.1 23326.4 23416.0 19213.3 19219.1 19260.0 19356.4 19390.0 16826.3 16895.0 17259.8 17626.0 17750.4 19172.1 19354.4 19422.6 19433.9 19459.9 14593.1 14676.8 14720.1 14725.1 14738.7 18781.5 18849.3 18867.2 18872.8 18887.8 16:43:55 INFO - PID 5945 | 16:43:55 INFO - PID 5945 | [#3] mootools.html Cycles:180 Average:10149.38 Median:2653.39 stddev:7550.96 (284.6%) stddev-sans-first:7543.72 16:43:55 INFO - PID 5945 | Values: 1428.3 1458.0 1464.1 1465.9 1488.8 16093.0 16708.4 16800.2 16802.3 16806.1 16622.8 16767.3 16790.8 16801.2 16811.5 2674.8 2789.3 2807.9 2809.3 2816.8 2678.8 2708.8 2759.2 2760.4 2762.4 2637.3 2668.0 2675.8 2676.8 2680.1 2599.9 2633.4 2634.2 2638.4 2638.7 2532.6 2550.9 2570.7 2571.9 2579.1 16167.7 16591.0 16601.8 16602.2 16608.6 8361.4 8628.4 8650.4 8685.0 8690.1 5545.4 5679.1 5864.6 6020.3 6043.4 1969.3 1971.5 1977.8 2025.6 2050.3 28956.6 28976.0 29216.6 29336.6 30156.7 7136.3 7292.0 7301.4 7330.1 7400.3 18702.5 18738.6 18766.6 18774.4 18781.9 8164.0 8242.8 8245.7 8259.5 8347.8 7580.7 7618.8 7744.0 7769.8 7802.3 5574.0 5742.6 5743.9 5795.6 5807.7 1651.0 1661.7 1682.2 1691.1 1711.5 891.8 912.8 920.1 925.0 927.4 8063.9 8205.8 8234.2 8234.6 8303.7 741.8 751.6 763.7 767.2 780.4 4794.1 5335.3 5424.5 5429.3 5435.0 23726.6 24375.0 24667.6 24699.9 24791.3 23474.4 24114.8 24531.3 25136.4 25142.2 10597.8 10849.8 11800.5 12058.6 12482.9 9231.2 9271.0 9404.7 9476.5 9684.7 20737.7 21096.2 21123.6 21205.2 21346.7 21558.0 21712.7 21791.9 21899.7 21955.9 12103.6 12251.8 12262.5 12263.0 12268.8 15375.8 15396.5 15530.5 15547.5 15630.1 12156.4 12244.6 12366.8 12605.9 12753.7 11752.9 11846.3 11916.6 12112.9 12142.8 9273.5 9410.1 9445.1 9496.3 9501.4 7644.9 7852.6 7921.5 7942.0 7964.0 7972.7 8008.6 8141.2 8197.0 8200.0 16:43:55 INFO - PID 5945 | 16:43:55 INFO - PID 5945 | [#4] prototype.html Cycles:180 Average:5613.57 Median:6261.47 stddev:1567.47 (25.0%) stddev-sans-first:1525.21 16:43:55 INFO - PID 5945 | Values: 555.6 558.2 577.3 581.2 587.2 6183.4 6261.9 6280.8 6289.0 6301.2 6076.0 6168.8 6194.6 6261.0 6277.7 5047.2 5076.5 5080.1 5108.5 5116.4 4880.3 4995.7 5018.1 5027.1 5036.5 4932.5 4944.2 4977.5 4978.0 5006.7 4854.7 4915.8 4952.5 4967.8 4981.5 4496.6 4505.7 4509.2 4524.4 4540.4 6130.9 6193.5 6222.8 6224.2 6253.5 5767.6 5802.3 5805.8 5812.7 5840.3 6530.3 6572.8 6596.0 6622.2 6625.7 5029.2 5039.5 5041.3 5047.5 5048.5 6658.6 6681.9 6721.9 6742.9 6904.2 5099.6 5100.1 5102.5 5116.4 5181.8 5179.7 5193.3 5199.4 5225.0 5266.4 5778.6 5793.4 5836.0 5841.3 5871.3 6543.2 6547.1 6584.9 6609.4 6657.5 1762.5 1774.7 1801.3 1804.1 1810.1 1240.0 1266.8 1275.7 1299.1 1310.7 6073.6 6151.5 6209.6 6260.2 6309.5 6788.0 6803.9 6813.7 6828.9 6836.5 6212.7 6254.7 6262.8 6274.9 6307.6 3525.6 3583.1 3585.9 3588.6 3599.6 6793.9 6930.6 6989.7 7021.6 7101.3 6856.7 6884.9 6912.2 6983.9 7013.9 6807.2 6822.7 6946.9 6973.9 7062.3 6647.4 6669.1 6749.8 6769.5 6771.8 6694.9 6721.8 6785.9 6807.1 6822.4 7005.2 7055.8 7067.2 7068.9 7071.6 5134.9 5178.1 5179.9 5203.5 5214.2 6557.5 6689.8 6760.8 6772.3 6860.4 6306.4 6421.9 6432.9 6497.1 6502.4 6334.5 6440.7 6446.2 6448.8 6462.1 6336.5 6384.8 6402.9 6441.7 6479.8 6514.4 6548.6 6567.0 6569.4 6592.7 6350.2 6376.6 6413.7 6449.3 6472.9 16:43:55 INFO - PID 5945 | 16:43:55 INFO - PID 5945 | [#5] yui.html Cycles:180 Average:27498.98 Median:19112.90 stddev:39881.51 (208.7%) stddev-sans-first:39943.54 16:43:55 INFO - PID 5945 | Values: 943.9 970.1 986.3 990.4 997.6 19098.0 19535.0 19546.9 19613.3 19670.4 16626.3 16818.0 17002.4 17659.2 17713.0 897.7 961.1 971.0 971.4 973.4 1003.2 1037.8 1048.5 1049.3 1051.2 1111.2 1113.6 1114.2 1115.1 1115.4 1037.0 1106.9 1108.0 1110.1 1112.4 785.4 786.7 788.6 789.0 789.2 18966.1 18989.6 19024.6 19054.3 19096.8 22054.1 22255.8 22729.8 22777.2 22804.3 17280.0 17502.9 17540.9 17634.3 17656.6 4017.1 4019.4 4026.6 4035.2 4037.1 139259.0 139344.0 140901.0 142860.3 145439.0 16439.5 16973.7 17031.1 17069.1 17105.5 31496.4 31656.2 31786.4 31819.8 31944.7 9241.2 9362.1 9365.1 9374.2 9408.5 4515.2 4581.4 4592.2 4668.8 4670.7 5877.9 5972.8 5984.6 6002.4 6005.5 1025.6 1053.5 1067.9 1074.7 1078.1 720.8 727.6 738.1 739.4 753.9 12672.0 12892.0 12940.4 13395.1 13547.7 706.2 709.5 710.6 718.4 724.8 6016.8 6082.8 6105.3 6197.5 6273.5 146331.0 148077.0 148415.0 149624.0 157815.0 104515.5 106103.5 127563.0 128936.0 133990.3 108030.4 109670.5 110921.0 113112.4 114664.4 13349.5 14291.9 14313.7 14337.5 14462.2 52395.2 53084.7 54845.7 55595.4 55819.7 73033.0 73277.6 74428.6 75129.2 76164.2 19064.4 19423.3 19971.5 20077.6 20267.0 21459.5 21605.9 21909.3 21946.6 21987.3 18240.7 18844.2 18881.0 18921.8 19127.8 17072.0 17098.1 17157.2 17370.7 17398.1 19828.8 19847.4 19862.6 20019.4 20135.0 14489.6 14589.5 14681.9 14689.1 14694.9 15316.5 15661.7 15862.6 15892.0 16082.7 16:43:55 INFO - PID 5945 | -------- Summary: end -------- 16:43:55 INFO - PID 5945 | 16:43:55 INFO - TEST-INFO | 5945: exit 0 16:43:55 INFO - TEST-OK | dromaeo_css | took 1108031ms 16:43:55 INFO - TEST-START | kraken 16:43:55 INFO - Initialising browser for kraken test... 16:43:55 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:55899/getInfo.html -profile /tmp/tmpWbn09W/profile 16:43:55 INFO - TEST-INFO | started process 6320 (/builds/slave/test/build/application/firefox/firefox http://localhost:55899/getInfo.html) 16:44:01 INFO - TEST-INFO | 6320: exit 0 16:44:01 INFO - Browser initialized. 16:44:01 INFO - Running cycle 1/1 for kraken test... 16:44:01 INFO - Using env: {'DISPLAY': ':0', 16:44:01 INFO - 'HOME': '/home/cltbld', 16:44:01 INFO - 'JSGC_DISABLE_POISONING': '1', 16:44:01 INFO - 'LANG': 'en_US.UTF-8', 16:44:01 INFO - 'LANGUAGE': 'en_US:en', 16:44:01 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 16:44:01 INFO - 'LOGNAME': 'cltbld', 16:44:01 INFO - 'MAIL': '/var/mail/cltbld', 16:44:01 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:44:01 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:44:01 INFO - 'MOZ_CRASHREPORTER': '1', 16:44:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:44:01 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 16:44:01 INFO - 'MOZ_NO_REMOTE': '1', 16:44:01 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:44:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:44:01 INFO - 'NO_EM_RESTART': '1', 16:44:01 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:44:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:44:01 INFO - 'PWD': '/builds/slave/test', 16:44:01 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:44:01 INFO - 'RUST_BACKTRACE': 'full', 16:44:01 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 16:44:01 INFO - 'SHELL': '/bin/bash', 16:44:01 INFO - 'SHLVL': '1', 16:44:01 INFO - 'STYLO_FORCE_DISABLED': '1', 16:44:01 INFO - 'TERM': 'linux', 16:44:01 INFO - 'TMOUT': '86400', 16:44:01 INFO - 'USER': 'cltbld', 16:44:01 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1504826657.945308-1168561671', 16:44:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:44:01 INFO - '_': '/tools/buildbot/bin/python'} 16:44:01 INFO - TEST-INFO | started process 6519 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpWbn09W/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 16:44:02 INFO - PID 6519 | 16:44:02 INFO - PID 6519 | (/builds/slave/test/build/application/firefox/firefox:6570): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 16:44:02 INFO - PID 6519 | 16:44:12 INFO - PID 6519 | RSS: Main: 157630464 16:44:12 INFO - PID 6519 | 16:44:15 INFO - PID 6519 | Cycle 1(1): loaded http://localhost:55899/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:55899/tests/kraken/driver.html?testName=audio-beat-detection) 16:44:16 INFO - PID 6519 | RSS: Main: 163725312 16:44:16 INFO - PID 6519 | 16:44:18 INFO - PID 6519 | Cycle 1(1): loaded http://localhost:55899/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:55899/tests/kraken/driver.html?testName=audio-dft) 16:44:19 INFO - PID 6519 | RSS: Main: 164347904 16:44:19 INFO - PID 6519 | 16:44:22 INFO - PID 6519 | Cycle 1(1): loaded http://localhost:55899/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:55899/tests/kraken/driver.html?testName=audio-fft) 16:44:23 INFO - PID 6519 | RSS: Main: 158134272 16:44:23 INFO - PID 6519 | 16:44:25 INFO - PID 6519 | Cycle 1(1): loaded http://localhost:55899/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:55899/tests/kraken/driver.html?testName=audio-oscillator) 16:44:25 INFO - PID 6519 | RSS: Main: 159592448 16:44:25 INFO - PID 6519 | 16:44:27 INFO - PID 6519 | Cycle 1(1): loaded http://localhost:55899/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:55899/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:44:28 INFO - PID 6519 | RSS: Main: 159236096 16:44:28 INFO - PID 6519 | 16:44:31 INFO - PID 6519 | Cycle 1(1): loaded http://localhost:55899/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:55899/tests/kraken/driver.html?testName=imaging-darkroom) 16:44:32 INFO - PID 6519 | RSS: Main: 159625216 16:44:32 INFO - PID 6519 | 16:44:35 INFO - PID 6519 | Cycle 1(1): loaded http://localhost:55899/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:55899/tests/kraken/driver.html?testName=imaging-desaturate) 16:44:36 INFO - PID 6519 | RSS: Main: 161116160 16:44:36 INFO - PID 6519 | 16:44:38 INFO - PID 6519 | Cycle 1(1): loaded http://localhost:55899/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:55899/tests/kraken/driver.html?testName=json-parse-financial) 16:44:39 INFO - PID 6519 | RSS: Main: 162504704 16:44:39 INFO - PID 6519 | 16:44:41 INFO - PID 6519 | Cycle 1(1): loaded http://localhost:55899/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:55899/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:44:41 INFO - PID 6519 | RSS: Main: 162598912 16:44:41 INFO - PID 6519 | 16:44:43 INFO - PID 6519 | Cycle 1(1): loaded http://localhost:55899/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:55899/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:44:43 INFO - PID 6519 | RSS: Main: 162529280 16:44:43 INFO - PID 6519 | 16:44:45 INFO - PID 6519 | Cycle 1(1): loaded http://localhost:55899/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:55899/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:44:46 INFO - PID 6519 | RSS: Main: 162947072 16:44:46 INFO - PID 6519 | 16:44:49 INFO - PID 6519 | Cycle 1(1): loaded http://localhost:55899/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:55899/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:44:49 INFO - PID 6519 | RSS: Main: 162979840 16:44:49 INFO - PID 6519 | 16:44:52 INFO - PID 6519 | Cycle 1(1): loaded http://localhost:55899/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:55899/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:44:53 INFO - PID 6519 | RSS: Main: 163508224 16:44:53 INFO - PID 6519 | 16:44:54 INFO - PID 6519 | Cycle 1(1): loaded http://localhost:55899/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:55899/tests/kraken/driver.html?testName=ai-astar) 16:44:55 INFO - PID 6519 | RSS: Main: 165490688 16:44:55 INFO - PID 6519 | 16:44:55 INFO - PID 6519 | __start_tp_report 16:44:55 INFO - PID 6519 | _x_x_mozilla_page_load 16:44:55 INFO - PID 6519 | _x_x_mozilla_page_load_details 16:44:55 INFO - PID 6519 | |i|pagename|runs| 16:44:55 INFO - PID 6519 | |0;ai-astar;109;106;110;106;109;109;107;108;106;107 16:44:55 INFO - PID 6519 | |1;audio-beat-detection;117;112;113;116;116;133;116;112;113;113 16:44:55 INFO - PID 6519 | |2;audio-dft;163;160;163;162;163;161;162;161;166;168 16:44:55 INFO - PID 6519 | |3;audio-fft;73;72;73;72;73;106;73;75;73;82 16:44:55 INFO - PID 6519 | |4;audio-oscillator;81;78;81;81;81;78;85;79;77;78 16:44:55 INFO - PID 6519 | |5;imaging-gaussian-blur;114;114;111;113;114;114;111;115;114;114 16:44:55 INFO - PID 6519 | |6;imaging-darkroom;149;150;151;157;150;149;157;152;151;154 16:44:55 INFO - PID 6519 | |7;imaging-desaturate;94;94;94;93;93;93;94;94;97;93 16:44:55 INFO - PID 6519 | |8;json-parse-financial;48;47;45;48;46;46;47;47;47;49 16:44:55 INFO - PID 6519 | |9;json-stringify-tinderbox;37;37;37;36;37;37;37;36;37;37 16:44:55 INFO - PID 6519 | |10;stanford-crypto-aes;73;76;79;77;72;71;72;72;73;80 16:44:55 INFO - PID 6519 | |11;stanford-crypto-ccm;119;116;111;115;118;118;116;107;110;108 16:44:55 INFO - PID 6519 | |12;stanford-crypto-pbkdf2;167;158;158;158;161;156;162;154;173;156 16:44:55 INFO - PID 6519 | |13;stanford-crypto-sha256-iterative;61;59;59;59;59;61;59;61;61;59 16:44:55 INFO - PID 6519 | __end_tp_report 16:44:55 INFO - PID 6519 | __start_cc_report 16:44:55 INFO - PID 6519 | _x_x_mozilla_cycle_collect,868 16:44:55 INFO - PID 6519 | __end_cc_report 16:44:55 INFO - PID 6519 | __startTimestamp1504827895019__endTimestamp 16:44:55 INFO - PID 6519 | 16:44:55 INFO - PID 6519 | ------- Summary: start ------- 16:44:55 INFO - PID 6519 | Number of tests: 14 16:44:55 INFO - PID 6519 | 16:44:55 INFO - PID 6519 | [#0] ai-astar Cycles:10 Average:107.70 Median:108.50 stddev:1.49 (1.4%) stddev-sans-first:1.51 16:44:55 INFO - PID 6519 | Values: 109.0 106.0 110.0 106.0 109.0 109.0 107.0 108.0 106.0 107.0 16:44:55 INFO - PID 6519 | 16:44:55 INFO - PID 6519 | [#1] audio-beat-detection Cycles:10 Average:116.10 Median:116.00 stddev:6.23 (5.4%) stddev-sans-first:6.60 16:44:55 INFO - PID 6519 | Values: 117.0 112.0 113.0 116.0 116.0 133.0 116.0 112.0 113.0 113.0 16:44:55 INFO - PID 6519 | 16:44:55 INFO - PID 6519 | [#2] audio-dft Cycles:10 Average:162.90 Median:163.00 stddev:2.42 (1.5%) stddev-sans-first:2.57 16:44:55 INFO - PID 6519 | Values: 163.0 160.0 163.0 162.0 163.0 161.0 162.0 161.0 166.0 168.0 16:44:55 INFO - PID 6519 | 16:44:55 INFO - PID 6519 | [#3] audio-fft Cycles:10 Average:77.20 Median:73.00 stddev:10.54 (14.4%) stddev-sans-first:11.07 16:44:55 INFO - PID 6519 | Values: 73.0 72.0 73.0 72.0 73.0 106.0 73.0 75.0 73.0 82.0 16:44:55 INFO - PID 6519 | 16:44:55 INFO - PID 6519 | [#4] audio-oscillator Cycles:10 Average:79.90 Median:81.00 stddev:2.38 (2.9%) stddev-sans-first:2.49 16:44:55 INFO - PID 6519 | Values: 81.0 78.0 81.0 81.0 81.0 78.0 85.0 79.0 77.0 78.0 16:44:55 INFO - PID 6519 | 16:44:55 INFO - PID 6519 | [#5] imaging-gaussian-blur Cycles:10 Average:113.40 Median:114.00 stddev:1.35 (1.2%) stddev-sans-first:1.41 16:44:55 INFO - PID 6519 | Values: 114.0 114.0 111.0 113.0 114.0 114.0 111.0 115.0 114.0 114.0 16:44:55 INFO - PID 6519 | 16:44:55 INFO - PID 6519 | [#6] imaging-darkroom Cycles:10 Average:152.00 Median:151.50 stddev:3.02 (2.0%) stddev-sans-first:3.00 16:44:55 INFO - PID 6519 | Values: 149.0 150.0 151.0 157.0 150.0 149.0 157.0 152.0 151.0 154.0 16:44:55 INFO - PID 6519 | 16:44:55 INFO - PID 6519 | [#7] imaging-desaturate Cycles:10 Average:93.90 Median:94.00 stddev:1.20 (1.3%) stddev-sans-first:1.27 16:44:55 INFO - PID 6519 | Values: 94.0 94.0 94.0 93.0 93.0 93.0 94.0 94.0 97.0 93.0 16:44:55 INFO - PID 6519 | 16:44:55 INFO - PID 6519 | [#8] json-parse-financial Cycles:10 Average:47.00 Median:47.00 stddev:1.15 (2.5%) stddev-sans-first:1.17 16:44:55 INFO - PID 6519 | Values: 48.0 47.0 45.0 48.0 46.0 46.0 47.0 47.0 47.0 49.0 16:44:55 INFO - PID 6519 | 16:44:55 INFO - PID 6519 | [#9] json-stringify-tinderbox Cycles:10 Average:36.80 Median:37.00 stddev:0.42 (1.1%) stddev-sans-first:0.44 16:44:55 INFO - PID 6519 | Values: 37.0 37.0 37.0 36.0 37.0 37.0 37.0 36.0 37.0 37.0 16:44:55 INFO - PID 6519 | 16:44:55 INFO - PID 6519 | [#10] stanford-crypto-aes Cycles:10 Average:74.50 Median:74.50 stddev:3.24 (4.3%) stddev-sans-first:3.39 16:44:55 INFO - PID 6519 | Values: 73.0 76.0 79.0 77.0 72.0 71.0 72.0 72.0 73.0 80.0 16:44:55 INFO - PID 6519 | 16:44:55 INFO - PID 6519 | [#11] stanford-crypto-ccm Cycles:10 Average:113.80 Median:116.00 stddev:4.42 (3.8%) stddev-sans-first:4.27 16:44:55 INFO - PID 6519 | Values: 119.0 116.0 111.0 115.0 118.0 118.0 116.0 107.0 110.0 108.0 16:44:55 INFO - PID 6519 | 16:44:55 INFO - PID 6519 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:160.30 Median:159.50 stddev:5.79 (3.6%) stddev-sans-first:5.61 16:44:55 INFO - PID 6519 | Values: 167.0 158.0 158.0 158.0 161.0 156.0 162.0 154.0 173.0 156.0 16:44:55 INFO - PID 6519 | 16:44:55 INFO - PID 6519 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:59.80 Median:60.00 stddev:1.03 (1.7%) stddev-sans-first:1.00 16:44:55 INFO - PID 6519 | Values: 61.0 59.0 59.0 59.0 59.0 61.0 59.0 61.0 61.0 59.0 16:44:55 INFO - PID 6519 | -------- Summary: end -------- 16:44:55 INFO - PID 6519 | 16:44:55 INFO - PID 6519 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 16:44:55 INFO - TEST-INFO | 6519: exit 0 16:44:55 INFO - TEST-OK | kraken | took 59767ms 16:44:55 INFO - SUITE-END | took 1167s 16:44:55 INFO - Completed test suite (00:19:27) 16:44:55 INFO - javascript benchmark 16:44:55 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s", "stylo_disabled"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8927.33372451659, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3813.1420430403155, 3836.827342769575, 3850.7689538627683, 3859.845606175753, 3870.7290489665716, 6223.2532096148525, 6257.124002639628, 6375.043743438487, 6376.075469056984, 6397.520185986046, 6341.841453963653, 6375.426211640952, 6385.840353991152, 6386.169797926274, 6395.648239346834, 5674.744636491358, 5724.8282551523525, 5729.656220626775, 5748.45389687979, 5762.769489220426, 5717.256756621633, 5758.971205143968, 5774.3936886626925, 5775.075987841946, 5785.190073389729, 155.97477511395573, 5570.749316280777, 5604.411536788619, 5607.971960140214, 5796.30444346679, 5638.943610563882, 5706.087026075849, 5716.3426205986425, 5718.685472299022, 5746.02317606008, 5638.126090455986, 5640.407757185519, 5695.117256825199, 5695.5443564514735, 5695.77216911323, 6224.906626400608, 6231.3145553989025, 6276.403741644582, 6366.236051673783, 6381.457576106037, 6832.419244364235, 6836.077129587541, 6936.965315173442, 6941.548799328028, 6945.027696122547, 6801.523893327469, 6892.724291028353, 6899.793006209822, 6904.551204171713, 6991.650417479156, 6304.401081897258, 6340.841478963017, 6365.522585806037, 6371.426571608577, 6386.808395748135, 6989.965050174818, 7070.8939365909555, 7125.180604230476, 7127.287271272935, 7132.821679458055, 6813.080234168423, 6882.552634078769, 6886.552374095668, 6902.551334163364, 6910.378065974087, 6926.722931082701, 6937.375636192767, 7008.369246767816, 7027.332403421667, 7046.1544614645545, 6727.93272067273, 6796.0, 6821.147356580427, 6870.141232345957, 6925.307469253135, 6789.422899053537, 6810.557313774688, 6845.794626161223, 6848.452123830081, 6851.246362900077, 4977.0792403405485, 4996.300517927493, 5021.271915572222, 5032.79868805251, 5058.064258112261, 4599.05719327537, 4620.2607582786595, 4626.97686511572, 4638.698484598491, 4663.137319595886, 6310.810675679737, 6319.462845658079, 6331.715072821734, 6409.487241020707, 6424.71088801005, 6738.494612904052, 6832.282610325944, 6861.005154252605, 6861.348171923659, 6931.6880740366805, 6205.0, 6279.277883043417, 6280.089387038854, 6290.21372328459, 6298.212723409574, 5541.27963364768, 5549.639273447213, 5586.022446071921, 5606.943930560642, 5621.409751976065, 7079.752208672672, 7168.139823221142, 7175.784726458214, 7183.568985860865, 7194.676239569232, 6825.0, 6836.829079273058, 6904.964255361736, 6949.617771022642, 6963.303669632996, 6983.266756990442, 7082.822929426806, 7085.220625731063, 7115.359617634438, 7207.783766487014, 6638.933610663831, 6642.236142843635, 6645.0, 6646.0, 6646.468282537482, 6769.796906092628, 6819.0, 6873.656317184221, 6876.140482439695, 6911.930880691129, 6633.867322653617, 6645.734170633314, 6673.766418175341, 6684.799456016327, 6718.025886246369, 6520.413162815369, 6604.174478190226, 6611.33886611335, 6688.665566721742, 6693.196816382066, 6516.543841931209, 6551.4103730664465, 6551.934480655133, 6555.836104097436, 6558.1802274715665, 6584.8024559263295, 6636.900446493403, 6676.065350850982, 6678.265390806911, 6681.2984636612455, 6535.901961470677, 6559.967200163968, 6575.539712220098, 6579.309172537006, 6579.374959378852], "value": 6226.882061056939, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [934.4159900062461, 956.7368973532278, 991.5686676295812, 994.6916455898672, 997.1275134257525, 42018.36972445412, 42763.0, 42776.35835462463, 42800.0, 43055.56944430558, 40098.39852402209, 42262.15475690484, 42608.57391426092, 42933.0, 43004.569954300525, 7983.361331093513, 8077.676892924276, 8079.192080791932, 8115.634796434175, 8120.6345714443, 7928.127905930343, 8048.59757012147, 8054.556999365032, 8056.0, 8058.717944871902, 8080.11118776937, 8102.7569172924905, 8106.067802202734, 8115.918840811575, 8117.918820811805, 7941.483803552751, 7956.323712484388, 7959.204079592052, 8036.557989310586, 8080.434369594102, 7754.185810489874, 7809.789482630202, 7817.218278172194, 7818.726344577913, 7825.921740782576, 38307.425388619195, 38586.0, 39069.21861562781, 39095.60904390977, 39130.413043804365, 3627.873024444158, 3657.725670574691, 3659.762115462513, 3667.174885650734, 3701.0747313171705, 31434.0, 31634.209144771336, 31677.20806979844, 31715.048548543582, 31785.205369865707, 5165.0, 5364.785408583652, 5384.111621582434, 5401.405845357047, 5418.214358917935, 210221.0, 211668.0, 211991.0, 218179.0, 233676.0, 51590.74204628953, 51628.74185629048, 51640.48359516432, 51892.22161667578, 51899.48100519022, 53689.46310536923, 53699.194512081565, 53922.7303863486, 54013.0, 54147.729261353445, 16282.83717162837, 16456.83543164577, 16504.17479126063, 16515.752263716055, 16581.419650312182, 13375.130616509896, 13388.732225355438, 13493.0, 13496.190228586316, 13500.122492037986, 14088.929555352363, 14153.150810951376, 14179.007469477037, 14230.928845355707, 14325.140491570539, 5256.185291279858, 5306.204069389622, 5340.6915305750335, 5376.704281264568, 5384.892302153935, 1068.9465526723632, 1071.3893080943787, 1076.3810808784824, 1081.605214096849, 1084.669175901342, 25214.747852521607, 25269.87365063163, 25357.492850142895, 25556.488870222493, 25577.488450230892, 1187.2105050141558, 1188.8157335612993, 1192.9642110736693, 1196.8503937007874, 1202.0623913347604, 13380.799288010687, 13394.263315517741, 13489.66275843092, 13521.256330901895, 13699.520516782064, 291913.0, 292752.0, 293808.0, 294679.0, 294692.0, 53379.46620533745, 53390.466095339325, 53584.732076339365, 53588.732056340246, 53707.19439208415, 1480.6742516646536, 1487.6950225203807, 1515.5832146159894, 1519.6276912156593, 1538.4230913407473, 24515.754842451348, 24577.13980010691, 24626.0, 24896.54691036736, 24911.626325604768, 36081.0, 36292.45561316529, 39804.40293395659, 40118.79940600395, 40260.1947961045, 239062.0, 239374.0, 239916.0, 239971.0, 240027.0, 33451.83274083614, 34003.319933600695, 36008.63991360053, 36042.4593631101, 36196.81901590475, 45561.31658025199, 45988.0, 46107.077858443314, 46297.0, 46342.073158537314, 33625.495617566245, 33817.15457113493, 34045.65954340425, 34439.31121377608, 34486.31027379388, 33473.0, 33681.83159084287, 33778.64885404556, 34096.48855267123, 34454.0, 37996.0, 39153.41269880897, 39396.21207575775, 39420.211595768495, 39755.2048959025, 32246.0, 32279.677203227668, 32359.51460728044, 32543.0, 32899.835500822344, 24357.0, 24742.50514989726, 24899.502009960062, 25264.621030684186, 25700.614490782278], "value": 18127.42806167514, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [591.5326891755514, 591.8875413671404, 599.2808629644425, 602.3494625804132, 619.2166908860291, 22775.0, 23464.64803027956, 23490.88254558727, 23536.529269414623, 23548.17581384652, 23428.414289642766, 24704.629430558554, 24985.87507062467, 25127.74872251277, 25146.0, 6715.529912906086, 6911.101556797609, 6917.51577389583, 6927.584344939294, 6934.479914006444, 6513.837154071162, 6804.285550017251, 6822.931770682279, 6833.9316606834045, 6900.827479313007, 6816.182058153028, 6823.4200092991905, 6829.556078854884, 6832.453403727714, 6836.658167091626, 6575.967120164392, 6806.659667016655, 6838.076859623987, 6838.384545390913, 6853.17761868579, 6364.681765911686, 6369.490440764727, 6371.490280777527, 6383.202099737533, 6395.712192951329, 28468.572971405447, 29010.41979160426, 29025.85487072572, 29055.56416653752, 29096.418071638447, 2303.0557471436796, 2318.0843566791023, 2319.7100362454694, 2326.3021093672, 2326.3835083702834, 19927.20291188351, 21012.579748404947, 21126.683099753514, 21145.577088458143, 21213.257535986166, 2466.2231397109854, 2474.2948259745885, 2541.5552278351215, 2594.001309495839, 2597.467519158568, 137640.0, 139596.30201849027, 142706.28646856803, 143119.0, 147606.0, 15216.326204117539, 15375.461858834888, 15479.613009674847, 15803.20983950798, 15874.0, 24740.0, 24777.13280035226, 24797.752022479544, 24807.25578232656, 24867.129650462146, 9918.859331176862, 9935.155511781579, 9936.552855121392, 10057.0, 10138.391696498235, 9006.63973441055, 9020.278377729766, 9126.863097053549, 9142.177204051668, 9180.31147663928, 8991.56135018394, 9306.581203845843, 9373.797157667943, 9502.857457138149, 9678.903210967941, 2828.6339256284955, 2843.3556792183053, 2864.760154970655, 2906.7238612331794, 2961.060505368987, 1023.8464230365356, 1052.4579841381694, 1062.9096526795154, 1066.4294602387686, 1075.516017791981, 20974.370768876957, 20976.79023209779, 21028.894855525625, 21334.35996920095, 21352.572948540943, 1134.9205555611193, 1137.4483375562845, 1138.6754774889116, 1142.280363371071, 1154.5958914380067, 11335.829962550568, 11424.771504569862, 11531.192816502931, 11554.0, 11619.244749091451, 522851.0, 544005.0, 548741.0, 553227.0, 555836.0, 116643.0, 120850.3957480207, 121770.0, 123112.38443807724, 125089.37455312847, 1209.3348658237935, 1227.5519435406013, 1261.1487246109023, 1261.4890969157507, 1267.702090008847, 17937.641247175245, 18112.0, 18207.635847282716, 18251.0, 18496.81503184951, 59860.401395985486, 60819.391806081374, 60830.47367868999, 62222.68888655709, 62646.37353626405, 75679.24320756723, 75764.0, 76110.61944690463, 76763.61618191874, 76888.0, 14732.484363047242, 15002.924985375439, 15023.47417840414, 15050.172240526876, 15187.696246075238, 23023.88488057616, 23138.07447702073, 23316.067357305517, 23326.416839579146, 23416.0, 19213.32753353626, 19219.135138918504, 19260.0, 19356.4193074208, 19390.0, 16826.326946921985, 16895.0, 17259.827401725823, 17626.0, 17750.37873674415, 19172.13725382389, 19354.419367419, 19422.611547769247, 19433.931133787213, 19459.902700486407, 14593.124412535282, 14676.77984830207, 14720.116792992454, 14725.116493010453, 14738.705225895637, 18781.53046173802, 18849.340273090376, 18867.245310187987, 18872.811271887105, 18887.811121888604], "value": 12482.298834300087, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1428.2858570714643, 1458.0, 1464.1288433382133, 1465.868071873531, 1488.8436714145014, 16093.0, 16708.415205467812, 16800.24398902049, 16802.32790688374, 16806.075665838373, 16622.750658740097, 16767.329306827713, 16790.83209167908, 16801.159942002912, 16811.495655130337, 2674.77264432523, 2789.27478855497, 2807.9438411231763, 2809.2976755811046, 2816.7887408444344, 2678.7856971442334, 2708.823926444785, 2759.1998320487137, 2760.4341110072487, 2762.4337010912814, 2637.3011152044724, 2668.0395057779183, 2675.759181673649, 2676.8260063095936, 2680.128958088619, 2599.9350016249555, 2633.4206474575562, 2634.2229042432527, 2638.3931695709903, 2638.7493188147096, 2532.645429639852, 2550.85970271635, 2570.742925707433, 2571.9357016074746, 2579.058643595093, 16167.676646467122, 16591.0, 16601.750973735405, 16602.169891505375, 16608.584785380463, 8361.414700970934, 8628.439151455135, 8650.394472386935, 8685.044645089036, 8690.130986901322, 5545.417731138213, 5679.091345384719, 5864.560157988168, 6020.337762846083, 6043.425874541911, 1969.3107412405686, 1971.4775584470126, 1977.7725561560317, 2025.6252593270292, 2050.3131450963947, 28956.565651515244, 28976.0, 29216.561751573743, 29336.559951600742, 30156.69843301561, 7136.286371362822, 7291.963540182265, 7301.379382752525, 7330.120385553768, 7400.296971787671, 18702.532436688918, 18738.625227495373, 18766.624667506574, 18774.436766897015, 18781.90609046946, 8163.959180204061, 8242.835143297221, 8245.711400101087, 8259.463134896212, 8347.833043339098, 7580.734674286484, 7618.771436856903, 7743.9612801936755, 7769.805754856174, 7802.258785415376, 5573.972130139324, 5742.59801813869, 5743.8564035899435, 5795.623284486495, 5807.738651760681, 1650.983490165107, 1661.7258152404959, 1682.1841406917636, 1691.053010314228, 1711.5464401933498, 891.8483857744069, 912.7946212102356, 920.1488623023677, 924.9917589827121, 927.4157280912982, 8063.919360806317, 8205.83588328242, 8234.217749313806, 8234.629441675139, 8303.709370172015, 741.8034220931457, 751.6109494921426, 763.7326935572617, 767.2212704105337, 780.4107898536569, 4794.137055330004, 5335.253064570963, 5424.538914192338, 5429.348478182565, 5434.989054049947, 23726.644100338508, 24375.0, 24667.62998555023, 24699.87650061738, 24791.25626231216, 23474.413139671644, 24114.758852411604, 24531.264062078168, 25136.37159071038, 25142.24573262805, 10597.788044239227, 10849.78300433987, 11800.46897889597, 12058.638240852788, 12482.937585312196, 9231.169194772234, 9270.953645231732, 9404.670836520963, 9476.526173691425, 9684.70945871625, 20737.68893466569, 21096.156153753676, 21123.577528449652, 21205.151793928686, 21346.679799802703, 21558.0, 21712.67430988568, 21791.891040544695, 21899.67150492776, 21955.890220549434, 12103.576374826838, 12251.754964900474, 12262.509499619915, 12263.0, 12268.754624907631, 15375.846241537442, 15396.538103856903, 15530.534083977498, 15547.455839045579, 15630.06219626826, 12156.392180390769, 12244.632661020183, 12366.81449778272, 12605.87394126047, 12753.744925101631, 11752.882471175179, 11846.348450834943, 11916.582919597773, 12112.939435302767, 12142.75714485723, 9273.489958052372, 9410.058994100535, 9445.102715242041, 9496.287778416785, 9501.42991420517, 7644.877931785828, 7852.646630901738, 7921.485103468256, 7942.0, 7964.0443146820435, 7972.681092756225, 8008.559529225949, 8141.185881411811, 8196.959015204886, 8200.0], "value": 7052.445988727509, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [555.572209398763, 558.2045585041317, 577.3187638586467, 581.2211636407214, 587.213134399904, 6183.350748171439, 6261.937380626192, 6280.842978925518, 6289.0, 6301.180846489961, 6075.969620151893, 6168.814935551941, 6194.566380353366, 6261.0, 6277.68611569422, 5047.2176812593925, 5076.492350764931, 5080.110980578384, 5108.540231379176, 5116.386033675964, 4880.292357608145, 4995.6752811067345, 5018.121828679985, 5027.120253955562, 5036.546710796028, 4932.457429682732, 4944.233643785219, 4977.50224977503, 4977.975110124463, 5006.749662516878, 4854.660173787836, 4915.827946021908, 4952.5047495250365, 4967.751612419364, 4981.5018498150075, 4496.572825581565, 4505.707129036602, 4509.210888094603, 4524.411826462539, 4540.387047748544, 6130.877382452326, 6193.504519638418, 6222.81331560054, 6224.234338225399, 6253.53098517613, 5767.61577221468, 5802.274715660543, 5805.796797112123, 5812.651240925558, 5840.299164100293, 6530.2816690164045, 6572.835679108012, 6595.967020164917, 6622.205335359788, 6625.701843417058, 5029.170186919154, 5039.496050394968, 5041.294218809333, 5047.545720885138, 5048.469910659402, 6658.6004839709285, 6681.864083105884, 6721.932780672179, 6742.898856517108, 6904.206016308139, 5099.6175286852895, 5100.1074811907765, 5102.4642412546145, 5116.360454943132, 5181.844544663666, 5179.715115668674, 5193.324867767166, 5199.350081239845, 5224.9738751306, 5266.4470230626, 5778.624389414675, 5793.420657934257, 5835.9708201458725, 5841.299044114733, 5871.266091738533, 6543.1821022372205, 6547.116139321226, 6584.868302634016, 6609.405153536205, 6657.46740260778, 1762.4712586224082, 1774.7426623139572, 1801.25248022072, 1804.1340156724846, 1810.1311370542214, 1240.0, 1266.7909794883742, 1275.661949583361, 1299.13607451044, 1310.7247478029722, 6073.605215660968, 6151.5386346024225, 6209.565330426827, 6260.248770147611, 6309.466698648771, 6788.0, 6803.863922721518, 6813.659317034128, 6828.9317106829285, 6836.4530837532875, 6212.689365531705, 6254.718537665725, 6262.843428914313, 6274.874502509924, 6307.5584709070845, 3525.6121826599056, 3583.050491619723, 3585.928281434357, 3588.551431071116, 3599.6400359963795, 6793.898091528631, 6930.584164950119, 6989.650517474106, 7021.613811240431, 7101.325374089453, 6856.725730970805, 6884.896726549106, 6912.205096413977, 6983.854208296768, 7013.859722805618, 6807.183138023469, 6822.658867056726, 6946.930530694628, 6973.930260697328, 7062.329078737512, 6647.368499992493, 6669.1330127083165, 6749.763758268437, 6769.458443324621, 6771.848785706343, 6694.866102677822, 6721.764738234138, 6785.932140678728, 6807.149106361705, 6822.420094291842, 7005.159380874327, 7055.823604409931, 7067.187273463617, 7068.893966590608, 7071.646417679199, 5134.922976155286, 5178.093833579184, 5179.92230116541, 5203.531682148473, 5214.243934629533, 6557.540972131905, 6689.76585819494, 6760.830979225559, 6772.322767723188, 6860.382565568923, 6306.369363063841, 6421.903671445026, 6432.903506447501, 6497.122888410004, 6502.439414540381, 6334.493240540838, 6440.67796610177, 6446.1628435895545, 6448.806535803933, 6462.127612772404, 6336.4614007808, 6384.776532821329, 6402.903956440564, 6441.710123044568, 6479.838004049937, 6514.413702766774, 6548.639824809491, 6567.0, 6569.37590928861, 6592.670366481752, 6350.237971443456, 6376.617402955838, 6413.71138298787, 6449.290578036506, 6472.92866282785], "value": 5164.233946184309, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [943.9480828554426, 970.1462712812726, 986.2997271936924, 990.3661656539821, 997.6109317366227, 19098.0, 19535.0, 19546.902265488672, 19613.31353402631, 19670.40988770339, 16626.33494660212, 16818.0, 17002.404915827916, 17659.20533575989, 17713.0, 897.7306807957602, 961.1493827962252, 971.0, 971.4220039076738, 973.4062222044547, 1003.2225025605178, 1037.8028174646831, 1048.4967215736451, 1049.2917280835443, 1051.2273478992934, 1111.183280288987, 1113.6436340371085, 1114.2089116727134, 1115.0577761791274, 1115.4367044642486, 1036.9585216591327, 1106.9391183484904, 1108.0415440643826, 1110.0897264243324, 1112.4382186995556, 785.3520845302648, 786.657803855319, 788.613579346122, 789.0491957191571, 789.2305002622454, 18966.051697415212, 18989.620207595908, 19024.619507609772, 19054.333098341493, 19096.80903190964, 22054.117835286568, 22255.777442225695, 22729.77270227293, 22777.202797901995, 22804.31587052391, 17280.0, 17502.912485437617, 17540.91229543844, 17634.294628214855, 17656.646867062715, 4017.0961533654986, 4019.3970904364287, 4026.6376026157786, 4035.1727895781296, 4037.1118353962083, 139259.0, 139344.0, 140901.0, 142860.28569857188, 145439.0, 16439.506814795575, 16973.660526789394, 17031.14844257782, 17069.146542673065, 17105.486835394957, 31496.37007259888, 31656.208594785316, 31786.36427271488, 31819.84090079535, 31944.68055319417, 9241.21449676785, 9362.06379362072, 9365.063493650581, 9374.156325930699, 9408.482533460588, 4515.164694531522, 4581.427321584802, 4592.15045216636, 4668.789904454307, 4670.719756814602, 5877.882442351129, 5972.820815375546, 5984.611000284967, 6002.429769171922, 6005.489533389711, 1025.6051420203275, 1053.5311786255043, 1067.893210678926, 1074.7366895110747, 1078.1051727066515, 720.7621484909968, 727.6289092562831, 738.1400668221569, 739.4454159380465, 753.8906858505486, 12672.0, 12892.0, 12940.417681204368, 13395.129316594584, 13547.729045419037, 706.2372637551432, 709.4643544124153, 710.5878590417545, 718.3786025088261, 724.7535837815168, 6016.789412370546, 6082.75668973245, 6105.297890742532, 6197.504199664016, 6273.498120150468, 146331.0, 148077.0, 148415.0, 149624.0, 157815.0, 104515.47742261393, 106103.46948265209, 127563.0, 128936.0, 133990.33004835108, 108030.3795443069, 109670.45164774284, 110921.0, 113112.43443782728, 114664.42667786608, 13349.466021359036, 14291.928540357232, 14313.713725725636, 14337.475670010397, 14462.20457874785, 52395.21407178819, 53084.73457632687, 54845.72577137089, 55595.44404555903, 55819.72090139523, 73033.0, 73277.6336118316, 74428.62785686037, 75129.24870751222, 76164.23835761793, 19064.42806715801, 19423.3201837935, 19971.500712482306, 20077.598448030665, 20267.0, 21459.46351341229, 21605.891970540677, 21909.342719718436, 21946.561068778217, 21987.34037978863, 18240.726389103907, 18844.152013159168, 18881.0, 18921.810781892003, 19127.808721912603, 17072.0, 17098.145092745563, 17157.227924743165, 17370.652586947937, 17398.13009349553, 19828.801711983273, 19847.404577862686, 19862.60274794525, 20019.39941801748, 20135.0, 14489.637759056108, 14589.489367872073, 14681.92659036698, 14689.118652880861, 14694.853051469348, 15316.5449282318, 15661.686766264838, 15862.60343491422, 15892.0, 16082.67834643324], "value": 9864.094599092947, "unit": "score"}]}, {"extraOptions": ["e10s", "stylo_disabled"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1395.3, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 106.0, 110.0, 106.0, 109.0, 109.0, 107.0, 108.0, 106.0, 107.0], "value": 107.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 112.0, 113.0, 116.0, 116.0, 133.0, 116.0, 112.0, 113.0, 113.0], "value": 116.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 160.0, 163.0, 162.0, 163.0, 161.0, 162.0, 161.0, 166.0, 168.0], "value": 162.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 72.0, 73.0, 72.0, 73.0, 106.0, 73.0, 75.0, 73.0, 82.0], "value": 77.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 78.0, 81.0, 81.0, 81.0, 78.0, 85.0, 79.0, 77.0, 78.0], "value": 79.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 111.0, 113.0, 114.0, 114.0, 111.0, 115.0, 114.0, 114.0], "value": 113.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 150.0, 151.0, 157.0, 150.0, 149.0, 157.0, 152.0, 151.0, 154.0], "value": 152.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 93.0, 93.0, 93.0, 94.0, 94.0, 97.0, 93.0], "value": 93.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [48.0, 47.0, 45.0, 48.0, 46.0, 46.0, 47.0, 47.0, 47.0, 49.0], "value": 47.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [37.0, 37.0, 37.0, 36.0, 37.0, 37.0, 37.0, 36.0, 37.0, 37.0], "value": 36.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 76.0, 79.0, 77.0, 72.0, 71.0, 72.0, 72.0, 73.0, 80.0], "value": 74.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [119.0, 116.0, 111.0, 115.0, 118.0, 118.0, 116.0, 107.0, 110.0, 108.0], "value": 113.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 158.0, 158.0, 158.0, 161.0, 156.0, 162.0, 154.0, 173.0, 156.0], "value": 160.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 59.0, 59.0, 59.0, 59.0, 61.0, 59.0, 61.0, 61.0, 59.0], "value": 59.8, "unit": "score"}]}]} 16:44:55 INFO - Return code: 0 16:44:55 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:44:55 INFO - # TBPL SUCCESS # 16:44:55 INFO - Running post-action listener: _package_coverage_data 16:44:55 INFO - Running post-action listener: _resource_record_post_action 16:44:55 INFO - [mozharness: 2017-09-07 23:44:55.836644Z] Finished run-tests step (success) 16:44:55 INFO - Running post-run listener: _resource_record_post_run 16:44:55 INFO - Total resource usage - Wall time: 1184s; CPU: 13.0%; Read bytes: 4096; Write bytes: 375406592; Read time: 16; Write time: 229160 16:44:55 INFO - TinderboxPrint: CPU usage
13.1% 16:44:55 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 16 16:44:55 INFO - TinderboxPrint: I/O write bytes / time
375,406,592 / 229,160 16:44:55 INFO - TinderboxPrint: CPU idle
8,205.9 (86.9%) 16:44:55 INFO - TinderboxPrint: CPU user
1,184.2 (12.5%) 16:44:55 INFO - TinderboxPrint: Swap in / out
0 / 0 16:44:55 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 108126208; Read time: 0; Write time: 62032 16:44:55 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 16:44:55 INFO - run-tests - Wall time: 1168s; CPU: 13.0%; Read bytes: 4096; Write bytes: 267280384; Read time: 16; Write time: 167128 16:44:56 INFO - Running post-run listener: _upload_blobber_files 16:44:56 INFO - Blob upload gear active. 16:44:56 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:44:56 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 16:44:56 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'] 16:44:56 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 16:44:56 INFO - (blobuploader) - INFO - Open directory for files ... 16:44:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-stylo-disabled-e10s_errorsummary.log ... 16:44:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:44:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:44:57 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-stylo-disabled-e10s_errorsummary.log: uploaded 16:44:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:44:57 INFO - (blobuploader) - INFO - Done attempting. 16:44:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:44:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:44:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:45:04 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:45:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:45:04 INFO - (blobuploader) - INFO - Done attempting. 16:45:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 16:45:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:45:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:45:05 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 16:45:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:45:05 INFO - (blobuploader) - INFO - Done attempting. 16:45:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-stylo-disabled-e10s_raw.log ... 16:45:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:45:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:45:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-stylo-disabled-e10s_raw.log: uploaded 16:45:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:45:06 INFO - (blobuploader) - INFO - Done attempting. 16:45:06 INFO - (blobuploader) - INFO - Iteration through files over. 16:45:06 INFO - Return code: 0 16:45:06 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:45:06 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:45:06 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/87741721eb7240f3f108edb22ce2e342d47a456320d1a7d7fb34c47e6b7c9bdbb3fdf52f0baf28440854ecc39e7fe7ac6df558c740770ed27e86894d9e39b376", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5b112a9df8dcdd8388f4ac72ebea15f70a09bbacbfe8c7cea7f5b1bb6add8cd468d116148983dcd9325fd5dbc836a59c95aed6b709d0101101b44bbc3f5895bf", "dromaeojs-stylo-disabled-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/477a2422bdcbde00594a75de267ccbe47bfa3220fed3a52627040eebe9bff0599f8e68006fb7f3876c7f7e23d3387acfcd19f2f5fd5870cae45a2e24991b2aab", "dromaeojs-stylo-disabled-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e0aca74c51bb2c6780c8d9aaa5403d981a0058bb27223bc3eeaffb5160d20d1fcff1dba1969c14f061b277d1e020d2ac898504e91336a6d3f0ba3f53a3a7a97e"} 16:45:06 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:45:06 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:45:06 INFO - Contents: 16:45:06 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/87741721eb7240f3f108edb22ce2e342d47a456320d1a7d7fb34c47e6b7c9bdbb3fdf52f0baf28440854ecc39e7fe7ac6df558c740770ed27e86894d9e39b376", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5b112a9df8dcdd8388f4ac72ebea15f70a09bbacbfe8c7cea7f5b1bb6add8cd468d116148983dcd9325fd5dbc836a59c95aed6b709d0101101b44bbc3f5895bf", "dromaeojs-stylo-disabled-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/477a2422bdcbde00594a75de267ccbe47bfa3220fed3a52627040eebe9bff0599f8e68006fb7f3876c7f7e23d3387acfcd19f2f5fd5870cae45a2e24991b2aab", "dromaeojs-stylo-disabled-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e0aca74c51bb2c6780c8d9aaa5403d981a0058bb27223bc3eeaffb5160d20d1fcff1dba1969c14f061b277d1e020d2ac898504e91336a6d3f0ba3f53a3a7a97e"} 16:45:06 INFO - Running post-run listener: copy_logs_to_upload_dir 16:45:06 INFO - Copying logs to upload dir... 16:45:06 INFO - mkdir: /builds/slave/test/build/upload/logs 16:45:06 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1245.902658 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 45 secs) (at 2017-09-07 16:45:06.332671) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-07 16:45:06.336525) ========= 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=4300f881736b422476174874000001bc-1504826657.945308-1168561671 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/87741721eb7240f3f108edb22ce2e342d47a456320d1a7d7fb34c47e6b7c9bdbb3fdf52f0baf28440854ecc39e7fe7ac6df558c740770ed27e86894d9e39b376", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5b112a9df8dcdd8388f4ac72ebea15f70a09bbacbfe8c7cea7f5b1bb6add8cd468d116148983dcd9325fd5dbc836a59c95aed6b709d0101101b44bbc3f5895bf", "dromaeojs-stylo-disabled-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/477a2422bdcbde00594a75de267ccbe47bfa3220fed3a52627040eebe9bff0599f8e68006fb7f3876c7f7e23d3387acfcd19f2f5fd5870cae45a2e24991b2aab", "dromaeojs-stylo-disabled-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e0aca74c51bb2c6780c8d9aaa5403d981a0058bb27223bc3eeaffb5160d20d1fcff1dba1969c14f061b277d1e020d2ac898504e91336a6d3f0ba3f53a3a7a97e"} build_url:https://queue.taskcluster.net/v1/task/Jlx0Q_90SDmX-HVjCVREGw/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011380 build_url: 'https://queue.taskcluster.net/v1/task/Jlx0Q_90SDmX-HVjCVREGw/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/87741721eb7240f3f108edb22ce2e342d47a456320d1a7d7fb34c47e6b7c9bdbb3fdf52f0baf28440854ecc39e7fe7ac6df558c740770ed27e86894d9e39b376", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5b112a9df8dcdd8388f4ac72ebea15f70a09bbacbfe8c7cea7f5b1bb6add8cd468d116148983dcd9325fd5dbc836a59c95aed6b709d0101101b44bbc3f5895bf", "dromaeojs-stylo-disabled-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/477a2422bdcbde00594a75de267ccbe47bfa3220fed3a52627040eebe9bff0599f8e68006fb7f3876c7f7e23d3387acfcd19f2f5fd5870cae45a2e24991b2aab", "dromaeojs-stylo-disabled-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e0aca74c51bb2c6780c8d9aaa5403d981a0058bb27223bc3eeaffb5160d20d1fcff1dba1969c14f061b277d1e020d2ac898504e91336a6d3f0ba3f53a3a7a97e"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-07 16:45:06.364805) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-07 16:45:06.365173) ========= 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=4300f881736b422476174874000001bc-1504826657.945308-1168561671 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004525 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-07 16:45:06.448455) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-07 16:45:06.448782) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-07 16:45:06.449092) ========= ========= Total master_lag: 0.20 =========