builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-084 starttime: 1504826473.24 results: success (0) revision: b4c1ad9565ee9d00d96501c4a83083daf25c1413 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-07 16:21:13.240439) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-07 16:21:13.240905) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-07 16:21:13.241198) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-07 16:21:13.259514) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-07 16:21:13.259935) ========= 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=ea65d483da93c827905c2090000001f5-1504826472.457837-528075096 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-07 16:21:13-- 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% 88.3M=0s 2017-09-07 16:21:13 (88.3 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.122718 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-07 16:21:13.399838) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-07 16:21:13.400197) ========= 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=ea65d483da93c827905c2090000001f5-1504826472.457837-528075096 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020742 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-07 16:21:13.450284) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-09-07 16:21:13.450615) ========= 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=ea65d483da93c827905c2090000001f5-1504826472.457837-528075096 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-07 16:21:13,526 truncating revision to first 12 chars 2017-09-07 16:21:13,526 Setting DEBUG logging. 2017-09-07 16:21:13,526 attempt 1/10 2017-09-07 16:21:13,526 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:21:14,894 unpacking tar archive at: mozilla-central-b4c1ad9565ee/testing/mozharness/ program finished with exit code 0 elapsedTime=1.686697 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-09-07 16:21:15.153907) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-07 16:21:15.154488) ========= script_repo_revision: b4c1ad9565ee9d00d96501c4a83083daf25c1413 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-07 16:21:15.155124) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-07 16:21:15.155588) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-07 16:21:15.168808) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 50 secs) (at 2017-09-07 16:21:15.169151) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=ea65d483da93c827905c2090000001f5-1504826472.457837-528075096 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 16:21:15 INFO - MultiFileLogger online at 20170907 16:21:15 in /builds/slave/test 16:21:15 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 16:21:15 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:21:15 INFO - {'append_to_log': False, 16:21:15 INFO - 'base_work_dir': '/builds/slave/test', 16:21:15 INFO - 'blob_upload_branch': 'Firefox', 16:21:15 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:21:15 INFO - 'branch': 'Firefox', 16:21:15 INFO - 'buildbot_json_path': 'buildprops.json', 16:21:15 INFO - 'code_coverage': False, 16:21:15 INFO - 'config_files': ('talos/linux_config.py',), 16:21:15 INFO - 'default_actions': ('clobber', 16:21:15 INFO - 'read-buildbot-config', 16:21:15 INFO - 'download-and-extract', 16:21:15 INFO - 'populate-webroot', 16:21:15 INFO - 'create-virtualenv', 16:21:15 INFO - 'install', 16:21:15 INFO - 'setup-mitmproxy', 16:21:15 INFO - 'run-tests'), 16:21:15 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:21:15 INFO - 'disable_ccov_upload': False, 16:21:15 INFO - 'disable_stylo': False, 16:21:15 INFO - 'download_minidump_stackwalk': True, 16:21:15 INFO - 'download_symbols': 'ondemand', 16:21:15 INFO - 'enable_stylo': False, 16:21:15 INFO - 'enable_webrender': False, 16:21:15 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 16:21:15 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:21:15 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:21:15 INFO - 'gecko_profile': False, 16:21:15 INFO - 'gecko_profile_interval': 0, 16:21:15 INFO - 'installer_path': 'installer.exe', 16:21:15 INFO - 'log_level': 'info', 16:21:15 INFO - 'log_name': 'talos', 16:21:15 INFO - 'log_to_console': True, 16:21:15 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 16:21:15 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 16:21:15 INFO - 'opt_config_files': (), 16:21:15 INFO - 'pip_index': False, 16:21:15 INFO - 'suite': 'dromaeojs-e10s', 16:21:15 INFO - 'system_bits': '32', 16:21:15 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:21:15 INFO - 'title': 'talos-linux64-ix-084', 16:21:15 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:21:15 INFO - 'use_talos_json': True, 16:21:15 INFO - 'verify': 'False', 16:21:15 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:21:15 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:21:15 INFO - 'work_dir': 'build'} 16:21:15 INFO - [mozharness: 2017-09-07 23:21:15.384629Z] Running clobber step. 16:21:15 INFO - Running pre-action listener: _resource_record_pre_action 16:21:15 INFO - Running main action method: clobber 16:21:15 INFO - rmtree: /builds/slave/test/build 16:21:15 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:21:16 INFO - Running post-action listener: _resource_record_post_action 16:21:16 INFO - [mozharness: 2017-09-07 23:21:16.406888Z] Finished clobber step (success) 16:21:16 INFO - [mozharness: 2017-09-07 23:21:16.406986Z] Running read-buildbot-config step. 16:21:16 INFO - Running pre-action listener: _resource_record_pre_action 16:21:16 INFO - Running main action method: read_buildbot_config 16:21:16 INFO - Using buildbot properties: 16:21:16 INFO - { 16:21:16 INFO - "project": "", 16:21:16 INFO - "product": "firefox", 16:21:16 INFO - "who": "archaeopteryx@coole-files.de", 16:21:16 INFO - "installer_path": "public/build/target.tar.bz2", 16:21:16 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 16:21:16 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 16:21:16 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs-e10s", 16:21:16 INFO - "stage_platform": "linux64-pgo", 16:21:16 INFO - "basedir": "/builds/slave/test", 16:21:16 INFO - "buildnumber": 164, 16:21:16 INFO - "platform": "ubuntu64_hw", 16:21:16 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 16:21:16 INFO - "slavebuilddir": "test", 16:21:16 INFO - "taskId": "If0srYX0Qgir9DieKspbqw", 16:21:16 INFO - "branch": "mozilla-central", 16:21:16 INFO - "script_repo_revision": "production", 16:21:16 INFO - "revision": "b4c1ad9565ee9d00d96501c4a83083daf25c1413", 16:21:16 INFO - "slavename": "talos-linux64-ix-084", 16:21:16 INFO - "repo_path": "mozilla-central" 16:21:16 INFO - } 16:21:16 INFO - Finding installer, test and symbols from parent task. 16:21:16 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/If0srYX0Qgir9DieKspbqw'}, attempt #1 16:21:16 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/If0srYX0Qgir9DieKspbqw'}, attempt #1 16:21:17 INFO - Task dependencies: Jlx0Q_90SDmX-HVjCVREGw 16:21:17 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Jlx0Q_90SDmX-HVjCVREGw'}, attempt #1 16:21:17 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/Jlx0Q_90SDmX-HVjCVREGw/artifacts/public/build/target.tar.bz2 16:21:17 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/Jlx0Q_90SDmX-HVjCVREGw/artifacts/public/build/target.test_packages.json 16:21:17 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/Jlx0Q_90SDmX-HVjCVREGw/artifacts/public/build/target.crashreporter-symbols.zip 16:21:17 INFO - Running post-action listener: _resource_record_post_action 16:21:17 INFO - [mozharness: 2017-09-07 23:21:17.735267Z] Finished read-buildbot-config step (success) 16:21:17 INFO - [mozharness: 2017-09-07 23:21:17.735484Z] Running download-and-extract step. 16:21:17 INFO - Running pre-action listener: _resource_record_pre_action 16:21:17 INFO - Running main action method: download_and_extract 16:21:17 INFO - mkdir: /builds/slave/test/build/tests 16:21:17 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:21:17 INFO - trying https://queue.taskcluster.net/v1/task/Jlx0Q_90SDmX-HVjCVREGw/artifacts/public/build/target.test_packages.json 16:21:17 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:21:17 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:21:18 INFO - Downloaded 989 bytes. 16:21:18 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 16:21:18 INFO - Using the following test package requirements: 16:21:18 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 16:21:18 INFO - u'common': [u'target.common.tests.zip'], 16:21:18 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 16:21:18 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 16:21:18 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 16:21:18 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 16:21:18 INFO - u'mozbase': [u'target.common.tests.zip'], 16:21:18 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 16:21:18 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 16:21:18 INFO - u'web-platform': [u'target.common.tests.zip', 16:21:18 INFO - u'target.web-platform.tests.tar.gz'], 16:21:18 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 16:21:18 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 16:21:18 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:21:18 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:21:18 INFO - Fetch https://queue.taskcluster.net/v1/task/Jlx0Q_90SDmX-HVjCVREGw/artifacts/public/build/target.common.tests.zip into memory 16:21:19 INFO - Content-Length response header: 55689823 16:21:19 INFO - Bytes received: 55689823 16:21:30 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 16:21:30 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:21:30 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:21:30 INFO - Fetch https://queue.taskcluster.net/v1/task/Jlx0Q_90SDmX-HVjCVREGw/artifacts/public/build/target.common.tests.zip into memory 16:21:31 INFO - Content-Length response header: 55689823 16:21:31 INFO - Bytes received: 55689823 16:21:43 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:21:43 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:21:43 INFO - Fetch https://queue.taskcluster.net/v1/task/Jlx0Q_90SDmX-HVjCVREGw/artifacts/public/build/target.talos.tests.zip into memory 16:21:44 INFO - Content-Length response header: 14496665 16:21:44 INFO - Bytes received: 14496665 16:21:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:21:44 INFO - trying https://queue.taskcluster.net/v1/task/Jlx0Q_90SDmX-HVjCVREGw/artifacts/public/build/target.tar.bz2 16:21:44 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:21:44 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:21:45 INFO - Downloaded 63416205 bytes. 16:21:45 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Jlx0Q_90SDmX-HVjCVREGw/artifacts/public/build/target.tar.bz2 16:21:45 INFO - mkdir: /builds/slave/test/properties 16:21:45 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:21:45 INFO - Writing to file /builds/slave/test/properties/build_url 16:21:45 INFO - Contents: 16:21:45 INFO - build_url:https://queue.taskcluster.net/v1/task/Jlx0Q_90SDmX-HVjCVREGw/artifacts/public/build/target.tar.bz2 16:21:45 INFO - Running post-action listener: _resource_record_post_action 16:21:45 INFO - Running post-action listener: find_tests_for_verification 16:21:45 INFO - Running post-action listener: set_extra_try_arguments 16:21:45 INFO - [mozharness: 2017-09-07 23:21:45.622816Z] Finished download-and-extract step (success) 16:21:45 INFO - [mozharness: 2017-09-07 23:21:45.622914Z] Running populate-webroot step. 16:21:45 INFO - Running pre-action listener: _resource_record_pre_action 16:21:45 INFO - Running main action method: populate_webroot 16:21:45 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:21:45 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 16:21:45 INFO - 'tests': ['tresize', 16:21:45 INFO - 'tcanvasmark']}, 16:21:45 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:21:45 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 16:21:45 INFO - 'tests': ['dromaeo_css', 16:21:45 INFO - 'kraken']}, 16:21:45 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 16:21:45 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:21:45 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 16:21:45 INFO - 'talos_options': ['--disable-stylo'], 16:21:45 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:21:45 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 16:21:45 INFO - 'tests': ['damp', 'tps']}, 16:21:45 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 16:21:45 INFO - 'talos_options': ['--disable-stylo'], 16:21:45 INFO - 'tests': ['damp', 'tps']}, 16:21:45 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:21:45 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 16:21:45 INFO - 'tests': ['dromaeo_dom']}, 16:21:45 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 16:21:45 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 16:21:45 INFO - 'tests': ['basic_compositor_video', 16:21:45 INFO - 'glvideo']}, 16:21:45 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 16:21:45 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 16:21:45 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 16:21:45 INFO - 'talos_options': ['--disable-stylo'], 16:21:45 INFO - 'tests': ['ts_paint_webext', 16:21:45 INFO - 'tp5o_webext']}, 16:21:45 INFO - 'other-e10s': {'tests': ['a11yr', 16:21:45 INFO - 'ts_paint', 16:21:45 INFO - 'tpaint', 16:21:45 INFO - 'sessionrestore', 16:21:45 INFO - 'sessionrestore_many_windows', 16:21:45 INFO - 'sessionrestore_no_auto_restore', 16:21:45 INFO - 'tabpaint']}, 16:21:45 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 16:21:45 INFO - 'tests': ['a11yr', 16:21:45 INFO - 'ts_paint', 16:21:45 INFO - 'tpaint', 16:21:45 INFO - 'sessionrestore', 16:21:45 INFO - 'sessionrestore_many_windows', 16:21:45 INFO - 'sessionrestore_no_auto_restore', 16:21:45 INFO - 'tabpaint']}, 16:21:45 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 16:21:45 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 16:21:45 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 16:21:45 INFO - 'tests': ['bloom_basic_singleton']}, 16:21:45 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 16:21:45 INFO - 'tests': ['bloom_basic']}, 16:21:45 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:21:45 INFO - 'tsvgr_opacity', 16:21:45 INFO - 'tart', 16:21:45 INFO - 'tscrollx', 16:21:45 INFO - 'tsvg_static']}, 16:21:45 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 16:21:45 INFO - 'tests': ['tsvgx', 16:21:45 INFO - 'tsvgr_opacity', 16:21:45 INFO - 'tart', 16:21:45 INFO - 'tscrollx', 16:21:45 INFO - 'tsvg_static']}, 16:21:45 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 16:21:45 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 16:21:45 INFO - 'talos_options': ['--disable-stylo'], 16:21:45 INFO - 'tests': ['tp5o']}, 16:21:45 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 16:21:45 INFO - 'talos_options': ['--mitmproxy', 16:21:45 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 16:21:45 INFO - '--firstNonBlankPaint'], 16:21:45 INFO - 'tests': ['tp6_google', 16:21:45 INFO - 'tp6_youtube', 16:21:45 INFO - 'tp6_amazon', 16:21:45 INFO - 'tp6_facebook']}, 16:21:45 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 16:21:45 INFO - 'talos_options': ['--disable-stylo', 16:21:45 INFO - '--mitmproxy', 16:21:45 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 16:21:45 INFO - '--firstNonBlankPaint'], 16:21:45 INFO - 'tests': ['tp6_google', 16:21:45 INFO - 'tp6_youtube', 16:21:45 INFO - 'tp6_amazon', 16:21:45 INFO - 'tp6_facebook']}, 16:21:45 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 16:21:45 INFO - 'talos_options': ['--stylo-threads=1', 16:21:45 INFO - '--mitmproxy', 16:21:45 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 16:21:45 INFO - '--firstNonBlankPaint'], 16:21:45 INFO - 'tests': ['tp6_google', 16:21:45 INFO - 'tp6_youtube', 16:21:45 INFO - 'tp6_amazon', 16:21:45 INFO - 'tp6_facebook']}, 16:21:45 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 16:21:45 INFO - 'talos_options': ['--xperf_path', 16:21:45 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:21:45 INFO - 'tests': ['tp5n']}, 16:21:45 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 16:21:45 INFO - 'talos_options': ['--disable-stylo', 16:21:45 INFO - '--xperf_path', 16:21:45 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:21:45 INFO - 'tests': ['tp5n']}}} 16:21:45 INFO - Running post-action listener: _resource_record_post_action 16:21:45 INFO - [mozharness: 2017-09-07 23:21:45.638056Z] Finished populate-webroot step (success) 16:21:45 INFO - [mozharness: 2017-09-07 23:21:45.638146Z] Running create-virtualenv step. 16:21:45 INFO - Running pre-action listener: _resource_record_pre_action 16:21:45 INFO - Running main action method: create_virtualenv 16:21:45 INFO - Creating virtualenv /builds/slave/test/build/venv 16:21:45 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 16:21:45 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 16:21:45 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 16:21:45 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:21:45 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:21:46 INFO - Installing distribute.............................................................................................................................................................................................done. 16:21:46 INFO - Installing pip...............done. 16:21:46 INFO - Return code: 0 16:21:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 16:21:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 16:21:46 INFO - Reading from file tmpfile_stdout 16:21:46 INFO - Output received: 16:21:46 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:21:46 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:21:46 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:21:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:21:46 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:21:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:21:46 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 0x16661f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x182fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19d2a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19d6090>, '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 0x19d6d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19d0f90>, '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': 'ea65d483da93c827905c2090000001f5-1504826472.457837-528075096', '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:21:46 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:21:46 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:21:46 INFO - Using env: {'DISPLAY': ':0', 16:21:46 INFO - 'HOME': '/home/cltbld', 16:21:46 INFO - 'LANG': 'en_US.UTF-8', 16:21:46 INFO - 'LANGUAGE': 'en_US:en', 16:21:46 INFO - 'LOGNAME': 'cltbld', 16:21:46 INFO - 'MAIL': '/var/mail/cltbld', 16:21:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:21:46 INFO - 'MOZ_NO_REMOTE': '1', 16:21:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:21:46 INFO - 'NO_EM_RESTART': '1', 16:21:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:21:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:21:46 INFO - 'PWD': '/builds/slave/test', 16:21:46 INFO - 'SHELL': '/bin/bash', 16:21:46 INFO - 'SHLVL': '1', 16:21:46 INFO - 'TERM': 'linux', 16:21:46 INFO - 'TMOUT': '86400', 16:21:46 INFO - 'USER': 'cltbld', 16:21:46 INFO - 'XDG_SESSION_COOKIE': 'ea65d483da93c827905c2090000001f5-1504826472.457837-528075096', 16:21:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:21:46 INFO - '_': '/tools/buildbot/bin/python'} 16:21:47 INFO - Ignoring indexes: http://pypi.python.org/simple/ 16:21:47 INFO - Downloading/unpacking pip>=1.5 16:21:47 INFO - Running setup.py egg_info for package pip 16:21:47 INFO - warning: no files found matching 'pip/cacert.pem' 16:21:47 INFO - warning: no files found matching '*.html' under directory 'docs' 16:21:47 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 16:21:47 INFO - no previously-included directories found matching 'docs/_build/_sources' 16:21:47 INFO - Installing collected packages: pip 16:21:47 INFO - Found existing installation: pip 1.1 16:21:47 INFO - Uninstalling pip: 16:21:47 INFO - Successfully uninstalled pip 16:21:47 INFO - Running setup.py install for pip 16:21:47 INFO - warning: no files found matching 'pip/cacert.pem' 16:21:47 INFO - warning: no files found matching '*.html' under directory 'docs' 16:21:47 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 16:21:47 INFO - no previously-included directories found matching 'docs/_build/_sources' 16:21:47 INFO - Installing pip script to /builds/slave/test/build/venv/bin 16:21:47 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 16:21:47 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 16:21:47 INFO - Successfully installed pip 16:21:47 INFO - Cleaning up... 16:21:47 INFO - Return code: 0 16:21:47 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:21:47 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:21:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:21:47 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:21:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:21:47 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 0x16661f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x182fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19d2a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19d6090>, '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 0x19d6d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19d0f90>, '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': 'ea65d483da93c827905c2090000001f5-1504826472.457837-528075096', '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:21:47 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:21:47 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:21:47 INFO - Using env: {'DISPLAY': ':0', 16:21:47 INFO - 'HOME': '/home/cltbld', 16:21:47 INFO - 'LANG': 'en_US.UTF-8', 16:21:47 INFO - 'LANGUAGE': 'en_US:en', 16:21:47 INFO - 'LOGNAME': 'cltbld', 16:21:47 INFO - 'MAIL': '/var/mail/cltbld', 16:21:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:21:47 INFO - 'MOZ_NO_REMOTE': '1', 16:21:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:21:47 INFO - 'NO_EM_RESTART': '1', 16:21:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:21:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:21:47 INFO - 'PWD': '/builds/slave/test', 16:21:47 INFO - 'SHELL': '/bin/bash', 16:21:47 INFO - 'SHLVL': '1', 16:21:47 INFO - 'TERM': 'linux', 16:21:47 INFO - 'TMOUT': '86400', 16:21:47 INFO - 'USER': 'cltbld', 16:21:47 INFO - 'XDG_SESSION_COOKIE': 'ea65d483da93c827905c2090000001f5-1504826472.457837-528075096', 16:21:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:21:47 INFO - '_': '/tools/buildbot/bin/python'} 16:21:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:21:48 INFO - Downloading/unpacking psutil>=3.1.1 16:21:48 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:21:48 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:21:48 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:21:48 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:21:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:21:49 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:21:49 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:21:49 INFO - Installing collected packages: psutil 16:21:49 INFO - Running setup.py install for psutil 16:21:49 INFO - building 'psutil._psutil_linux' extension 16:21:49 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:21:49 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:21:49 INFO - building 'psutil._psutil_posix' extension 16:21:49 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:21:49 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:21:49 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:21:49 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:21:49 INFO - Successfully installed psutil 16:21:49 INFO - Cleaning up... 16:21:49 INFO - Return code: 0 16:21:49 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:21:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:21:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:21:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:21:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:21:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16661f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x182fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19d2a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19d6090>, '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 0x19d6d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19d0f90>, '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': 'ea65d483da93c827905c2090000001f5-1504826472.457837-528075096', '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:21:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 16:21:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 16:21:49 INFO - Using env: {'DISPLAY': ':0', 16:21:49 INFO - 'HOME': '/home/cltbld', 16:21:49 INFO - 'LANG': 'en_US.UTF-8', 16:21:49 INFO - 'LANGUAGE': 'en_US:en', 16:21:49 INFO - 'LOGNAME': 'cltbld', 16:21:49 INFO - 'MAIL': '/var/mail/cltbld', 16:21:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:21:49 INFO - 'MOZ_NO_REMOTE': '1', 16:21:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:21:49 INFO - 'NO_EM_RESTART': '1', 16:21:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:21:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:21:49 INFO - 'PWD': '/builds/slave/test', 16:21:49 INFO - 'SHELL': '/bin/bash', 16:21:49 INFO - 'SHLVL': '1', 16:21:49 INFO - 'TERM': 'linux', 16:21:49 INFO - 'TMOUT': '86400', 16:21:49 INFO - 'USER': 'cltbld', 16:21:49 INFO - 'XDG_SESSION_COOKIE': 'ea65d483da93c827905c2090000001f5-1504826472.457837-528075096', 16:21:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:21:49 INFO - '_': '/tools/buildbot/bin/python'} 16:21:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:21:49 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:21:49 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:21:49 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:21:49 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:21:49 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:21:51 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:21:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:21:51 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:21:51 INFO - Installing collected packages: mozsystemmonitor 16:21:51 INFO - Running setup.py install for mozsystemmonitor 16:21:51 INFO - Successfully installed mozsystemmonitor 16:21:51 INFO - Cleaning up... 16:21:51 INFO - Return code: 0 16:21:51 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:21:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:21:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:21:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:21:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:21:51 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 0x16661f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x182fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19d2a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19d6090>, '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 0x19d6d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19d0f90>, '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': 'ea65d483da93c827905c2090000001f5-1504826472.457837-528075096', '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:21:51 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:21:51 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:21:51 INFO - Using env: {'DISPLAY': ':0', 16:21:51 INFO - 'HOME': '/home/cltbld', 16:21:51 INFO - 'LANG': 'en_US.UTF-8', 16:21:51 INFO - 'LANGUAGE': 'en_US:en', 16:21:51 INFO - 'LOGNAME': 'cltbld', 16:21:51 INFO - 'MAIL': '/var/mail/cltbld', 16:21:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:21:51 INFO - 'MOZ_NO_REMOTE': '1', 16:21:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:21:51 INFO - 'NO_EM_RESTART': '1', 16:21:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:21:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:21:51 INFO - 'PWD': '/builds/slave/test', 16:21:51 INFO - 'SHELL': '/bin/bash', 16:21:51 INFO - 'SHLVL': '1', 16:21:51 INFO - 'TERM': 'linux', 16:21:51 INFO - 'TMOUT': '86400', 16:21:51 INFO - 'USER': 'cltbld', 16:21:51 INFO - 'XDG_SESSION_COOKIE': 'ea65d483da93c827905c2090000001f5-1504826472.457837-528075096', 16:21:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:21:51 INFO - '_': '/tools/buildbot/bin/python'} 16:21:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:21:51 INFO - Downloading/unpacking jsonschema==2.5.1 16:21:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:21:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:21:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:21:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:21:55 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:21:55 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:21:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:21:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:21:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:21:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:21:55 INFO - Downloading functools32-3.2.3-2.tar.gz 16:21:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:21:55 INFO - warning: no files found matching '*.txt' 16:21:55 INFO - no previously-included directories found matching 'build' 16:21:55 INFO - no previously-included directories found matching 'dist' 16:21:55 INFO - no previously-included directories found matching '.git*' 16:21:55 INFO - Installing collected packages: jsonschema, functools32 16:21:55 INFO - Running setup.py install for functools32 16:21:55 INFO - warning: no files found matching '*.txt' 16:21:55 INFO - no previously-included directories found matching 'build' 16:21:55 INFO - no previously-included directories found matching 'dist' 16:21:55 INFO - no previously-included directories found matching '.git*' 16:21:55 INFO - Successfully installed jsonschema functools32 16:21:55 INFO - Cleaning up... 16:21:56 INFO - Return code: 0 16:21:56 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:21: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:21:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:21: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:21:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:21: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', '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 0x16661f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x182fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19d2a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19d6090>, '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 0x19d6d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19d0f90>, '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': 'ea65d483da93c827905c2090000001f5-1504826472.457837-528075096', '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:21: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 16:21: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 functools32==3.2.3-2 16:21:56 INFO - Using env: {'DISPLAY': ':0', 16:21:56 INFO - 'HOME': '/home/cltbld', 16:21:56 INFO - 'LANG': 'en_US.UTF-8', 16:21:56 INFO - 'LANGUAGE': 'en_US:en', 16:21:56 INFO - 'LOGNAME': 'cltbld', 16:21:56 INFO - 'MAIL': '/var/mail/cltbld', 16:21:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:21:56 INFO - 'MOZ_NO_REMOTE': '1', 16:21:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:21:56 INFO - 'NO_EM_RESTART': '1', 16:21:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:21:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:21:56 INFO - 'PWD': '/builds/slave/test', 16:21:56 INFO - 'SHELL': '/bin/bash', 16:21:56 INFO - 'SHLVL': '1', 16:21:56 INFO - 'TERM': 'linux', 16:21:56 INFO - 'TMOUT': '86400', 16:21:56 INFO - 'USER': 'cltbld', 16:21:56 INFO - 'XDG_SESSION_COOKIE': 'ea65d483da93c827905c2090000001f5-1504826472.457837-528075096', 16:21:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:21:56 INFO - '_': '/tools/buildbot/bin/python'} 16:21:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:21:56 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:21:56 INFO - Cleaning up... 16:21:56 INFO - Return code: 0 16:21:56 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:21: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:21:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:21: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:21:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:21:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16661f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x182fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19d2a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19d6090>, '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 0x19d6d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19d0f90>, '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': 'ea65d483da93c827905c2090000001f5-1504826472.457837-528075096', '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:21:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 16:21:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 16:21:56 INFO - Using env: {'DISPLAY': ':0', 16:21:56 INFO - 'HOME': '/home/cltbld', 16:21:56 INFO - 'LANG': 'en_US.UTF-8', 16:21:56 INFO - 'LANGUAGE': 'en_US:en', 16:21:56 INFO - 'LOGNAME': 'cltbld', 16:21:56 INFO - 'MAIL': '/var/mail/cltbld', 16:21:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:21:56 INFO - 'MOZ_NO_REMOTE': '1', 16:21:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:21:56 INFO - 'NO_EM_RESTART': '1', 16:21:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:21:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:21:56 INFO - 'PWD': '/builds/slave/test', 16:21:56 INFO - 'SHELL': '/bin/bash', 16:21:56 INFO - 'SHLVL': '1', 16:21:56 INFO - 'TERM': 'linux', 16:21:56 INFO - 'TMOUT': '86400', 16:21:56 INFO - 'USER': 'cltbld', 16:21:56 INFO - 'XDG_SESSION_COOKIE': 'ea65d483da93c827905c2090000001f5-1504826472.457837-528075096', 16:21:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:21:56 INFO - '_': '/tools/buildbot/bin/python'} 16:21:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:21:56 INFO - Downloading/unpacking blobuploader==1.2.4 16:21: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:21: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:21: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:21: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:21:57 INFO - Downloading blobuploader-1.2.4.tar.gz 16:21:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:21:57 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:21: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:21: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:21: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:21: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:21:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:21:57 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:21: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:21: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:21: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:21: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:21:58 INFO - Downloading docopt-0.6.1.tar.gz 16:21:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:21:58 INFO - Installing collected packages: blobuploader, requests, docopt 16:21:58 INFO - Running setup.py install for blobuploader 16:21:58 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:21:58 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:21:58 INFO - Running setup.py install for requests 16:21:58 INFO - Running setup.py install for docopt 16:21:58 INFO - Successfully installed blobuploader requests docopt 16:21:58 INFO - Cleaning up... 16:21:58 INFO - Return code: 0 16:21:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:21:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:21:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:21:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:21:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:21:58 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 0x16661f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x182fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19d2a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19d6090>, '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 0x19d6d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19d0f90>, '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': 'ea65d483da93c827905c2090000001f5-1504826472.457837-528075096', '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:21:58 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:21:58 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:21:58 INFO - Using env: {'DISPLAY': ':0', 16:21:58 INFO - 'HOME': '/home/cltbld', 16:21:58 INFO - 'LANG': 'en_US.UTF-8', 16:21:58 INFO - 'LANGUAGE': 'en_US:en', 16:21:58 INFO - 'LOGNAME': 'cltbld', 16:21:58 INFO - 'MAIL': '/var/mail/cltbld', 16:21:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:21:58 INFO - 'MOZ_NO_REMOTE': '1', 16:21:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:21:58 INFO - 'NO_EM_RESTART': '1', 16:21:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:21:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:21:58 INFO - 'PWD': '/builds/slave/test', 16:21:58 INFO - 'SHELL': '/bin/bash', 16:21:58 INFO - 'SHLVL': '1', 16:21:58 INFO - 'TERM': 'linux', 16:21:58 INFO - 'TMOUT': '86400', 16:21:58 INFO - 'USER': 'cltbld', 16:21:58 INFO - 'XDG_SESSION_COOKIE': 'ea65d483da93c827905c2090000001f5-1504826472.457837-528075096', 16:21:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:21:58 INFO - '_': '/tools/buildbot/bin/python'} 16:21:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:21:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:21:58 INFO - Running setup.py (path:/tmp/pip-193qIy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:21:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:21:59 INFO - Running setup.py (path:/tmp/pip-OGwTlQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:21:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:21:59 INFO - Running setup.py (path:/tmp/pip-MahkAe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:21:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:21:59 INFO - Running setup.py (path:/tmp/pip-PUHGeX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:21:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:21:59 INFO - Running setup.py (path:/tmp/pip-QDjcoH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:21:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:21:59 INFO - Running setup.py (path:/tmp/pip-kCmFbK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:21:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:21:59 INFO - Running setup.py (path:/tmp/pip-A5c7aM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:21:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:21:59 INFO - Running setup.py (path:/tmp/pip-Pm4zat-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:21:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:21:59 INFO - Running setup.py (path:/tmp/pip-UPbBtG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:21:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:21:59 INFO - Running setup.py (path:/tmp/pip-tz0YQW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:21:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:21:59 INFO - Running setup.py (path:/tmp/pip-dQDEgm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:21:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:21:59 INFO - Running setup.py (path:/tmp/pip-voysmq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:21:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:21:59 INFO - Running setup.py (path:/tmp/pip-roV4h6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:21:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:21:59 INFO - Running setup.py (path:/tmp/pip-PPdnle-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:21:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:21:59 INFO - Running setup.py (path:/tmp/pip-7LZSqM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:22:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:22:00 INFO - Running setup.py (path:/tmp/pip-zNGAIt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:22:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:22:00 INFO - Running setup.py (path:/tmp/pip-1Qtdkl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:22:00 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:22:00 INFO - Running setup.py install for manifestparser 16:22:00 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:22:00 INFO - Running setup.py install for mozcrash 16:22:00 INFO - Running setup.py install for mozdebug 16:22:00 INFO - Running setup.py install for mozdevice 16:22:00 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:22:00 INFO - Running setup.py install for mozfile 16:22:00 INFO - Running setup.py install for mozhttpd 16:22:00 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:22:00 INFO - Running setup.py install for mozinfo 16:22:00 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:22:00 INFO - Running setup.py install for mozInstall 16:22:00 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:22:00 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:22:00 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:22:00 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:22:00 INFO - Running setup.py install for mozleak 16:22:01 INFO - Running setup.py install for mozlog 16:22:01 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:22:01 INFO - Running setup.py install for moznetwork 16:22:01 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:22:01 INFO - Running setup.py install for mozprocess 16:22:01 INFO - Running setup.py install for mozprofile 16:22:01 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:22:01 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:22:01 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:22:01 INFO - Running setup.py install for mozrunner 16:22:01 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:22:01 INFO - Running setup.py install for mozscreenshot 16:22:01 INFO - Running setup.py install for moztest 16:22:01 INFO - Running setup.py install for mozversion 16:22:01 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:22:01 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:22:01 INFO - Cleaning up... 16:22:01 INFO - Return code: 0 16:22:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:22: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:22:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:22: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:22:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:22:01 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 0x16661f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x182fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19d2a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19d6090>, '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 0x19d6d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19d0f90>, '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': 'ea65d483da93c827905c2090000001f5-1504826472.457837-528075096', '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:22:01 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:22:01 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:22:01 INFO - Using env: {'DISPLAY': ':0', 16:22:01 INFO - 'HOME': '/home/cltbld', 16:22:01 INFO - 'LANG': 'en_US.UTF-8', 16:22:01 INFO - 'LANGUAGE': 'en_US:en', 16:22:01 INFO - 'LOGNAME': 'cltbld', 16:22:01 INFO - 'MAIL': '/var/mail/cltbld', 16:22:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:22:01 INFO - 'MOZ_NO_REMOTE': '1', 16:22:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:22:01 INFO - 'NO_EM_RESTART': '1', 16:22:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:22:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:22:01 INFO - 'PWD': '/builds/slave/test', 16:22:01 INFO - 'SHELL': '/bin/bash', 16:22:01 INFO - 'SHLVL': '1', 16:22:01 INFO - 'TERM': 'linux', 16:22:01 INFO - 'TMOUT': '86400', 16:22:01 INFO - 'USER': 'cltbld', 16:22:01 INFO - 'XDG_SESSION_COOKIE': 'ea65d483da93c827905c2090000001f5-1504826472.457837-528075096', 16:22:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:22:01 INFO - '_': '/tools/buildbot/bin/python'} 16:22:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:22:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:22:02 INFO - Running setup.py (path:/tmp/pip-1J4uhi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:22:02 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:22:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:22:02 INFO - Running setup.py (path:/tmp/pip-99gIM1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:22:02 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:22:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:22:02 INFO - Running setup.py (path:/tmp/pip-U2Ml9j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:22:02 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:22:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:22:02 INFO - Running setup.py (path:/tmp/pip-NOAio8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:22:02 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:22:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:22:02 INFO - Running setup.py (path:/tmp/pip-IAu3K1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:22:02 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:22:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:22:02 INFO - Running setup.py (path:/tmp/pip-sdNszW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:22:02 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:22:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:22:02 INFO - Running setup.py (path:/tmp/pip-16vN1Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:22:02 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:22:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:22:02 INFO - Running setup.py (path:/tmp/pip-Z6twhR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:22:02 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:22:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:22:02 INFO - Running setup.py (path:/tmp/pip-lm6A9K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:22:02 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:22:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:22:02 INFO - Running setup.py (path:/tmp/pip-Lt5GxO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:22:02 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:22:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:22:02 INFO - Running setup.py (path:/tmp/pip-S_fj8P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:22:02 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:22:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:22:02 INFO - Running setup.py (path:/tmp/pip-Np6Bmn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:22:02 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:22:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:22:03 INFO - Running setup.py (path:/tmp/pip-Vnf00z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:22:03 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:22:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:22:03 INFO - Running setup.py (path:/tmp/pip-dpYVjP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:22:03 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:22:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:22:03 INFO - Running setup.py (path:/tmp/pip-lTdExR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:22:03 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:22:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:22:03 INFO - Running setup.py (path:/tmp/pip-EN3u2d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:22:03 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:22:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:22:03 INFO - Running setup.py (path:/tmp/pip-sHN5Sh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:22:03 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:22:03 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:22:03 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:22:03 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:22:03 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:22:03 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:22:03 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:22:03 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:22:03 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:22:03 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:22:03 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:22:03 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:22:04 INFO - Downloading blessings-1.6.tar.gz 16:22:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:22:04 INFO - Installing collected packages: blessings 16:22:04 INFO - Running setup.py install for blessings 16:22:04 INFO - Successfully installed blessings 16:22:04 INFO - Cleaning up... 16:22:04 INFO - Return code: 0 16:22:04 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:22:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:22:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:22:04 INFO - Reading from file tmpfile_stdout 16:22:04 INFO - Current package versions: 16:22:04 INFO - argparse == 1.2.1 16:22:04 INFO - blessings == 1.6 16:22:04 INFO - blobuploader == 1.2.4 16:22:04 INFO - docopt == 0.6.1 16:22:04 INFO - functools32 == 3.2.3-2 16:22:04 INFO - jsonschema == 2.5.1 16:22:04 INFO - manifestparser == 1.1 16:22:04 INFO - mozInstall == 1.13 16:22:04 INFO - mozcrash == 1.0 16:22:04 INFO - mozdebug == 0.1 16:22:04 INFO - mozdevice == 0.51 16:22:04 INFO - mozfile == 1.2 16:22:04 INFO - mozhttpd == 0.7 16:22:04 INFO - mozinfo == 0.10 16:22:04 INFO - mozleak == 0.1 16:22:04 INFO - mozlog == 3.5 16:22:04 INFO - moznetwork == 0.27 16:22:04 INFO - mozprocess == 0.25 16:22:04 INFO - mozprofile == 0.28 16:22:04 INFO - mozrunner == 6.13 16:22:04 INFO - mozscreenshot == 0.1 16:22:04 INFO - mozsystemmonitor == 0.3 16:22:04 INFO - moztest == 0.8 16:22:04 INFO - mozversion == 1.4 16:22:04 INFO - psutil == 3.1.1 16:22:04 INFO - requests == 1.2.3 16:22:04 INFO - wsgiref == 0.1.2 16:22:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:22:04 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:22:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:22:04 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:22:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:22:04 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 0x16661f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x182fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19d2a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19d6090>, '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 0x19d6d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19d0f90>, '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': 'ea65d483da93c827905c2090000001f5-1504826472.457837-528075096', '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:22:04 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:22:04 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:22:04 INFO - Using env: {'DISPLAY': ':0', 16:22:04 INFO - 'HOME': '/home/cltbld', 16:22:04 INFO - 'LANG': 'en_US.UTF-8', 16:22:04 INFO - 'LANGUAGE': 'en_US:en', 16:22:04 INFO - 'LOGNAME': 'cltbld', 16:22:04 INFO - 'MAIL': '/var/mail/cltbld', 16:22:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:22:04 INFO - 'MOZ_NO_REMOTE': '1', 16:22:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:22:04 INFO - 'NO_EM_RESTART': '1', 16:22:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:22:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:22:04 INFO - 'PWD': '/builds/slave/test', 16:22:04 INFO - 'SHELL': '/bin/bash', 16:22:04 INFO - 'SHLVL': '1', 16:22:04 INFO - 'TERM': 'linux', 16:22:04 INFO - 'TMOUT': '86400', 16:22:04 INFO - 'USER': 'cltbld', 16:22:04 INFO - 'XDG_SESSION_COOKIE': 'ea65d483da93c827905c2090000001f5-1504826472.457837-528075096', 16:22:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:22:04 INFO - '_': '/tools/buildbot/bin/python'} 16:22:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:22:05 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:22:05 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:22:05 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:22:05 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:22:05 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:22:05 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:22:05 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:22:05 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:22:05 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:22:05 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 16:22:05 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:22:05 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:22:05 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:22:05 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:22:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 16:22:06 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:22:06 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:22:06 INFO - Installing collected packages: simplejson 16:22:06 INFO - Running setup.py install for simplejson 16:22:06 INFO - building 'simplejson._speedups' extension 16:22:06 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:22:07 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:22:07 INFO - Successfully installed simplejson 16:22:07 INFO - Cleaning up... 16:22:07 INFO - Return code: 0 16:22:07 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:22:07 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:22:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:22:07 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:22:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:22:07 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 0x16661f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x182fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19d2a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19d6090>, '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 0x19d6d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19d0f90>, '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': 'ea65d483da93c827905c2090000001f5-1504826472.457837-528075096', '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:22:07 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:22:07 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:22:07 INFO - Using env: {'DISPLAY': ':0', 16:22:07 INFO - 'HOME': '/home/cltbld', 16:22:07 INFO - 'LANG': 'en_US.UTF-8', 16:22:07 INFO - 'LANGUAGE': 'en_US:en', 16:22:07 INFO - 'LOGNAME': 'cltbld', 16:22:07 INFO - 'MAIL': '/var/mail/cltbld', 16:22:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:22:07 INFO - 'MOZ_NO_REMOTE': '1', 16:22:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:22:07 INFO - 'NO_EM_RESTART': '1', 16:22:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:22:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:22:07 INFO - 'PWD': '/builds/slave/test', 16:22:07 INFO - 'SHELL': '/bin/bash', 16:22:07 INFO - 'SHLVL': '1', 16:22:07 INFO - 'TERM': 'linux', 16:22:07 INFO - 'TMOUT': '86400', 16:22:07 INFO - 'USER': 'cltbld', 16:22:07 INFO - 'XDG_SESSION_COOKIE': 'ea65d483da93c827905c2090000001f5-1504826472.457837-528075096', 16:22:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:22:07 INFO - '_': '/tools/buildbot/bin/python'} 16:22:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:22:07 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:22:07 INFO - Cleaning up... 16:22:07 INFO - Return code: 0 16:22:07 INFO - Running post-action listener: _resource_record_post_action 16:22:07 INFO - Running post-action listener: _start_resource_monitoring 16:22:07 INFO - Starting resource monitoring. 16:22:07 INFO - [mozharness: 2017-09-07 23:22:07.602488Z] Finished create-virtualenv step (success) 16:22:07 INFO - [mozharness: 2017-09-07 23:22:07.602962Z] Running install step. 16:22:07 INFO - Running pre-action listener: _resource_record_pre_action 16:22:07 INFO - Running main action method: install 16:22:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:22:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:22:07 INFO - Reading from file tmpfile_stdout 16:22:07 INFO - Detecting whether we're running mozinstall >=1.0... 16:22:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:22:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:22:07 INFO - Reading from file tmpfile_stdout 16:22:07 INFO - Output received: 16:22:07 INFO - Usage: mozinstall [options] installer 16:22:07 INFO - Options: 16:22:07 INFO - -h, --help show this help message and exit 16:22:07 INFO - -d DEST, --destination=DEST 16:22:07 INFO - Directory to install application into. [default: 16:22:07 INFO - "/builds/slave/test"] 16:22:07 INFO - --app=APP Application being installed. [default: firefox] 16:22:07 INFO - mkdir: /builds/slave/test/build/application 16:22:07 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:22:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 16:22:24 INFO - Reading from file tmpfile_stdout 16:22:24 INFO - Output received: 16:22:24 INFO - /builds/slave/test/build/application/firefox/firefox 16:22:24 INFO - Running post-action listener: _resource_record_post_action 16:22:24 INFO - [mozharness: 2017-09-07 23:22:24.293164Z] Finished install step (success) 16:22:24 INFO - [mozharness: 2017-09-07 23:22:24.293309Z] Running setup-mitmproxy step. 16:22:24 INFO - Running pre-action listener: _resource_record_pre_action 16:22:24 INFO - Running main action method: setup_mitmproxy 16:22:24 INFO - Skipping: mitmproxy is not required 16:22:24 INFO - Running post-action listener: _resource_record_post_action 16:22:24 INFO - [mozharness: 2017-09-07 23:22:24.294272Z] Finished setup-mitmproxy step (success) 16:22:24 INFO - [mozharness: 2017-09-07 23:22:24.294491Z] Running run-tests step. 16:22:24 INFO - Running pre-action listener: _resource_record_pre_action 16:22:24 INFO - Running pre-action listener: _set_gcov_prefix 16:22:24 INFO - Running main action method: run_tests 16:22:24 WARNING - Try message not found. 16:22:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:22:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:22:24 INFO - Python 2.7.3 16:22:24 INFO - Return code: 0 16:22:24 INFO - grabbing minidump binary from tooltool 16:22:24 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:22:24 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 0x19d6090>, '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 0x19d6d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19d0f90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:22:24 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:22:24 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:22:24 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:22:24 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:22:24 INFO - Return code: 0 16:22:24 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 16:22:24 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:22:24 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:22:24 INFO - ENV: RUST_BACKTRACE is now full 16:22:24 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 16:22:24 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:22:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Jlx0Q_90SDmX-HVjCVREGw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-084', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 16:22:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Jlx0Q_90SDmX-HVjCVREGw/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-084 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 16:22:24 INFO - Using env: {'DISPLAY': ':0', 16:22:24 INFO - 'HOME': '/home/cltbld', 16:22:24 INFO - 'LANG': 'en_US.UTF-8', 16:22:24 INFO - 'LANGUAGE': 'en_US:en', 16:22:24 INFO - 'LOGNAME': 'cltbld', 16:22:24 INFO - 'MAIL': '/var/mail/cltbld', 16:22:24 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:22:24 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:22:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:22:24 INFO - 'MOZ_NO_REMOTE': '1', 16:22:24 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:22:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:22:24 INFO - 'NO_EM_RESTART': '1', 16:22:24 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:22:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:22:24 INFO - 'PWD': '/builds/slave/test', 16:22:24 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:22:24 INFO - 'RUST_BACKTRACE': 'full', 16:22:24 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 16:22:24 INFO - 'SHELL': '/bin/bash', 16:22:24 INFO - 'SHLVL': '1', 16:22:24 INFO - 'TERM': 'linux', 16:22:24 INFO - 'TMOUT': '86400', 16:22:24 INFO - 'USER': 'cltbld', 16:22:24 INFO - 'XDG_SESSION_COOKIE': 'ea65d483da93c827905c2090000001f5-1504826472.457837-528075096', 16:22:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:22:24 INFO - '_': '/tools/buildbot/bin/python'} 16:22:24 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Jlx0Q_90SDmX-HVjCVREGw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-084', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 16:22:24 INFO - mozversion application_buildid: 20170907220040 16:22:24 INFO - mozversion application_changeset: b4c1ad9565ee9d00d96501c4a83083daf25c1413 16:22:24 INFO - mozversion application_display_name: Nightly 16:22:24 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:22:24 INFO - mozversion application_name: Firefox 16:22:24 INFO - mozversion application_remotingname: firefox 16:22:24 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 16:22:24 INFO - mozversion application_vendor: Mozilla 16:22:24 INFO - mozversion application_version: 57.0a1 16:22:24 INFO - mozversion platform_buildid: 20170907220040 16:22:24 INFO - mozversion platform_changeset: b4c1ad9565ee9d00d96501c4a83083daf25c1413 16:22:24 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 16:22:24 INFO - mozversion platform_version: 57.0a1 16:22:24 INFO - using testdate: 1504826544 16:22:24 INFO - actual date: 1504826544 16:22:24 INFO - starting webserver on 'localhost:37937' 16:22:24 INFO - SUITE-START | Running 2 tests 16:22:24 INFO - TEST-START | dromaeo_css 16:22:24 INFO - Initialising browser for dromaeo_css test... 16:22:24 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:37937/getInfo.html -profile /tmp/tmpjjcMWX/profile 16:22:24 INFO - TEST-INFO | started process 2173 (/builds/slave/test/build/application/firefox/firefox http://localhost:37937/getInfo.html) 16:22:30 INFO - TEST-INFO | 2173: exit 0 16:22:30 INFO - Browser initialized. 16:22:30 INFO - Running cycle 1/1 for dromaeo_css test... 16:22:30 INFO - Using env: {'DISPLAY': ':0', 16:22:30 INFO - 'HOME': '/home/cltbld', 16:22:30 INFO - 'JSGC_DISABLE_POISONING': '1', 16:22:30 INFO - 'LANG': 'en_US.UTF-8', 16:22:30 INFO - 'LANGUAGE': 'en_US:en', 16:22:30 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 16:22:30 INFO - 'LOGNAME': 'cltbld', 16:22:30 INFO - 'MAIL': '/var/mail/cltbld', 16:22:30 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:22:30 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:22:30 INFO - 'MOZ_CRASHREPORTER': '1', 16:22:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:22:30 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 16:22:30 INFO - 'MOZ_NO_REMOTE': '1', 16:22:30 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:22:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:22:30 INFO - 'NO_EM_RESTART': '1', 16:22:30 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:22:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:22:30 INFO - 'PWD': '/builds/slave/test', 16:22:30 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:22:30 INFO - 'RUST_BACKTRACE': 'full', 16:22:30 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 16:22:30 INFO - 'SHELL': '/bin/bash', 16:22:30 INFO - 'SHLVL': '1', 16:22:30 INFO - 'TERM': 'linux', 16:22:30 INFO - 'TMOUT': '86400', 16:22:30 INFO - 'USER': 'cltbld', 16:22:30 INFO - 'XDG_SESSION_COOKIE': 'ea65d483da93c827905c2090000001f5-1504826472.457837-528075096', 16:22:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:22:30 INFO - '_': '/tools/buildbot/bin/python'} 16:22:30 INFO - TEST-INFO | started process 2372 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpjjcMWX/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 16:22:31 INFO - PID 2372 | 16:22:31 INFO - PID 2372 | (/builds/slave/test/build/application/firefox/firefox:2423): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 16:22:31 INFO - PID 2372 | 16:22:41 INFO - PID 2372 | RSS: Main: 159858688 16:22:41 INFO - PID 2372 | 16:25:39 INFO - PID 2372 | Cycle 1(1): loaded http://localhost:37937/tests/dromaeo/cssquery-dojo.html (next: http://localhost:37937/tests/dromaeo/cssquery-ext.html) 16:25:40 INFO - PID 2372 | RSS: Main: 166989824 16:25:40 INFO - PID 2372 | 16:28:42 INFO - PID 2372 | Cycle 1(1): loaded http://localhost:37937/tests/dromaeo/cssquery-ext.html (next: http://localhost:37937/tests/dromaeo/cssquery-jquery.html) 16:28:43 INFO - PID 2372 | RSS: Main: 163270656 16:28:43 INFO - PID 2372 | 16:31:44 INFO - PID 2372 | Cycle 1(1): loaded http://localhost:37937/tests/dromaeo/cssquery-jquery.html (next: http://localhost:37937/tests/dromaeo/cssquery-mootools.html) 16:31:45 INFO - PID 2372 | RSS: Main: 165781504 16:31:45 INFO - PID 2372 | 16:34:46 INFO - PID 2372 | Cycle 1(1): loaded http://localhost:37937/tests/dromaeo/cssquery-mootools.html (next: http://localhost:37937/tests/dromaeo/cssquery-prototype.html) 16:34:47 INFO - PID 2372 | RSS: Main: 164605952 16:34:47 INFO - PID 2372 | 16:37:50 INFO - PID 2372 | Cycle 1(1): loaded http://localhost:37937/tests/dromaeo/cssquery-prototype.html (next: http://localhost:37937/tests/dromaeo/cssquery-yui.html) 16:37:51 INFO - PID 2372 | RSS: Main: 165965824 16:37:51 INFO - PID 2372 | 16:40:52 INFO - PID 2372 | Cycle 1(1): loaded http://localhost:37937/tests/dromaeo/cssquery-yui.html (next: http://localhost:37937/tests/dromaeo/cssquery-dojo.html) 16:40:53 INFO - PID 2372 | RSS: Main: 167915520 16:40:53 INFO - PID 2372 | 16:40:53 INFO - PID 2372 | __start_tp_report 16:40:53 INFO - PID 2372 | _x_x_mozilla_page_load 16:40:53 INFO - PID 2372 | _x_x_mozilla_page_load_details 16:40:53 INFO - PID 2372 | |i|pagename|runs| 16:40:53 INFO - PID 2372 | |0;dojo.html;3767.7362584619077;3799.0312470320073;3857.9614203857955;3860.7683538987662;3864;6259.186305780254;6276.403741644548;6277.2153480814895;6364.172657554512;6416.101745755598;6367.204099487564;6395.0727144564125;6407.903881441782;6411.294757576675;6411.839704007402;5658.603897727161;5680.8295751127425;5703.287089113861;5719.084946408576;5725.2843394575675;5761.625494342875;5774.2782152230975;5782.421757824205;5803.82588522345;5806.622569532988;5690.118031705092;5697.0599851024535;5747.281589801275;5779.913301300484;5791.623544469617;5479.123340265578;5585.413531579166;5655.434456554352;5665.66006039639;5802.303723553179;5634.211210430543;5655;5669.886602267973;5688.11834165705;5700.287464066992;6175.87648247037;6196.659183744892;6260.342664020303;6306.369363063703;6345.333739957284;6789.796306110825;6811.693473793691;6813.829654258634;6841.315868413169;6841.418479429254;6831.863362732767;6890.827729306808;6900.7929762107215;6902.827429314257;7003.3346832050875;6437.839054023687;6487.610743355413;6496.480281577463;6507.479401647857;6513.348665133496;6930;7061.1173603299585;7062.152541694927;7145.356917877416;7175.138983322035;6565.310642382577;6694.02936574194;6712.060311556386;6714.764983225564;6920.204176519765;6792.150981127359;6862.965685171542;6895.689693963784;6901.792946211622;6916.9278761792;6804.387605115563;6819.829504262334;6824.249332573413;6862.279460656559;6898.344657257552;6873.20958089816;6941.652917354112;6967.93032069683;6973;6974.895376569356;5003.524665156804;5030.622703297267;5075.746212689351;5084.923726144111;5090.185570308733;4453.287474004209;4529.8641040768825;4563.794629241692;4564.566366195206;4625.537446255415;6290.968545157245;6320.367963203643;6399.584027038228;6400.615963042233;6406.839829004312;6791.2869148739655;6794.72821087151;6864.450843932574;6891.896621550681;6900.2409734929115;6236.75052997875;6293.213348331458;6313.842153946188;6325;6397.872042559123;5482.122860342406;5527.336719593674;5537.944620553743;5546.334439867242;5567.025770490148;7066.50534462593;7128.572285662877;7156.821079473055;7162.247963963709;7162.391196748231;6688.760778321326;6786.355296246848;6795.116634837439;6873.20958089826;6874.587524748531;6933.5493192942295;7049.400800931979;7056;7058.400035996792;7145.606991615303;6741.2921643226755;6744.325567443217;6745.730170793114;6752.358525940028;6753.15585551806;6745.156855393076;6833.829154271183;6841.863162736817;6983.9650801745665;7010.789676309719;6613.900791488228;6631.734730610721;6686.665666716547;6706.396424321834;6709.698063587052;6602.801915942529;6617.602943823386;6697;6697.933020669731;6698.966505167443;6457.483401327976;6491.415772580491;6537.640429776408;6558.540902136995;6564.934350656432;6669.366410191024;6720.0255962886085;6729.461643068641;6741.831454213684;6759.121314229315;6459.547831651739;6471.385218404244;6472.902906456313;6548.214214294315;6562.606243625398 16:40:53 INFO - PID 2372 | |1;ext.html;966.6761634852325;992.0377234082937;992.4045572656407;996.6212839121135;996.6362277768617;40356.19287614249;45085.323720144144;45159.77420112903;45174.77412612933;45263.547364526385;42328.788356058176;42566;42633.7868310658;42797;42846.571534284645;7486.550806951586;7611.619419029054;8071.2332328429;8264.917350826476;8265.380096492758;8205.917940820575;8310.792230194233;8338.916610833874;8346.87479687802;8352.916470835306;8329.83340333193;8377.162283771604;8391.496510209407;8400.495970241775;8403.5378054207;8299.336053115767;8321.334293256525;8337.83324333513;8338.291245244161;8364.2472177504;8106.918930810734;8153.388495862834;8157.347412207009;8158.959205203995;8159.224873637055;40971;41215.1756964859;41220.76337709874;41242.38136427956;41338.79330603358;3638.43604241343;3684.944725829088;3695.5011073505;3704.74066815323;3710.350688629479;34531.654683453344;34590;34657.133571660655;34662.65337346645;34687.132821679406;5357.089294819851;5565.805196818133;5569.136783798518;5569.164625306196;5619.719014049322;215262;216277;216397;216447;216501.99999999843;46598;47342.76328618335;50871.49128508742;50977.74511127495;51083;56127;56603.43396566064;57015.714921425955;57196.428035719946;57272.71363643238;15010.099394036175;15067.547973560811;15122.54632361031;15124.54626361211;15159.924200379148;11966.58116965902;11998.400079995965;12004.75990480203;12013.819792703116;12014.699632509257;14932.552023439313;15121.621959451102;15157.166355850533;15217.239138043053;15336.079835209923;5869.853253668607;5889.116632505158;5900.64596124234;6019.608725432833;6032.849178770566;1051.8106740786732;1070.983935240972;1104.5802595013843;1111.488715191005;1120.910327173824;25493.8725306376;25561;25756.227313180636;25769.61345579818;25814.741852581235;1169.7660467906453;1183.4851839449866;1191.70803153228;1205.873383294759;1213.9028877689764;13384.866151338556;13510;13554.525591604444;13574.864251357558;13720.86279137196;268011;268539;269529;270956;271005;58613.41386586165;58628;58668.41331586715;58720.7063964686;58889.11666325008;1468.1924941282252;1495.9326830292664;1497.2663394936621;1510.9951881998347;1522.7868098466224;25559.361015974748;25928.351791205372;25942.870285648452;26006.34984125412;26157.215283541525;42067.7896610515;42211;42314.36528452137;42333.153336933705;42351.788241058595;234374;234584;235039;235684;236350;36583.81708091443;38057.80971095127;38861.22277554376;38991.41512877366;39095.80452097835;51068;51349.743251285;51943.961120776614;52012.479875202276;52138.21792673037;36949.44575831311;37177.1222323871;37296.81351593225;37747.43378849264;37759.81120094382;37006.814965925;37200.25599488049;37230;37724;38230;43053.3541996864;44542.331865021406;44558.3316250263;44667.55332446634;45064.324035140155;34711;35420;35492.29015419626;35853.28293434169;36008.099797505274;25886.870565647052;26928.32679183036;27164.32089197786;27401.45197096007;27628.58557121685 16:40:53 INFO - PID 2372 | |2;jquery.html;562.4684672984032;580.5471732049003;587.1720873568269;588.9734961926713;610.4719417703686;22751.658725119116;23495;23535.76464235357;23540.64689029666;23547.882260588696;24963.251102466955;25223.369415764617;26747.598786018178;26948.056818011373;26999.325016874587;6697.1963364396215;6800.42196413303;6843.555168914029;6847.93152068478;6851.45188384928;6542.967285163568;6673.599584024974;6764.154480689914;6819.522633415663;6823.317668233161;6786.016027675984;6789.7284108635595;6791.660416979156;6796.626185559792;6796.660166991631;6525.0864878916755;6765.797026089201;6767.830804229909;6779.423748981293;6780;6305.274893387274;6319.778807741753;6328.810135695937;6331.430171284561;6352.4918006559365;30551.2362190947;31122.533162002586;31195.532067019012;31337;31362.843185784153;2252.0653928619604;2284.280451657739;2285.04028308111;2289.175896677195;2294.9655755163685;20709.792902071087;21353;21531.67702484464;21534.138634454604;21550.353489395187;2538.771510564058;2577.7164511903675;2618.8297760645496;2632.4603456291416;2634.2360715392515;131778;138966;139368.99999999898;139424;154310;15101.35540362259;15880.92059539718;16118.194090295437;16433.260503277383;16639.916800415922;24907.128250511145;24971.62542561863;25031.249062528153;25035.749642503706;25088.37279068038;9527.714168574954;9637.193308643047;9642.95178524117;9809.362391444532;9856.852147217798;8606.139386061344;8646.221840034426;8716.607752651145;8725.21473067427;8794.86807697885;9587.328886977984;9597.856032159523;9632.229421646392;9752.46361450127;9862.211023118134;2695.0623820036253;2704.188743376979;2789.539725945237;2789.7907656925813;2884.942301153965;1014.5130337437923;1040.625374865048;1055.8574592429923;1056.4189695667353;1062.5006247063868;21766.673499897515;21837.60982777421;21907.780922190574;22014.229501967355;22512.32463026112;1095.9342439453658;1101.2731597146005;1110.134095405585;1116.5198964445367;1128.6501184632791;11942.940285298519;12059.638210853689;12076.396180191132;12139.089568282414;12164.209326393757;548590;571438;580329;581949;589439;130053;131311;132134.33932830466;132832;132888.3355583216;1169.0180248591225;1238.8575313838844;1239.0335538280156;1244.8008318669151;1249.443997421157;19329.4201173965;19367.77070191012;19599.02004899778;19976.400707978784;20014.799852001295;61323.38676613177;63473.36526634859;64492;65018.674906625165;65546.67226663836;81084.5945770291;82790.17209828066;83659.5817020911;84257.57871210604;84418;15450.459233926758;15564.84435155634;15636.374545018072;15804.130772807146;15807.525774226791;24692.629610556214;24720.752792471845;25010;25038.248852534452;25079.24762257135;19990.500237493598;20044.899775501028;20215.39353819388;20460.283890063776;20507.58984820325;17454.476365709048;17576.472705818847;17851.37520186787;18179.18193681261;18415.631687366447;20542.38372848817;20923.686144708146;20956;21051.684224736946;21053;15106.320215590544;15686.764698529303;15729.370825166865;16186.67626647484;16358.836411635732;20549;20564.766114033206;20607.896960515103;20759.27342543064;20785.376438706862 16:40:53 INFO - PID 2372 | |3;mootools.html;1394.6931675031492;1450.4053338131368;1463.8536146385359;1470.088545102037;1475.3950880139146;16710.832891671078;17335.913320433396;17351.305947762106;17366.218520166592;17366.652666946684;16793.412230571932;16957;16962;16972.83027169728;16982.49052528423;2723.754862062414;2798.4962706712786;2819.873105710248;2834.8157369770897;2836.347640042791;2696.32591852037;2722.060888993304;2736.53478908585;2745.5448612235546;2772;2659.9601005984928;2696.137236084454;2782.2209781261176;2782.833030018195;2783.498970185366;2738.589211618253;2769.5984082308055;2772.209920172741;2800.7339302766204;2808.620836187119;2631.1054241557963;2639.4061336199393;2661.733826617342;2662.9600555991674;2663.893444262227;16468.341266349333;16919;16932.322707091702;16943.83056169447;16965.83034169655;8074.152214017561;8356.415050946436;8357.916420835774;8363.58182090899;8366.790830229293;5385.973070134663;5547.142268409804;5560.638558493685;5633.521150702195;5653.717314134276;1962.2445358537066;1967.3507742444958;1970.123295133666;2003.7996200379846;2007.8293345065688;29760;29889.551656725384;29930.55104173439;29978.25054373636;30001.249968750737;7009;7180;7212.74755383559;7378.040854688856;7380.225076367012;19314.32399865998;19315.227390904205;19328.1302341395;19329.903350483157;19350.709739353922;8024.478408903403;8134.308583770446;8159.551224682699;8163.7142700005215;8164.714235001747;7464.477486575996;7538.660760265801;7542.208068152875;7626.733064342721;7628.8474230516185;5511.834644960658;5647.858803529863;5715.657060576379;5765.3946335634155;5776.277965254343;1567.3495499367634;1687.4178408449066;1690.5435532406182;1707.5218938697185;1718.9226484808214;846.3609974469236;912.2337236721054;916.8349697054595;933.4446004627235;944.9558238146886;7779.572123533147;7843.294103530709;7904.644291006918;7911.881321780178;7994.200579941959;1033.9793204135874;1035.1046344911597;1039.111559616532;1044.8693913260843;1054.920880933933;4646;4801.303810947463;4801.991581767798;4816.036792641486;4825.758712064383;24355.87822060914;24562.263132106065;24725;25002.12492562788;25360.873195633903;25190.118345858173;25191.748082519305;25423;25435.87282063615;25482.235532934043;12142.45358958849;12542.623721288375;12548.874511254953;12575.559855404834;12649.747005059848;8771.473711577326;8792.340574456814;8806.779830504289;8870.467771933705;8926.330525210766;20933.16267349289;21488.355349339545;21642.89178554097;21957.341279761633;21966;21286.78713212848;21489.892550537148;21600.783992160505;21793.564128717655;22206.111755530244;11792.646220613395;11797.115216358636;11839.289642621126;11934.4032798358;11969;15077.472288469851;15435.536933892;15445.304961276994;15448.073115613099;15451.072935623899;12309;12402.937985310016;12444.937775311066;12498.562550310695;12775.1057425983;12361.62915112548;12412.751744965231;12450.750984980068;12450.81323780126;12692.555760548337;10025.147862431775;10061.798764024825;10155.543000564845;10160.390376577428;10164.89835101669;7834.647440865061;7917.920820791949;7992.360611150978;8067.596620169085;8161.714339998071;7703.499272547267;7716.652750626347;7732.110807257238;7807.531548107132;7821.374290056668 16:40:53 INFO - PID 2372 | |4;prototype.html;536.118085748943;544.3576579636031;557.1392199052464;564.3904583050306;576.8356018534715;5980.760769569218;6174.567780255389;6179.691015449232;6258.249010118787;6262.906056409158;6176.166217560639;6242.625442473455;6244.56288059836;6250.718717657705;6259.780907668232;5085.847424577269;5135.589152867761;5155.793768249266;5178.896422071566;5184.585233181337;4881.780319885614;4945.727984960826;5163.709072731817;5165.354330708661;5172.508611681884;4945.530174633404;4991.351124353847;5021.096202683516;5031.213919058737;5040.117979353617;4913.624185228124;4914.75426228689;4956.587372598792;4988.825391111293;5008.824691135793;4511.481179664348;4537.6143027842345;4543.5002149763495;4548.408706868119;4578.702384345007;6132.080187971796;6181.876362472725;6192.256929168483;6198.318184999646;6252.937470625281;5778.711064446761;5829.650220986754;5853.824385268449;5863.70681465925;5914.940850591482;6569.146011018537;6577.80266592003;6645.60126392418;6665.566738162004;6669.732291021028;5049.368828896388;5050.166722490784;5067.543921047087;5081.28861959326;5101.923471147936;6928.099347084897;6953.721851126;6958.721651133898;7007.264237255065;7041.612711300876;5147.330846989942;5173.63784535079;5196.220566914917;5208.609354298367;5215.504527069922;5210.426853046162;5287.9735601321745;5296.364436267672;5296.364436267672;5334.07973129673;5790.71046447676;5838.474537291664;5852.326982397079;5937.970310148422;5949.286085669747;6630.270670226271;6649.7033078550185;6669.733210671616;6705.2624211336715;6706.429953454011;1726.335360886068;1787.642471505704;1792;1816.3461153984556;1826.954326141857;1278.5525066226933;1285.5436320106414;1301.1477482249143;1303.2831942431624;1311.6261865368324;6024;6157.907631385533;6161.291451483047;6199.876002479925;6224.813255602339;6753.49348798842;6808.8978665320055;6842.281560436082;6842.4868134890085;6857.931420685829;6306.747730090745;6311.936880631135;6314.305426403092;6343.3339499351905;6347.52393570484;3567.5362202913457;3567.965290065904;3579.6420357963993;3593.874214402483;3595.0832537702718;7135.7859264221115;7155.212926578072;7165.706973768344;7169.462290328246;7182.497225194186;7121.608311542915;7123.928760712431;7134.607596582134;7142;7155.606441645759;7129.679164437509;7145.749898753519;7149.714011439588;7163.6060016699585;7210.71157153708;6807.0810440589885;6824.590524568542;6825.385715285648;6856.41720453767;6890.276520965427;6728.394444500019;6785.491088168308;6795.524313298021;6798.864022719419;6819.727210911707;7002.8949565757575;7056.1179852518435;7118.60847653384;7154.673077019211;7157.892631610426;5178.43037265908;5195.948040519546;5232.659877107976;5236.842894713165;5250.300980622245;6536.182977127859;6705.530612857248;6783.846746053084;6787.93212067873;6866.759663411756;6330.454945230686;6494.837629059311;6516.185476815398;6591.835204119743;6603.537752357289;6452.451541619015;6482.124913136666;6504.284528701744;6526.379993900572;6563.540552161494;6400.295967443671;6430.164078669743;6437;6494.805155845333;6516.57642253271;6545.2800191979795;6596.8350791230605;6612.735490580515;6691;6727.125473688389;6382;6395.360463953567;6396.232452105777;6440.967795160994;6493.188351456068 16:40:53 INFO - PID 2372 | |5;yui.html;957.7414098193492;981.7692842182086;986.107572646755;993.3195760903782;1005.1958433253398;19167.904160479196;19784.90107549462;19881.3041543546;19883.502912427197;19885.105170267354;17933.461996140104;18121.728174077365;18297.54256143597;18485.7227141593;18549.53626159347;989.1098011789376;996.9451680157589;999.0558921818885;999.5901680311074;999.9500024998757;1007.1388962437121;1026.712520494259;1031.7111208861531;1032.194887987371;1036.5542816588863;1093.923425360221;1094.7263184203948;1101.0585949013598;1102.2504696806168;1103.8013157631624;1052.4001319248032;1078.92986955848;1083.5178345636155;1083.6261489786011;1085.8751243607007;782.9021372328459;783.600363814453;783.6786917363853;783.9921600784033;784.2745460449119;19838.80161198384;20223.79776202234;20310;20331.288404905907;20381.592368152702;21688.783112168836;21710.565788684296;22199.001044952853;22395;22429.551408971893;17723.645527089517;17964.371247006424;17966.19152138157;17975.910120449444;17981.910090449463;3890.6109389060866;3945.9013524661827;3977.8607748728946;3983.6215559521797;3986.142979259444;144681.2765936174;145070;146040;147278;150406.9999999989;15736.527904162664;15862.286197121157;16270.349186032663;16275.430359937345;16388.180590970405;27133.86433067822;27179.456410871673;27311.726882731316;27316.31709207246;27379.452410952068;8938.329625278131;9023.097690231056;9112.453252804853;9181.081891810765;9202.401843880127;4545.3863728396245;4621.1219868224925;4627.6529260305615;4638.768061596907;4643.86068417948;5911.704414779244;5953.22608060958;6024.457798798129;6045.244344456943;6079.817605471843;1053.210092430677;1057.6456886942806;1079.0450451350612;1083.3012706804068;1084.6420681175193;728.730369763191;740.7222291640635;745.4520927118564;757.7120694136192;771.2750014986008;12442.191257568229;12531.624051278477;12904.548340808027;13068.215907045607;13286.069975101844;726.963651817407;727.6943683652772;733.8238822682517;740.9555426674417;746.358132006474;5666.829995100154;5681.346645135863;5702.2301989231755;5759.884802303931;5763.077907534858;147346;150547;150935;151854;151978;123972;125557;128966.35516822356;129087;131785;108176.45911770391;108391.47558025648;110379;113914;116123.99999999831;12153.696157595778;12413.200085987663;12553.435095420546;12660.810087848506;12876.420561074961;53233.20150197673;53279.73360133175;53888;54816.72591637016;55853.16220256618;67491.3250867485;69222.30777692159;69591.17456197315;69859.30140698528;70644.64677676579;19074.7138792921;19363.51591210231;19406.805931941064;19488.902555487133;19621.509462262988;22780.202692906332;22818.42953926165;22908.54182916299;22944.4263893404;23042.769572304733;20769.169233230503;20985.370438886857;21070.15719371208;21106.261280855095;21220.89389553104;20373.694394583796;20703.792962070787;20792.48018799542;20927.895360523707;21056.68414973746;20116.19535218575;20231;20275.898620507392;20375;20604.484887877923;13566.864331356956;13739.405774445875;13799.17204967665;13865.722685546434;14400.927995359956;13497.122687025312;13576.253306068262;13579.79630305566;13647.249401283025;13677.726445471233 16:40:53 INFO - PID 2372 | __end_tp_report 16:40:53 INFO - PID 2372 | __start_cc_report 16:40:53 INFO - PID 2372 | _x_x_mozilla_cycle_collect,362 16:40:53 INFO - PID 2372 | __end_cc_report 16:40:53 INFO - PID 2372 | __startTimestamp1504827653120__endTimestamp 16:40:53 INFO - PID 2372 | 16:40:53 INFO - PID 2372 | ------- Summary: start ------- 16:40:53 INFO - PID 2372 | Number of tests: 6 16:40:53 INFO - PID 2372 | 16:40:53 INFO - PID 2372 | [#0] dojo.html Cycles:165 Average:6314.21 Median:6558.54 stddev:752.42 (11.5%) stddev-sans-first:727.73 16:40:53 INFO - PID 2372 | Values: 3767.7 3799.0 3858.0 3860.8 3864.0 6259.2 6276.4 6277.2 6364.2 6416.1 6367.2 6395.1 6407.9 6411.3 6411.8 5658.6 5680.8 5703.3 5719.1 5725.3 5761.6 5774.3 5782.4 5803.8 5806.6 5690.1 5697.1 5747.3 5779.9 5791.6 5479.1 5585.4 5655.4 5665.7 5802.3 5634.2 5655.0 5669.9 5688.1 5700.3 6175.9 6196.7 6260.3 6306.4 6345.3 6789.8 6811.7 6813.8 6841.3 6841.4 6831.9 6890.8 6900.8 6902.8 7003.3 6437.8 6487.6 6496.5 6507.5 6513.3 6930.0 7061.1 7062.2 7145.4 7175.1 6565.3 6694.0 6712.1 6714.8 6920.2 6792.2 6863.0 6895.7 6901.8 6916.9 6804.4 6819.8 6824.2 6862.3 6898.3 6873.2 6941.7 6967.9 6973.0 6974.9 5003.5 5030.6 5075.7 5084.9 5090.2 4453.3 4529.9 4563.8 4564.6 4625.5 6291.0 6320.4 6399.6 6400.6 6406.8 6791.3 6794.7 6864.5 6891.9 6900.2 6236.8 6293.2 6313.8 6325.0 6397.9 5482.1 5527.3 5537.9 5546.3 5567.0 7066.5 7128.6 7156.8 7162.2 7162.4 6688.8 6786.4 6795.1 6873.2 6874.6 6933.5 7049.4 7056.0 7058.4 7145.6 6741.3 6744.3 6745.7 6752.4 6753.2 6745.2 6833.8 6841.9 6984.0 7010.8 6613.9 6631.7 6686.7 6706.4 6709.7 6602.8 6617.6 6697.0 6697.9 6699.0 6457.5 6491.4 6537.6 6558.5 6564.9 6669.4 6720.0 6729.5 6741.8 6759.1 6459.5 6471.4 6472.9 6548.2 6562.6 16:40:53 INFO - PID 2372 | 16:40:53 INFO - PID 2372 | [#1] ext.html Cycles:180 Average:42528.90 Median:38143.90 stddev:62680.61 (164.3%) stddev-sans-first:62778.76 16:40:53 INFO - PID 2372 | Values: 966.7 992.0 992.4 996.6 996.6 40356.2 45085.3 45159.8 45174.8 45263.5 42328.8 42566.0 42633.8 42797.0 42846.6 7486.6 7611.6 8071.2 8264.9 8265.4 8205.9 8310.8 8338.9 8346.9 8352.9 8329.8 8377.2 8391.5 8400.5 8403.5 8299.3 8321.3 8337.8 8338.3 8364.2 8106.9 8153.4 8157.3 8159.0 8159.2 40971.0 41215.2 41220.8 41242.4 41338.8 3638.4 3684.9 3695.5 3704.7 3710.4 34531.7 34590.0 34657.1 34662.7 34687.1 5357.1 5565.8 5569.1 5569.2 5619.7 215262.0 216277.0 216397.0 216447.0 216502.0 46598.0 47342.8 50871.5 50977.7 51083.0 56127.0 56603.4 57015.7 57196.4 57272.7 15010.1 15067.5 15122.5 15124.5 15159.9 11966.6 11998.4 12004.8 12013.8 12014.7 14932.6 15121.6 15157.2 15217.2 15336.1 5869.9 5889.1 5900.6 6019.6 6032.8 1051.8 1071.0 1104.6 1111.5 1120.9 25493.9 25561.0 25756.2 25769.6 25814.7 1169.8 1183.5 1191.7 1205.9 1213.9 13384.9 13510.0 13554.5 13574.9 13720.9 268011.0 268539.0 269529.0 270956.0 271005.0 58613.4 58628.0 58668.4 58720.7 58889.1 1468.2 1495.9 1497.3 1511.0 1522.8 25559.4 25928.4 25942.9 26006.3 26157.2 42067.8 42211.0 42314.4 42333.2 42351.8 234374.0 234584.0 235039.0 235684.0 236350.0 36583.8 38057.8 38861.2 38991.4 39095.8 51068.0 51349.7 51944.0 52012.5 52138.2 36949.4 37177.1 37296.8 37747.4 37759.8 37006.8 37200.3 37230.0 37724.0 38230.0 43053.4 44542.3 44558.3 44667.6 45064.3 34711.0 35420.0 35492.3 35853.3 36008.1 25886.9 26928.3 27164.3 27401.5 27628.6 16:40:53 INFO - PID 2372 | 16:40:53 INFO - PID 2372 | [#2] jquery.html Cycles:180 Average:38891.96 Median:24706.69 stddev:96253.43 (389.6%) stddev-sans-first:96480.42 16:40:53 INFO - PID 2372 | Values: 562.5 580.5 587.2 589.0 610.5 22751.7 23495.0 23535.8 23540.6 23547.9 24963.3 25223.4 26747.6 26948.1 26999.3 6697.2 6800.4 6843.6 6847.9 6851.5 6543.0 6673.6 6764.2 6819.5 6823.3 6786.0 6789.7 6791.7 6796.6 6796.7 6525.1 6765.8 6767.8 6779.4 6780.0 6305.3 6319.8 6328.8 6331.4 6352.5 30551.2 31122.5 31195.5 31337.0 31362.8 2252.1 2284.3 2285.0 2289.2 2295.0 20709.8 21353.0 21531.7 21534.1 21550.4 2538.8 2577.7 2618.8 2632.5 2634.2 131778.0 138966.0 139369.0 139424.0 154310.0 15101.4 15880.9 16118.2 16433.3 16639.9 24907.1 24971.6 25031.2 25035.7 25088.4 9527.7 9637.2 9643.0 9809.4 9856.9 8606.1 8646.2 8716.6 8725.2 8794.9 9587.3 9597.9 9632.2 9752.5 9862.2 2695.1 2704.2 2789.5 2789.8 2884.9 1014.5 1040.6 1055.9 1056.4 1062.5 21766.7 21837.6 21907.8 22014.2 22512.3 1095.9 1101.3 1110.1 1116.5 1128.7 11942.9 12059.6 12076.4 12139.1 12164.2 548590.0 571438.0 580329.0 581949.0 589439.0 130053.0 131311.0 132134.3 132832.0 132888.3 1169.0 1238.9 1239.0 1244.8 1249.4 19329.4 19367.8 19599.0 19976.4 20014.8 61323.4 63473.4 64492.0 65018.7 65546.7 81084.6 82790.2 83659.6 84257.6 84418.0 15450.5 15564.8 15636.4 15804.1 15807.5 24692.6 24720.8 25010.0 25038.2 25079.2 19990.5 20044.9 20215.4 20460.3 20507.6 17454.5 17576.5 17851.4 18179.2 18415.6 20542.4 20923.7 20956.0 21051.7 21053.0 15106.3 15686.8 15729.4 16186.7 16358.8 20549.0 20564.8 20607.9 20759.3 20785.4 16:40:53 INFO - PID 2372 | 16:40:53 INFO - PID 2372 | [#3] mootools.html Cycles:180 Average:10248.59 Median:2635.26 stddev:7729.43 (293.3%) stddev-sans-first:7722.49 16:40:53 INFO - PID 2372 | Values: 1394.7 1450.4 1463.9 1470.1 1475.4 16710.8 17335.9 17351.3 17366.2 17366.7 16793.4 16957.0 16962.0 16972.8 16982.5 2723.8 2798.5 2819.9 2834.8 2836.3 2696.3 2722.1 2736.5 2745.5 2772.0 2660.0 2696.1 2782.2 2782.8 2783.5 2738.6 2769.6 2772.2 2800.7 2808.6 2631.1 2639.4 2661.7 2663.0 2663.9 16468.3 16919.0 16932.3 16943.8 16965.8 8074.2 8356.4 8357.9 8363.6 8366.8 5386.0 5547.1 5560.6 5633.5 5653.7 1962.2 1967.4 1970.1 2003.8 2007.8 29760.0 29889.6 29930.6 29978.3 30001.2 7009.0 7180.0 7212.7 7378.0 7380.2 19314.3 19315.2 19328.1 19329.9 19350.7 8024.5 8134.3 8159.6 8163.7 8164.7 7464.5 7538.7 7542.2 7626.7 7628.8 5511.8 5647.9 5715.7 5765.4 5776.3 1567.3 1687.4 1690.5 1707.5 1718.9 846.4 912.2 916.8 933.4 945.0 7779.6 7843.3 7904.6 7911.9 7994.2 1034.0 1035.1 1039.1 1044.9 1054.9 4646.0 4801.3 4802.0 4816.0 4825.8 24355.9 24562.3 24725.0 25002.1 25360.9 25190.1 25191.7 25423.0 25435.9 25482.2 12142.5 12542.6 12548.9 12575.6 12649.7 8771.5 8792.3 8806.8 8870.5 8926.3 20933.2 21488.4 21642.9 21957.3 21966.0 21286.8 21489.9 21600.8 21793.6 22206.1 11792.6 11797.1 11839.3 11934.4 11969.0 15077.5 15435.5 15445.3 15448.1 15451.1 12309.0 12402.9 12444.9 12498.6 12775.1 12361.6 12412.8 12450.8 12450.8 12692.6 10025.1 10061.8 10155.5 10160.4 10164.9 7834.6 7917.9 7992.4 8067.6 8161.7 7703.5 7716.7 7732.1 7807.5 7821.4 16:40:53 INFO - PID 2372 | 16:40:53 INFO - PID 2372 | [#4] prototype.html Cycles:180 Average:5667.84 Median:6251.83 stddev:1594.57 (25.5%) stddev-sans-first:1551.82 16:40:53 INFO - PID 2372 | Values: 536.1 544.4 557.1 564.4 576.8 5980.8 6174.6 6179.7 6258.2 6262.9 6176.2 6242.6 6244.6 6250.7 6259.8 5085.8 5135.6 5155.8 5178.9 5184.6 4881.8 4945.7 5163.7 5165.4 5172.5 4945.5 4991.4 5021.1 5031.2 5040.1 4913.6 4914.8 4956.6 4988.8 5008.8 4511.5 4537.6 4543.5 4548.4 4578.7 6132.1 6181.9 6192.3 6198.3 6252.9 5778.7 5829.7 5853.8 5863.7 5914.9 6569.1 6577.8 6645.6 6665.6 6669.7 5049.4 5050.2 5067.5 5081.3 5101.9 6928.1 6953.7 6958.7 7007.3 7041.6 5147.3 5173.6 5196.2 5208.6 5215.5 5210.4 5288.0 5296.4 5296.4 5334.1 5790.7 5838.5 5852.3 5938.0 5949.3 6630.3 6649.7 6669.7 6705.3 6706.4 1726.3 1787.6 1792.0 1816.3 1827.0 1278.6 1285.5 1301.1 1303.3 1311.6 6024.0 6157.9 6161.3 6199.9 6224.8 6753.5 6808.9 6842.3 6842.5 6857.9 6306.7 6311.9 6314.3 6343.3 6347.5 3567.5 3568.0 3579.6 3593.9 3595.1 7135.8 7155.2 7165.7 7169.5 7182.5 7121.6 7123.9 7134.6 7142.0 7155.6 7129.7 7145.7 7149.7 7163.6 7210.7 6807.1 6824.6 6825.4 6856.4 6890.3 6728.4 6785.5 6795.5 6798.9 6819.7 7002.9 7056.1 7118.6 7154.7 7157.9 5178.4 5195.9 5232.7 5236.8 5250.3 6536.2 6705.5 6783.8 6787.9 6866.8 6330.5 6494.8 6516.2 6591.8 6603.5 6452.5 6482.1 6504.3 6526.4 6563.5 6400.3 6430.2 6437.0 6494.8 6516.6 6545.3 6596.8 6612.7 6691.0 6727.1 6382.0 6395.4 6396.2 6441.0 6493.2 16:40:53 INFO - PID 2372 | 16:40:53 INFO - PID 2372 | [#5] yui.html Cycles:180 Average:27689.76 Median:20292.95 stddev:40645.06 (200.3%) stddev-sans-first:40709.51 16:40:53 INFO - PID 2372 | Values: 957.7 981.8 986.1 993.3 1005.2 19167.9 19784.9 19881.3 19883.5 19885.1 17933.5 18121.7 18297.5 18485.7 18549.5 989.1 996.9 999.1 999.6 1000.0 1007.1 1026.7 1031.7 1032.2 1036.6 1093.9 1094.7 1101.1 1102.3 1103.8 1052.4 1078.9 1083.5 1083.6 1085.9 782.9 783.6 783.7 784.0 784.3 19838.8 20223.8 20310.0 20331.3 20381.6 21688.8 21710.6 22199.0 22395.0 22429.6 17723.6 17964.4 17966.2 17975.9 17981.9 3890.6 3945.9 3977.9 3983.6 3986.1 144681.3 145070.0 146040.0 147278.0 150407.0 15736.5 15862.3 16270.3 16275.4 16388.2 27133.9 27179.5 27311.7 27316.3 27379.5 8938.3 9023.1 9112.5 9181.1 9202.4 4545.4 4621.1 4627.7 4638.8 4643.9 5911.7 5953.2 6024.5 6045.2 6079.8 1053.2 1057.6 1079.0 1083.3 1084.6 728.7 740.7 745.5 757.7 771.3 12442.2 12531.6 12904.5 13068.2 13286.1 727.0 727.7 733.8 741.0 746.4 5666.8 5681.3 5702.2 5759.9 5763.1 147346.0 150547.0 150935.0 151854.0 151978.0 123972.0 125557.0 128966.4 129087.0 131785.0 108176.5 108391.5 110379.0 113914.0 116124.0 12153.7 12413.2 12553.4 12660.8 12876.4 53233.2 53279.7 53888.0 54816.7 55853.2 67491.3 69222.3 69591.2 69859.3 70644.6 19074.7 19363.5 19406.8 19488.9 19621.5 22780.2 22818.4 22908.5 22944.4 23042.8 20769.2 20985.4 21070.2 21106.3 21220.9 20373.7 20703.8 20792.5 20927.9 21056.7 20116.2 20231.0 20275.9 20375.0 20604.5 13566.9 13739.4 13799.2 13865.7 14400.9 13497.1 13576.3 13579.8 13647.2 13677.7 16:40:53 INFO - PID 2372 | -------- Summary: end -------- 16:40:53 INFO - PID 2372 | 16:40:53 INFO - TEST-INFO | 2372: exit 0 16:40:53 INFO - TEST-OK | dromaeo_css | took 1109192ms 16:40:53 INFO - TEST-START | kraken 16:40:53 INFO - Initialising browser for kraken test... 16:40:53 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:37937/getInfo.html -profile /tmp/tmpDGabJG/profile 16:40:53 INFO - TEST-INFO | started process 2843 (/builds/slave/test/build/application/firefox/firefox http://localhost:37937/getInfo.html) 16:40:59 INFO - TEST-INFO | 2843: exit 0 16:40:59 INFO - Browser initialized. 16:40:59 INFO - Running cycle 1/1 for kraken test... 16:40:59 INFO - Using env: {'DISPLAY': ':0', 16:40:59 INFO - 'HOME': '/home/cltbld', 16:40:59 INFO - 'JSGC_DISABLE_POISONING': '1', 16:40:59 INFO - 'LANG': 'en_US.UTF-8', 16:40:59 INFO - 'LANGUAGE': 'en_US:en', 16:40:59 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 16:40:59 INFO - 'LOGNAME': 'cltbld', 16:40:59 INFO - 'MAIL': '/var/mail/cltbld', 16:40:59 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:40:59 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:40:59 INFO - 'MOZ_CRASHREPORTER': '1', 16:40:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:40:59 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 16:40:59 INFO - 'MOZ_NO_REMOTE': '1', 16:40:59 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:40:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:40:59 INFO - 'NO_EM_RESTART': '1', 16:40:59 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:40:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:40:59 INFO - 'PWD': '/builds/slave/test', 16:40:59 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:40:59 INFO - 'RUST_BACKTRACE': 'full', 16:40:59 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 16:40:59 INFO - 'SHELL': '/bin/bash', 16:40:59 INFO - 'SHLVL': '1', 16:40:59 INFO - 'TERM': 'linux', 16:40:59 INFO - 'TMOUT': '86400', 16:40:59 INFO - 'USER': 'cltbld', 16:40:59 INFO - 'XDG_SESSION_COOKIE': 'ea65d483da93c827905c2090000001f5-1504826472.457837-528075096', 16:40:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:40:59 INFO - '_': '/tools/buildbot/bin/python'} 16:40:59 INFO - TEST-INFO | started process 3151 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpDGabJG/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 16:41:00 INFO - PID 3151 | 16:41:00 INFO - PID 3151 | (/builds/slave/test/build/application/firefox/firefox:3203): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 16:41:00 INFO - PID 3151 | 16:41:10 INFO - PID 3151 | RSS: Main: 164286464 16:41:10 INFO - PID 3151 | 16:41:13 INFO - PID 3151 | Cycle 1(1): loaded http://localhost:37937/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:37937/tests/kraken/driver.html?testName=audio-beat-detection) 16:41:14 INFO - PID 3151 | RSS: Main: 169918464 16:41:14 INFO - PID 3151 | 16:41:16 INFO - PID 3151 | Cycle 1(1): loaded http://localhost:37937/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:37937/tests/kraken/driver.html?testName=audio-dft) 16:41:17 INFO - PID 3151 | RSS: Main: 170401792 16:41:17 INFO - PID 3151 | 16:41:20 INFO - PID 3151 | Cycle 1(1): loaded http://localhost:37937/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:37937/tests/kraken/driver.html?testName=audio-fft) 16:41:21 INFO - PID 3151 | RSS: Main: 164564992 16:41:21 INFO - PID 3151 | 16:41:23 INFO - PID 3151 | Cycle 1(1): loaded http://localhost:37937/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:37937/tests/kraken/driver.html?testName=audio-oscillator) 16:41:23 INFO - PID 3151 | RSS: Main: 166055936 16:41:23 INFO - PID 3151 | 16:41:25 INFO - PID 3151 | Cycle 1(1): loaded http://localhost:37937/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:37937/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:41:26 INFO - PID 3151 | RSS: Main: 166125568 16:41:26 INFO - PID 3151 | 16:41:29 INFO - PID 3151 | Cycle 1(1): loaded http://localhost:37937/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:37937/tests/kraken/driver.html?testName=imaging-darkroom) 16:41:30 INFO - PID 3151 | RSS: Main: 166232064 16:41:30 INFO - PID 3151 | 16:41:33 INFO - PID 3151 | Cycle 1(1): loaded http://localhost:37937/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:37937/tests/kraken/driver.html?testName=imaging-desaturate) 16:41:34 INFO - PID 3151 | RSS: Main: 168652800 16:41:34 INFO - PID 3151 | 16:41:36 INFO - PID 3151 | Cycle 1(1): loaded http://localhost:37937/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:37937/tests/kraken/driver.html?testName=json-parse-financial) 16:41:37 INFO - PID 3151 | RSS: Main: 169054208 16:41:37 INFO - PID 3151 | 16:41:39 INFO - PID 3151 | Cycle 1(1): loaded http://localhost:37937/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:37937/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:41:39 INFO - PID 3151 | RSS: Main: 168927232 16:41:39 INFO - PID 3151 | 16:41:41 INFO - PID 3151 | Cycle 1(1): loaded http://localhost:37937/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:37937/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:41:41 INFO - PID 3151 | RSS: Main: 168943616 16:41:41 INFO - PID 3151 | 16:41:44 INFO - PID 3151 | Cycle 1(1): loaded http://localhost:37937/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:37937/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:41:44 INFO - PID 3151 | RSS: Main: 166539264 16:41:44 INFO - PID 3151 | 16:41:47 INFO - PID 3151 | Cycle 1(1): loaded http://localhost:37937/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:37937/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:41:47 INFO - PID 3151 | RSS: Main: 166297600 16:41:47 INFO - PID 3151 | 16:41:50 INFO - PID 3151 | Cycle 1(1): loaded http://localhost:37937/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:37937/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:41:51 INFO - PID 3151 | RSS: Main: 166391808 16:41:51 INFO - PID 3151 | 16:41:52 INFO - PID 3151 | Cycle 1(1): loaded http://localhost:37937/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:37937/tests/kraken/driver.html?testName=ai-astar) 16:41:53 INFO - PID 3151 | RSS: Main: 168206336 16:41:53 INFO - PID 3151 | 16:41:53 INFO - PID 3151 | __start_tp_report 16:41:53 INFO - PID 3151 | _x_x_mozilla_page_load 16:41:53 INFO - PID 3151 | _x_x_mozilla_page_load_details 16:41:53 INFO - PID 3151 | |i|pagename|runs| 16:41:53 INFO - PID 3151 | |0;ai-astar;108;108;105;106;106;111;108;105;106;109 16:41:53 INFO - PID 3151 | |1;audio-beat-detection;112;112;115;112;114;115;112;112;112;118 16:41:53 INFO - PID 3151 | |2;audio-dft;162;163;163;162;161;163;163;163;163;164 16:41:53 INFO - PID 3151 | |3;audio-fft;73;73;73;73;73;74;74;74;73;74 16:41:53 INFO - PID 3151 | |4;audio-oscillator;78;78;80;84;78;77;78;78;77;79 16:41:53 INFO - PID 3151 | |5;imaging-gaussian-blur;113;114;114;114;114;115;115;114;111;114 16:41:53 INFO - PID 3151 | |6;imaging-darkroom;150;149;150;149;150;149;150;150;151;149 16:41:53 INFO - PID 3151 | |7;imaging-desaturate;99;99;93;94;98;94;93;93;94;94 16:41:53 INFO - PID 3151 | |8;json-parse-financial;49;45;46;45;46;47;47;47;45;45 16:41:53 INFO - PID 3151 | |9;json-stringify-tinderbox;37;37;38;37;39;37;37;37;37;37 16:41:53 INFO - PID 3151 | |10;stanford-crypto-aes;74;77;74;72;72;73;72;79;74;75 16:41:53 INFO - PID 3151 | |11;stanford-crypto-ccm;114;116;115;116;111;115;140;124;125;116 16:41:53 INFO - PID 3151 | |12;stanford-crypto-pbkdf2;157;170;157;159;156;156;157;166;166;157 16:41:53 INFO - PID 3151 | |13;stanford-crypto-sha256-iterative;64;60;62;58;58;61;60;61;58;57 16:41:53 INFO - PID 3151 | __end_tp_report 16:41:53 INFO - PID 3151 | __start_cc_report 16:41:53 INFO - PID 3151 | _x_x_mozilla_cycle_collect,886 16:41:53 INFO - PID 3151 | __end_cc_report 16:41:53 INFO - PID 3151 | __startTimestamp1504827713208__endTimestamp 16:41:53 INFO - PID 3151 | 16:41:53 INFO - PID 3151 | ------- Summary: start ------- 16:41:53 INFO - PID 3151 | Number of tests: 14 16:41:53 INFO - PID 3151 | 16:41:53 INFO - PID 3151 | [#0] ai-astar Cycles:10 Average:107.20 Median:108.00 stddev:1.93 (1.8%) stddev-sans-first:2.03 16:41:53 INFO - PID 3151 | Values: 108.0 108.0 105.0 106.0 106.0 111.0 108.0 105.0 106.0 109.0 16:41:53 INFO - PID 3151 | 16:41:53 INFO - PID 3151 | [#1] audio-beat-detection Cycles:10 Average:113.40 Median:113.00 stddev:2.07 (1.8%) stddev-sans-first:2.13 16:41:53 INFO - PID 3151 | Values: 112.0 112.0 115.0 112.0 114.0 115.0 112.0 112.0 112.0 118.0 16:41:53 INFO - PID 3151 | 16:41:53 INFO - PID 3151 | [#2] audio-dft Cycles:10 Average:162.70 Median:163.00 stddev:0.82 (0.5%) stddev-sans-first:0.83 16:41:53 INFO - PID 3151 | Values: 162.0 163.0 163.0 162.0 161.0 163.0 163.0 163.0 163.0 164.0 16:41:53 INFO - PID 3151 | 16:41:53 INFO - PID 3151 | [#3] audio-fft Cycles:10 Average:73.40 Median:73.50 stddev:0.52 (0.7%) stddev-sans-first:0.53 16:41:53 INFO - PID 3151 | Values: 73.0 73.0 73.0 73.0 73.0 74.0 74.0 74.0 73.0 74.0 16:41:53 INFO - PID 3151 | 16:41:53 INFO - PID 3151 | [#4] audio-oscillator Cycles:10 Average:78.70 Median:78.00 stddev:2.06 (2.6%) stddev-sans-first:2.17 16:41:53 INFO - PID 3151 | Values: 78.0 78.0 80.0 84.0 78.0 77.0 78.0 78.0 77.0 79.0 16:41:53 INFO - PID 3151 | 16:41:53 INFO - PID 3151 | [#5] imaging-gaussian-blur Cycles:10 Average:113.80 Median:114.00 stddev:1.14 (1.0%) stddev-sans-first:1.17 16:41:53 INFO - PID 3151 | Values: 113.0 114.0 114.0 114.0 114.0 115.0 115.0 114.0 111.0 114.0 16:41:53 INFO - PID 3151 | 16:41:53 INFO - PID 3151 | [#6] imaging-darkroom Cycles:10 Average:149.70 Median:150.00 stddev:0.67 (0.4%) stddev-sans-first:0.71 16:41:53 INFO - PID 3151 | Values: 150.0 149.0 150.0 149.0 150.0 149.0 150.0 150.0 151.0 149.0 16:41:53 INFO - PID 3151 | 16:41:53 INFO - PID 3151 | [#7] imaging-desaturate Cycles:10 Average:95.10 Median:94.00 stddev:2.51 (2.7%) stddev-sans-first:2.24 16:41:53 INFO - PID 3151 | Values: 99.0 99.0 93.0 94.0 98.0 94.0 93.0 93.0 94.0 94.0 16:41:53 INFO - PID 3151 | 16:41:53 INFO - PID 3151 | [#8] json-parse-financial Cycles:10 Average:46.20 Median:46.50 stddev:1.32 (2.8%) stddev-sans-first:0.93 16:41:53 INFO - PID 3151 | Values: 49.0 45.0 46.0 45.0 46.0 47.0 47.0 47.0 45.0 45.0 16:41:53 INFO - PID 3151 | 16:41:53 INFO - PID 3151 | [#9] json-stringify-tinderbox Cycles:10 Average:37.30 Median:37.00 stddev:0.67 (1.8%) stddev-sans-first:0.71 16:41:53 INFO - PID 3151 | Values: 37.0 37.0 38.0 37.0 39.0 37.0 37.0 37.0 37.0 37.0 16:41:53 INFO - PID 3151 | 16:41:53 INFO - PID 3151 | [#10] stanford-crypto-aes Cycles:10 Average:74.20 Median:74.00 stddev:2.30 (3.1%) stddev-sans-first:2.44 16:41:53 INFO - PID 3151 | Values: 74.0 77.0 74.0 72.0 72.0 73.0 72.0 79.0 74.0 75.0 16:41:53 INFO - PID 3151 | 16:41:53 INFO - PID 3151 | [#11] stanford-crypto-ccm Cycles:10 Average:119.20 Median:116.00 stddev:8.50 (7.3%) stddev-sans-first:8.80 16:41:53 INFO - PID 3151 | Values: 114.0 116.0 115.0 116.0 111.0 115.0 140.0 124.0 125.0 116.0 16:41:53 INFO - PID 3151 | 16:41:53 INFO - PID 3151 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:160.10 Median:158.00 stddev:5.17 (3.3%) stddev-sans-first:5.36 16:41:53 INFO - PID 3151 | Values: 157.0 170.0 157.0 159.0 156.0 156.0 157.0 166.0 166.0 157.0 16:41:53 INFO - PID 3151 | 16:41:53 INFO - PID 3151 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:59.90 Median:60.50 stddev:2.18 (3.6%) stddev-sans-first:1.74 16:41:53 INFO - PID 3151 | Values: 64.0 60.0 62.0 58.0 58.0 61.0 60.0 61.0 58.0 57.0 16:41:53 INFO - PID 3151 | -------- Summary: end -------- 16:41:53 INFO - PID 3151 | 16:41:53 INFO - PID 3151 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 16:41:53 INFO - TEST-INFO | 3151: exit 0 16:41:53 INFO - TEST-OK | kraken | took 60089ms 16:41:53 INFO - SUITE-END | took 1169s 16:41:54 INFO - Completed test suite (00:19:29) 16:41:54 INFO - javascript benchmark 16:41:54 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 9047.094447591702, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3767.7362584619077, 3799.0312470320073, 3857.9614203857955, 3860.7683538987662, 3864.0, 6259.186305780254, 6276.403741644548, 6277.2153480814895, 6364.172657554512, 6416.101745755598, 6367.204099487564, 6395.0727144564125, 6407.903881441782, 6411.294757576675, 6411.839704007402, 5658.603897727161, 5680.8295751127425, 5703.287089113861, 5719.084946408576, 5725.2843394575675, 5761.625494342875, 5774.2782152230975, 5782.421757824205, 5803.82588522345, 5806.622569532988, 5690.118031705092, 5697.0599851024535, 5747.281589801275, 5779.913301300484, 5791.623544469617, 5479.123340265578, 5585.413531579166, 5655.434456554352, 5665.66006039639, 5802.303723553179, 5634.211210430543, 5655.0, 5669.886602267973, 5688.11834165705, 5700.287464066992, 6175.87648247037, 6196.659183744892, 6260.342664020303, 6306.369363063703, 6345.333739957284, 6789.796306110825, 6811.693473793691, 6813.829654258634, 6841.315868413169, 6841.418479429254, 6831.863362732767, 6890.827729306808, 6900.7929762107215, 6902.827429314257, 7003.3346832050875, 6437.839054023687, 6487.610743355413, 6496.480281577463, 6507.479401647857, 6513.348665133496, 6930.0, 7061.1173603299585, 7062.152541694927, 7145.356917877416, 7175.138983322035, 6565.310642382577, 6694.02936574194, 6712.060311556386, 6714.764983225564, 6920.204176519765, 6792.150981127359, 6862.965685171542, 6895.689693963784, 6901.792946211622, 6916.9278761792, 6804.387605115563, 6819.829504262334, 6824.249332573413, 6862.279460656559, 6898.344657257552, 6873.20958089816, 6941.652917354112, 6967.93032069683, 6973.0, 6974.895376569356, 5003.524665156804, 5030.622703297267, 5075.746212689351, 5084.923726144111, 5090.185570308733, 4453.287474004209, 4529.8641040768825, 4563.794629241692, 4564.566366195206, 4625.537446255415, 6290.968545157245, 6320.367963203643, 6399.584027038228, 6400.615963042233, 6406.839829004312, 6791.2869148739655, 6794.72821087151, 6864.450843932574, 6891.896621550681, 6900.2409734929115, 6236.75052997875, 6293.213348331458, 6313.842153946188, 6325.0, 6397.872042559123, 5482.122860342406, 5527.336719593674, 5537.944620553743, 5546.334439867242, 5567.025770490148, 7066.50534462593, 7128.572285662877, 7156.821079473055, 7162.247963963709, 7162.391196748231, 6688.760778321326, 6786.355296246848, 6795.116634837439, 6873.20958089826, 6874.587524748531, 6933.5493192942295, 7049.400800931979, 7056.0, 7058.400035996792, 7145.606991615303, 6741.2921643226755, 6744.325567443217, 6745.730170793114, 6752.358525940028, 6753.15585551806, 6745.156855393076, 6833.829154271183, 6841.863162736817, 6983.9650801745665, 7010.789676309719, 6613.900791488228, 6631.734730610721, 6686.665666716547, 6706.396424321834, 6709.698063587052, 6602.801915942529, 6617.602943823386, 6697.0, 6697.933020669731, 6698.966505167443, 6457.483401327976, 6491.415772580491, 6537.640429776408, 6558.540902136995, 6564.934350656432, 6669.366410191024, 6720.0255962886085, 6729.461643068641, 6741.831454213684, 6759.121314229315, 6459.547831651739, 6471.385218404244, 6472.902906456313, 6548.214214294315, 6562.606243625398], "value": 6262.441415587694, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [966.6761634852325, 992.0377234082937, 992.4045572656407, 996.6212839121135, 996.6362277768617, 40356.19287614249, 45085.323720144144, 45159.77420112903, 45174.77412612933, 45263.547364526385, 42328.788356058176, 42566.0, 42633.7868310658, 42797.0, 42846.571534284645, 7486.550806951586, 7611.619419029054, 8071.2332328429, 8264.917350826476, 8265.380096492758, 8205.917940820575, 8310.792230194233, 8338.916610833874, 8346.87479687802, 8352.916470835306, 8329.83340333193, 8377.162283771604, 8391.496510209407, 8400.495970241775, 8403.5378054207, 8299.336053115767, 8321.334293256525, 8337.83324333513, 8338.291245244161, 8364.2472177504, 8106.918930810734, 8153.388495862834, 8157.347412207009, 8158.959205203995, 8159.224873637055, 40971.0, 41215.1756964859, 41220.76337709874, 41242.38136427956, 41338.79330603358, 3638.43604241343, 3684.944725829088, 3695.5011073505, 3704.74066815323, 3710.350688629479, 34531.654683453344, 34590.0, 34657.133571660655, 34662.65337346645, 34687.132821679406, 5357.089294819851, 5565.805196818133, 5569.136783798518, 5569.164625306196, 5619.719014049322, 215262.0, 216277.0, 216397.0, 216447.0, 216501.99999999843, 46598.0, 47342.76328618335, 50871.49128508742, 50977.74511127495, 51083.0, 56127.0, 56603.43396566064, 57015.714921425955, 57196.428035719946, 57272.71363643238, 15010.099394036175, 15067.547973560811, 15122.54632361031, 15124.54626361211, 15159.924200379148, 11966.58116965902, 11998.400079995965, 12004.75990480203, 12013.819792703116, 12014.699632509257, 14932.552023439313, 15121.621959451102, 15157.166355850533, 15217.239138043053, 15336.079835209923, 5869.853253668607, 5889.116632505158, 5900.64596124234, 6019.608725432833, 6032.849178770566, 1051.8106740786732, 1070.983935240972, 1104.5802595013843, 1111.488715191005, 1120.910327173824, 25493.8725306376, 25561.0, 25756.227313180636, 25769.61345579818, 25814.741852581235, 1169.7660467906453, 1183.4851839449866, 1191.70803153228, 1205.873383294759, 1213.9028877689764, 13384.866151338556, 13510.0, 13554.525591604444, 13574.864251357558, 13720.86279137196, 268011.0, 268539.0, 269529.0, 270956.0, 271005.0, 58613.41386586165, 58628.0, 58668.41331586715, 58720.7063964686, 58889.11666325008, 1468.1924941282252, 1495.9326830292664, 1497.2663394936621, 1510.9951881998347, 1522.7868098466224, 25559.361015974748, 25928.351791205372, 25942.870285648452, 26006.34984125412, 26157.215283541525, 42067.7896610515, 42211.0, 42314.36528452137, 42333.153336933705, 42351.788241058595, 234374.0, 234584.0, 235039.0, 235684.0, 236350.0, 36583.81708091443, 38057.80971095127, 38861.22277554376, 38991.41512877366, 39095.80452097835, 51068.0, 51349.743251285, 51943.961120776614, 52012.479875202276, 52138.21792673037, 36949.44575831311, 37177.1222323871, 37296.81351593225, 37747.43378849264, 37759.81120094382, 37006.814965925, 37200.25599488049, 37230.0, 37724.0, 38230.0, 43053.3541996864, 44542.331865021406, 44558.3316250263, 44667.55332446634, 45064.324035140155, 34711.0, 35420.0, 35492.29015419626, 35853.28293434169, 36008.099797505274, 25886.870565647052, 26928.32679183036, 27164.32089197786, 27401.45197096007, 27628.58557121685], "value": 18741.176431114236, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [562.4684672984032, 580.5471732049003, 587.1720873568269, 588.9734961926713, 610.4719417703686, 22751.658725119116, 23495.0, 23535.76464235357, 23540.64689029666, 23547.882260588696, 24963.251102466955, 25223.369415764617, 26747.598786018178, 26948.056818011373, 26999.325016874587, 6697.1963364396215, 6800.42196413303, 6843.555168914029, 6847.93152068478, 6851.45188384928, 6542.967285163568, 6673.599584024974, 6764.154480689914, 6819.522633415663, 6823.317668233161, 6786.016027675984, 6789.7284108635595, 6791.660416979156, 6796.626185559792, 6796.660166991631, 6525.0864878916755, 6765.797026089201, 6767.830804229909, 6779.423748981293, 6780.0, 6305.274893387274, 6319.778807741753, 6328.810135695937, 6331.430171284561, 6352.4918006559365, 30551.2362190947, 31122.533162002586, 31195.532067019012, 31337.0, 31362.843185784153, 2252.0653928619604, 2284.280451657739, 2285.04028308111, 2289.175896677195, 2294.9655755163685, 20709.792902071087, 21353.0, 21531.67702484464, 21534.138634454604, 21550.353489395187, 2538.771510564058, 2577.7164511903675, 2618.8297760645496, 2632.4603456291416, 2634.2360715392515, 131778.0, 138966.0, 139368.99999999898, 139424.0, 154310.0, 15101.35540362259, 15880.92059539718, 16118.194090295437, 16433.260503277383, 16639.916800415922, 24907.128250511145, 24971.62542561863, 25031.249062528153, 25035.749642503706, 25088.37279068038, 9527.714168574954, 9637.193308643047, 9642.95178524117, 9809.362391444532, 9856.852147217798, 8606.139386061344, 8646.221840034426, 8716.607752651145, 8725.21473067427, 8794.86807697885, 9587.328886977984, 9597.856032159523, 9632.229421646392, 9752.46361450127, 9862.211023118134, 2695.0623820036253, 2704.188743376979, 2789.539725945237, 2789.7907656925813, 2884.942301153965, 1014.5130337437923, 1040.625374865048, 1055.8574592429923, 1056.4189695667353, 1062.5006247063868, 21766.673499897515, 21837.60982777421, 21907.780922190574, 22014.229501967355, 22512.32463026112, 1095.9342439453658, 1101.2731597146005, 1110.134095405585, 1116.5198964445367, 1128.6501184632791, 11942.940285298519, 12059.638210853689, 12076.396180191132, 12139.089568282414, 12164.209326393757, 548590.0, 571438.0, 580329.0, 581949.0, 589439.0, 130053.0, 131311.0, 132134.33932830466, 132832.0, 132888.3355583216, 1169.0180248591225, 1238.8575313838844, 1239.0335538280156, 1244.8008318669151, 1249.443997421157, 19329.4201173965, 19367.77070191012, 19599.02004899778, 19976.400707978784, 20014.799852001295, 61323.38676613177, 63473.36526634859, 64492.0, 65018.674906625165, 65546.67226663836, 81084.5945770291, 82790.17209828066, 83659.5817020911, 84257.57871210604, 84418.0, 15450.459233926758, 15564.84435155634, 15636.374545018072, 15804.130772807146, 15807.525774226791, 24692.629610556214, 24720.752792471845, 25010.0, 25038.248852534452, 25079.24762257135, 19990.500237493598, 20044.899775501028, 20215.39353819388, 20460.283890063776, 20507.58984820325, 17454.476365709048, 17576.472705818847, 17851.37520186787, 18179.18193681261, 18415.631687366447, 20542.38372848817, 20923.686144708146, 20956.0, 21051.684224736946, 21053.0, 15106.320215590544, 15686.764698529303, 15729.370825166865, 16186.67626647484, 16358.836411635732, 20549.0, 20564.766114033206, 20607.896960515103, 20759.27342543064, 20785.376438706862], "value": 12785.731714114474, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1394.6931675031492, 1450.4053338131368, 1463.8536146385359, 1470.088545102037, 1475.3950880139146, 16710.832891671078, 17335.913320433396, 17351.305947762106, 17366.218520166592, 17366.652666946684, 16793.412230571932, 16957.0, 16962.0, 16972.83027169728, 16982.49052528423, 2723.754862062414, 2798.4962706712786, 2819.873105710248, 2834.8157369770897, 2836.347640042791, 2696.32591852037, 2722.060888993304, 2736.53478908585, 2745.5448612235546, 2772.0, 2659.9601005984928, 2696.137236084454, 2782.2209781261176, 2782.833030018195, 2783.498970185366, 2738.589211618253, 2769.5984082308055, 2772.209920172741, 2800.7339302766204, 2808.620836187119, 2631.1054241557963, 2639.4061336199393, 2661.733826617342, 2662.9600555991674, 2663.893444262227, 16468.341266349333, 16919.0, 16932.322707091702, 16943.83056169447, 16965.83034169655, 8074.152214017561, 8356.415050946436, 8357.916420835774, 8363.58182090899, 8366.790830229293, 5385.973070134663, 5547.142268409804, 5560.638558493685, 5633.521150702195, 5653.717314134276, 1962.2445358537066, 1967.3507742444958, 1970.123295133666, 2003.7996200379846, 2007.8293345065688, 29760.0, 29889.551656725384, 29930.55104173439, 29978.25054373636, 30001.249968750737, 7009.0, 7180.0, 7212.74755383559, 7378.040854688856, 7380.225076367012, 19314.32399865998, 19315.227390904205, 19328.1302341395, 19329.903350483157, 19350.709739353922, 8024.478408903403, 8134.308583770446, 8159.551224682699, 8163.7142700005215, 8164.714235001747, 7464.477486575996, 7538.660760265801, 7542.208068152875, 7626.733064342721, 7628.8474230516185, 5511.834644960658, 5647.858803529863, 5715.657060576379, 5765.3946335634155, 5776.277965254343, 1567.3495499367634, 1687.4178408449066, 1690.5435532406182, 1707.5218938697185, 1718.9226484808214, 846.3609974469236, 912.2337236721054, 916.8349697054595, 933.4446004627235, 944.9558238146886, 7779.572123533147, 7843.294103530709, 7904.644291006918, 7911.881321780178, 7994.200579941959, 1033.9793204135874, 1035.1046344911597, 1039.111559616532, 1044.8693913260843, 1054.920880933933, 4646.0, 4801.303810947463, 4801.991581767798, 4816.036792641486, 4825.758712064383, 24355.87822060914, 24562.263132106065, 24725.0, 25002.12492562788, 25360.873195633903, 25190.118345858173, 25191.748082519305, 25423.0, 25435.87282063615, 25482.235532934043, 12142.45358958849, 12542.623721288375, 12548.874511254953, 12575.559855404834, 12649.747005059848, 8771.473711577326, 8792.340574456814, 8806.779830504289, 8870.467771933705, 8926.330525210766, 20933.16267349289, 21488.355349339545, 21642.89178554097, 21957.341279761633, 21966.0, 21286.78713212848, 21489.892550537148, 21600.783992160505, 21793.564128717655, 22206.111755530244, 11792.646220613395, 11797.115216358636, 11839.289642621126, 11934.4032798358, 11969.0, 15077.472288469851, 15435.536933892, 15445.304961276994, 15448.073115613099, 15451.072935623899, 12309.0, 12402.937985310016, 12444.937775311066, 12498.562550310695, 12775.1057425983, 12361.62915112548, 12412.751744965231, 12450.750984980068, 12450.81323780126, 12692.555760548337, 10025.147862431775, 10061.798764024825, 10155.543000564845, 10160.390376577428, 10164.89835101669, 7834.647440865061, 7917.920820791949, 7992.360611150978, 8067.596620169085, 8161.714339998071, 7703.499272547267, 7716.652750626347, 7732.110807257238, 7807.531548107132, 7821.374290056668], "value": 7129.057702979185, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [536.118085748943, 544.3576579636031, 557.1392199052464, 564.3904583050306, 576.8356018534715, 5980.760769569218, 6174.567780255389, 6179.691015449232, 6258.249010118787, 6262.906056409158, 6176.166217560639, 6242.625442473455, 6244.56288059836, 6250.718717657705, 6259.780907668232, 5085.847424577269, 5135.589152867761, 5155.793768249266, 5178.896422071566, 5184.585233181337, 4881.780319885614, 4945.727984960826, 5163.709072731817, 5165.354330708661, 5172.508611681884, 4945.530174633404, 4991.351124353847, 5021.096202683516, 5031.213919058737, 5040.117979353617, 4913.624185228124, 4914.75426228689, 4956.587372598792, 4988.825391111293, 5008.824691135793, 4511.481179664348, 4537.6143027842345, 4543.5002149763495, 4548.408706868119, 4578.702384345007, 6132.080187971796, 6181.876362472725, 6192.256929168483, 6198.318184999646, 6252.937470625281, 5778.711064446761, 5829.650220986754, 5853.824385268449, 5863.70681465925, 5914.940850591482, 6569.146011018537, 6577.80266592003, 6645.60126392418, 6665.566738162004, 6669.732291021028, 5049.368828896388, 5050.166722490784, 5067.543921047087, 5081.28861959326, 5101.923471147936, 6928.099347084897, 6953.721851126, 6958.721651133898, 7007.264237255065, 7041.612711300876, 5147.330846989942, 5173.63784535079, 5196.220566914917, 5208.609354298367, 5215.504527069922, 5210.426853046162, 5287.9735601321745, 5296.364436267672, 5296.364436267672, 5334.07973129673, 5790.71046447676, 5838.474537291664, 5852.326982397079, 5937.970310148422, 5949.286085669747, 6630.270670226271, 6649.7033078550185, 6669.733210671616, 6705.2624211336715, 6706.429953454011, 1726.335360886068, 1787.642471505704, 1792.0, 1816.3461153984556, 1826.954326141857, 1278.5525066226933, 1285.5436320106414, 1301.1477482249143, 1303.2831942431624, 1311.6261865368324, 6024.0, 6157.907631385533, 6161.291451483047, 6199.876002479925, 6224.813255602339, 6753.49348798842, 6808.8978665320055, 6842.281560436082, 6842.4868134890085, 6857.931420685829, 6306.747730090745, 6311.936880631135, 6314.305426403092, 6343.3339499351905, 6347.52393570484, 3567.5362202913457, 3567.965290065904, 3579.6420357963993, 3593.874214402483, 3595.0832537702718, 7135.7859264221115, 7155.212926578072, 7165.706973768344, 7169.462290328246, 7182.497225194186, 7121.608311542915, 7123.928760712431, 7134.607596582134, 7142.0, 7155.606441645759, 7129.679164437509, 7145.749898753519, 7149.714011439588, 7163.6060016699585, 7210.71157153708, 6807.0810440589885, 6824.590524568542, 6825.385715285648, 6856.41720453767, 6890.276520965427, 6728.394444500019, 6785.491088168308, 6795.524313298021, 6798.864022719419, 6819.727210911707, 7002.8949565757575, 7056.1179852518435, 7118.60847653384, 7154.673077019211, 7157.892631610426, 5178.43037265908, 5195.948040519546, 5232.659877107976, 5236.842894713165, 5250.300980622245, 6536.182977127859, 6705.530612857248, 6783.846746053084, 6787.93212067873, 6866.759663411756, 6330.454945230686, 6494.837629059311, 6516.185476815398, 6591.835204119743, 6603.537752357289, 6452.451541619015, 6482.124913136666, 6504.284528701744, 6526.379993900572, 6563.540552161494, 6400.295967443671, 6430.164078669743, 6437.0, 6494.805155845333, 6516.57642253271, 6545.2800191979795, 6596.8350791230605, 6612.735490580515, 6691.0, 6727.125473688389, 6382.0, 6395.360463953567, 6396.232452105777, 6440.967795160994, 6493.188351456068], "value": 5206.612589141116, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [957.7414098193492, 981.7692842182086, 986.107572646755, 993.3195760903782, 1005.1958433253398, 19167.904160479196, 19784.90107549462, 19881.3041543546, 19883.502912427197, 19885.105170267354, 17933.461996140104, 18121.728174077365, 18297.54256143597, 18485.7227141593, 18549.53626159347, 989.1098011789376, 996.9451680157589, 999.0558921818885, 999.5901680311074, 999.9500024998757, 1007.1388962437121, 1026.712520494259, 1031.7111208861531, 1032.194887987371, 1036.5542816588863, 1093.923425360221, 1094.7263184203948, 1101.0585949013598, 1102.2504696806168, 1103.8013157631624, 1052.4001319248032, 1078.92986955848, 1083.5178345636155, 1083.6261489786011, 1085.8751243607007, 782.9021372328459, 783.600363814453, 783.6786917363853, 783.9921600784033, 784.2745460449119, 19838.80161198384, 20223.79776202234, 20310.0, 20331.288404905907, 20381.592368152702, 21688.783112168836, 21710.565788684296, 22199.001044952853, 22395.0, 22429.551408971893, 17723.645527089517, 17964.371247006424, 17966.19152138157, 17975.910120449444, 17981.910090449463, 3890.6109389060866, 3945.9013524661827, 3977.8607748728946, 3983.6215559521797, 3986.142979259444, 144681.2765936174, 145070.0, 146040.0, 147278.0, 150406.9999999989, 15736.527904162664, 15862.286197121157, 16270.349186032663, 16275.430359937345, 16388.180590970405, 27133.86433067822, 27179.456410871673, 27311.726882731316, 27316.31709207246, 27379.452410952068, 8938.329625278131, 9023.097690231056, 9112.453252804853, 9181.081891810765, 9202.401843880127, 4545.3863728396245, 4621.1219868224925, 4627.6529260305615, 4638.768061596907, 4643.86068417948, 5911.704414779244, 5953.22608060958, 6024.457798798129, 6045.244344456943, 6079.817605471843, 1053.210092430677, 1057.6456886942806, 1079.0450451350612, 1083.3012706804068, 1084.6420681175193, 728.730369763191, 740.7222291640635, 745.4520927118564, 757.7120694136192, 771.2750014986008, 12442.191257568229, 12531.624051278477, 12904.548340808027, 13068.215907045607, 13286.069975101844, 726.963651817407, 727.6943683652772, 733.8238822682517, 740.9555426674417, 746.358132006474, 5666.829995100154, 5681.346645135863, 5702.2301989231755, 5759.884802303931, 5763.077907534858, 147346.0, 150547.0, 150935.0, 151854.0, 151978.0, 123972.0, 125557.0, 128966.35516822356, 129087.0, 131785.0, 108176.45911770391, 108391.47558025648, 110379.0, 113914.0, 116123.99999999831, 12153.696157595778, 12413.200085987663, 12553.435095420546, 12660.810087848506, 12876.420561074961, 53233.20150197673, 53279.73360133175, 53888.0, 54816.72591637016, 55853.16220256618, 67491.3250867485, 69222.30777692159, 69591.17456197315, 69859.30140698528, 70644.64677676579, 19074.7138792921, 19363.51591210231, 19406.805931941064, 19488.902555487133, 19621.509462262988, 22780.202692906332, 22818.42953926165, 22908.54182916299, 22944.4263893404, 23042.769572304733, 20769.169233230503, 20985.370438886857, 21070.15719371208, 21106.261280855095, 21220.89389553104, 20373.694394583796, 20703.792962070787, 20792.48018799542, 20927.895360523707, 21056.68414973746, 20116.19535218575, 20231.0, 20275.898620507392, 20375.0, 20604.484887877923, 13566.864331356956, 13739.405774445875, 13799.17204967665, 13865.722685546434, 14400.927995359956, 13497.122687025312, 13576.253306068262, 13579.79630305566, 13647.249401283025, 13677.726445471233], "value": 9844.061746738293, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1390.9, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 108.0, 105.0, 106.0, 106.0, 111.0, 108.0, 105.0, 106.0, 109.0], "value": 107.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 112.0, 115.0, 112.0, 114.0, 115.0, 112.0, 112.0, 112.0, 118.0], "value": 113.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 163.0, 163.0, 162.0, 161.0, 163.0, 163.0, 163.0, 163.0, 164.0], "value": 162.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 73.0, 73.0, 73.0, 73.0, 74.0, 74.0, 74.0, 73.0, 74.0], "value": 73.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 78.0, 80.0, 84.0, 78.0, 77.0, 78.0, 78.0, 77.0, 79.0], "value": 78.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 114.0, 114.0, 114.0, 114.0, 115.0, 115.0, 114.0, 111.0, 114.0], "value": 113.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 149.0, 150.0, 149.0, 150.0, 149.0, 150.0, 150.0, 151.0, 149.0], "value": 149.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 99.0, 93.0, 94.0, 98.0, 94.0, 93.0, 93.0, 94.0, 94.0], "value": 95.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [49.0, 45.0, 46.0, 45.0, 46.0, 47.0, 47.0, 47.0, 45.0, 45.0], "value": 46.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [37.0, 37.0, 38.0, 37.0, 39.0, 37.0, 37.0, 37.0, 37.0, 37.0], "value": 37.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 77.0, 74.0, 72.0, 72.0, 73.0, 72.0, 79.0, 74.0, 75.0], "value": 74.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 116.0, 115.0, 116.0, 111.0, 115.0, 140.0, 124.0, 125.0, 116.0], "value": 119.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 170.0, 157.0, 159.0, 156.0, 156.0, 157.0, 166.0, 166.0, 157.0], "value": 160.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 60.0, 62.0, 58.0, 58.0, 61.0, 60.0, 61.0, 58.0, 57.0], "value": 59.9, "unit": "score"}]}]} 16:41:54 INFO - Return code: 0 16:41:54 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:41:54 INFO - # TBPL SUCCESS # 16:41:54 INFO - Running post-action listener: _package_coverage_data 16:41:54 INFO - Running post-action listener: _resource_record_post_action 16:41:54 INFO - [mozharness: 2017-09-07 23:41:54.480870Z] Finished run-tests step (success) 16:41:54 INFO - Running post-run listener: _resource_record_post_run 16:41:54 INFO - Total resource usage - Wall time: 1186s; CPU: 13.0%; Read bytes: 4096; Write bytes: 388853760; Read time: 216; Write time: 370376 16:41:54 INFO - TinderboxPrint: CPU usage
13.1% 16:41:54 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 216 16:41:54 INFO - TinderboxPrint: I/O write bytes / time
388,853,760 / 370,376 16:41:54 INFO - TinderboxPrint: CPU idle
8,220.0 (86.9%) 16:41:54 INFO - TinderboxPrint: CPU user
1,186.4 (12.5%) 16:41:54 INFO - TinderboxPrint: Swap in / out
0 / 0 16:41:54 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 109658112; Read time: 0; Write time: 167736 16:41:54 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 16:41:54 INFO - run-tests - Wall time: 1170s; CPU: 13.0%; Read bytes: 4096; Write bytes: 279195648; Read time: 216; Write time: 202640 16:41:55 INFO - Running post-run listener: _upload_blobber_files 16:41:55 INFO - Blob upload gear active. 16:41:55 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:41:55 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:41:55 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:41:55 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:41:55 INFO - (blobuploader) - INFO - Open directory for files ... 16:41:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 16:41:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:41:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:41:56 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 16:41:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:41:56 INFO - (blobuploader) - INFO - Done attempting. 16:41:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:41:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:41:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:42:03 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:42:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:42:03 INFO - (blobuploader) - INFO - Done attempting. 16:42:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 16:42:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:42:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:42:04 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 16:42:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:42:04 INFO - (blobuploader) - INFO - Done attempting. 16:42:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 16:42:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:42:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:42:05 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 16:42:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:42:05 INFO - (blobuploader) - INFO - Done attempting. 16:42:05 INFO - (blobuploader) - INFO - Iteration through files over. 16:42:05 INFO - Return code: 0 16:42:05 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:42:05 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:42:05 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3a5e3e895e73f9b3484a7568f88533ac8948b255257f3b76fe55a7b458f1be65304ae5c87e98971917ee3a569517cb302eb2e909ff5cdee201b572f0e3e7f7ad", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2e84b693e522ff0ce7f881f724943c5eb6f2b085082f2b138f7805d87c60df8f18f3026860e50c0cb79c6588eaab1d32e04755db6e7e5c20558070ecede2900e", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e8818e07d2e42390f36680c681efc6fbd92c8bbd03d92ee1c63c533d7c26bfa68ce0dbf52f5b68d1b30a86199fdfb70013cad445c605d36ecb1ef5c02256b831", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e8a2ffec926d76161bffeef0cdc1f9b16f60d6cd53dc66b79012893d71487429c019e8e298df5ff6fa3c57e5a430ca154137b45392a079a19d2ec2b68f892464"} 16:42:05 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:42:05 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:42:05 INFO - Contents: 16:42:05 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3a5e3e895e73f9b3484a7568f88533ac8948b255257f3b76fe55a7b458f1be65304ae5c87e98971917ee3a569517cb302eb2e909ff5cdee201b572f0e3e7f7ad", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2e84b693e522ff0ce7f881f724943c5eb6f2b085082f2b138f7805d87c60df8f18f3026860e50c0cb79c6588eaab1d32e04755db6e7e5c20558070ecede2900e", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e8818e07d2e42390f36680c681efc6fbd92c8bbd03d92ee1c63c533d7c26bfa68ce0dbf52f5b68d1b30a86199fdfb70013cad445c605d36ecb1ef5c02256b831", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e8a2ffec926d76161bffeef0cdc1f9b16f60d6cd53dc66b79012893d71487429c019e8e298df5ff6fa3c57e5a430ca154137b45392a079a19d2ec2b68f892464"} 16:42:05 INFO - Running post-run listener: copy_logs_to_upload_dir 16:42:05 INFO - Copying logs to upload dir... 16:42:05 INFO - mkdir: /builds/slave/test/build/upload/logs 16:42:05 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1250.276676 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 50 secs) (at 2017-09-07 16:42:05.483905) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-07 16:42:05.487493) ========= 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=ea65d483da93c827905c2090000001f5-1504826472.457837-528075096 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/3a5e3e895e73f9b3484a7568f88533ac8948b255257f3b76fe55a7b458f1be65304ae5c87e98971917ee3a569517cb302eb2e909ff5cdee201b572f0e3e7f7ad", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2e84b693e522ff0ce7f881f724943c5eb6f2b085082f2b138f7805d87c60df8f18f3026860e50c0cb79c6588eaab1d32e04755db6e7e5c20558070ecede2900e", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e8818e07d2e42390f36680c681efc6fbd92c8bbd03d92ee1c63c533d7c26bfa68ce0dbf52f5b68d1b30a86199fdfb70013cad445c605d36ecb1ef5c02256b831", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e8a2ffec926d76161bffeef0cdc1f9b16f60d6cd53dc66b79012893d71487429c019e8e298df5ff6fa3c57e5a430ca154137b45392a079a19d2ec2b68f892464"} 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.011371 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/3a5e3e895e73f9b3484a7568f88533ac8948b255257f3b76fe55a7b458f1be65304ae5c87e98971917ee3a569517cb302eb2e909ff5cdee201b572f0e3e7f7ad", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2e84b693e522ff0ce7f881f724943c5eb6f2b085082f2b138f7805d87c60df8f18f3026860e50c0cb79c6588eaab1d32e04755db6e7e5c20558070ecede2900e", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e8818e07d2e42390f36680c681efc6fbd92c8bbd03d92ee1c63c533d7c26bfa68ce0dbf52f5b68d1b30a86199fdfb70013cad445c605d36ecb1ef5c02256b831", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e8a2ffec926d76161bffeef0cdc1f9b16f60d6cd53dc66b79012893d71487429c019e8e298df5ff6fa3c57e5a430ca154137b45392a079a19d2ec2b68f892464"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-07 16:42:05.519496) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-07 16:42:05.519819) ========= 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=ea65d483da93c827905c2090000001f5-1504826472.457837-528075096 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004651 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-07 16:42:05.604338) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-07 16:42:05.604654) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-07 16:42:05.604961) ========= ========= Total master_lag: 0.20 =========