builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-079 starttime: 1504787388.16 results: success (0) revision: 6469a2cb7df662afb23493b5566f99b6802fcb5a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-07 05:29:48.165462) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-07 05:29:48.166197) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-07 05:29:48.166668) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-07 05:29:48.195843) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-07 05:29:48.196338) ========= 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=1258088b6da462a47ba2cd90000001a2-1504787377.144078-1273233852 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-07 05:29:48-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 46.8M=0s 2017-09-07 05:29:48 (46.8 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.252519 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-07 05:29:48.465770) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-07 05:29:48.466272) ========= 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=1258088b6da462a47ba2cd90000001a2-1504787377.144078-1273233852 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.060008 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-07 05:29:48.542358) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-07 05:29:48.542692) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 6469a2cb7df662afb23493b5566f99b6802fcb5a --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 6469a2cb7df662afb23493b5566f99b6802fcb5a --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=1258088b6da462a47ba2cd90000001a2-1504787377.144078-1273233852 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-07 05:29:48,600 truncating revision to first 12 chars 2017-09-07 05:29:48,600 Setting DEBUG logging. 2017-09-07 05:29:48,600 attempt 1/10 2017-09-07 05:29:48,600 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/6469a2cb7df6?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-07 05:29:49,308 unpacking tar archive at: mozilla-central-6469a2cb7df6/testing/mozharness/ program finished with exit code 0 elapsedTime=0.981144 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-07 05:29:49.539935) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-07 05:29:49.540241) ========= script_repo_revision: 6469a2cb7df662afb23493b5566f99b6802fcb5a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-07 05:29:49.540632) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-07 05:29:49.540896) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-07 05:29:49.553639) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 51 secs) (at 2017-09-07 05:29:49.554204) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 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-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] 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=1258088b6da462a47ba2cd90000001a2-1504787377.144078-1273233852 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 05:29:49 INFO - MultiFileLogger online at 20170907 05:29:49 in /builds/slave/test 05:29:49 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 05:29:49 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:29:49 INFO - {'append_to_log': False, 05:29:49 INFO - 'base_work_dir': '/builds/slave/test', 05:29:49 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 05:29:49 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:29:49 INFO - 'branch': 'Firefox-Non-PGO', 05:29:49 INFO - 'buildbot_json_path': 'buildprops.json', 05:29:49 INFO - 'code_coverage': False, 05:29:49 INFO - 'config_files': ('talos/linux_config.py',), 05:29:49 INFO - 'default_actions': ('clobber', 05:29:49 INFO - 'read-buildbot-config', 05:29:49 INFO - 'download-and-extract', 05:29:49 INFO - 'populate-webroot', 05:29:49 INFO - 'create-virtualenv', 05:29:49 INFO - 'install', 05:29:49 INFO - 'setup-mitmproxy', 05:29:49 INFO - 'run-tests'), 05:29:49 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:29:49 INFO - 'disable_ccov_upload': False, 05:29:49 INFO - 'disable_stylo': False, 05:29:49 INFO - 'download_minidump_stackwalk': True, 05:29:49 INFO - 'download_symbols': 'ondemand', 05:29:49 INFO - 'enable_stylo': False, 05:29:49 INFO - 'enable_webrender': False, 05:29:49 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 05:29:49 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:29:49 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:29:49 INFO - 'gecko_profile': False, 05:29:49 INFO - 'gecko_profile_interval': 0, 05:29:49 INFO - 'installer_path': 'installer.exe', 05:29:49 INFO - 'log_level': 'info', 05:29:49 INFO - 'log_name': 'talos', 05:29:49 INFO - 'log_to_console': True, 05:29:49 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 05:29:49 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 05:29:49 INFO - 'opt_config_files': (), 05:29:49 INFO - 'pip_index': False, 05:29:49 INFO - 'suite': 'dromaeojs-e10s', 05:29:49 INFO - 'system_bits': '32', 05:29:49 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:29:49 INFO - 'title': 'talos-linux64-ix-079', 05:29:49 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:29:49 INFO - 'use_talos_json': True, 05:29:49 INFO - 'verify': 'False', 05:29:49 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:29:49 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:29:49 INFO - 'work_dir': 'build'} 05:29:49 INFO - [mozharness: 2017-09-07 12:29:49.889615Z] Running clobber step. 05:29:49 INFO - Running pre-action listener: _resource_record_pre_action 05:29:49 INFO - Running main action method: clobber 05:29:49 INFO - rmtree: /builds/slave/test/build 05:29:49 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:29:51 INFO - Running post-action listener: _resource_record_post_action 05:29:51 INFO - [mozharness: 2017-09-07 12:29:51.929521Z] Finished clobber step (success) 05:29:51 INFO - [mozharness: 2017-09-07 12:29:51.929622Z] Running read-buildbot-config step. 05:29:51 INFO - Running pre-action listener: _resource_record_pre_action 05:29:51 INFO - Running main action method: read_buildbot_config 05:29:51 INFO - Using buildbot properties: 05:29:51 INFO - { 05:29:51 INFO - "project": "", 05:29:51 INFO - "product": "firefox", 05:29:51 INFO - "who": "archaeopteryx@coole-files.de", 05:29:51 INFO - "installer_path": "public/build/target.tar.bz2", 05:29:51 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 05:29:51 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 05:29:51 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 05:29:51 INFO - "stage_platform": "linux64", 05:29:51 INFO - "basedir": "/builds/slave/test", 05:29:51 INFO - "buildnumber": 1120, 05:29:51 INFO - "platform": "ubuntu64_hw", 05:29:51 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 05:29:51 INFO - "slavebuilddir": "test", 05:29:51 INFO - "taskId": "Fz6N1f-_QQK7BRkYFvQHlQ", 05:29:51 INFO - "branch": "mozilla-central", 05:29:51 INFO - "script_repo_revision": "production", 05:29:51 INFO - "revision": "6469a2cb7df662afb23493b5566f99b6802fcb5a", 05:29:51 INFO - "slavename": "talos-linux64-ix-079", 05:29:51 INFO - "repo_path": "mozilla-central" 05:29:51 INFO - } 05:29:51 INFO - Finding installer, test and symbols from parent task. 05:29:51 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Fz6N1f-_QQK7BRkYFvQHlQ'}, attempt #1 05:29:52 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Fz6N1f-_QQK7BRkYFvQHlQ'}, attempt #1 05:29:52 INFO - Task dependencies: BaAK-As6Q66QQapQlMtaJw 05:29:52 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BaAK-As6Q66QQapQlMtaJw'}, attempt #1 05:29:53 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/BaAK-As6Q66QQapQlMtaJw/artifacts/public/build/target.tar.bz2 05:29:53 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/BaAK-As6Q66QQapQlMtaJw/artifacts/public/build/target.test_packages.json 05:29:53 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/BaAK-As6Q66QQapQlMtaJw/artifacts/public/build/target.crashreporter-symbols.zip 05:29:53 INFO - Running post-action listener: _resource_record_post_action 05:29:53 INFO - [mozharness: 2017-09-07 12:29:53.297561Z] Finished read-buildbot-config step (success) 05:29:53 INFO - [mozharness: 2017-09-07 12:29:53.297788Z] Running download-and-extract step. 05:29:53 INFO - Running pre-action listener: _resource_record_pre_action 05:29:53 INFO - Running main action method: download_and_extract 05:29:53 INFO - mkdir: /builds/slave/test/build/tests 05:29:53 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')]} 05:29:53 INFO - trying https://queue.taskcluster.net/v1/task/BaAK-As6Q66QQapQlMtaJw/artifacts/public/build/target.test_packages.json 05:29:53 INFO - Downloading https://queue.taskcluster.net/v1/task/BaAK-As6Q66QQapQlMtaJw/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 05:29:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BaAK-As6Q66QQapQlMtaJw/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 05:29:53 INFO - Downloaded 989 bytes. 05:29:53 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 05:29:53 INFO - Using the following test package requirements: 05:29:53 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 05:29:53 INFO - u'common': [u'target.common.tests.zip'], 05:29:53 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 05:29:53 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 05:29:53 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 05:29:53 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 05:29:53 INFO - u'mozbase': [u'target.common.tests.zip'], 05:29:53 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 05:29:53 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 05:29:53 INFO - u'web-platform': [u'target.common.tests.zip', 05:29:53 INFO - u'target.web-platform.tests.tar.gz'], 05:29:53 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 05:29:53 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 05:29:53 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/BaAK-As6Q66QQapQlMtaJw/artifacts/public/build/target.common.tests.zip 05:29:53 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/BaAK-As6Q66QQapQlMtaJw/artifacts/public/build/target.common.tests.zip'}, attempt #1 05:29:53 INFO - Fetch https://queue.taskcluster.net/v1/task/BaAK-As6Q66QQapQlMtaJw/artifacts/public/build/target.common.tests.zip into memory 05:29:54 INFO - Content-Length response header: 55021200 05:29:54 INFO - Bytes received: 55021200 05:30:06 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 05:30:06 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/BaAK-As6Q66QQapQlMtaJw/artifacts/public/build/target.common.tests.zip 05:30:06 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/BaAK-As6Q66QQapQlMtaJw/artifacts/public/build/target.common.tests.zip'}, attempt #1 05:30:06 INFO - Fetch https://queue.taskcluster.net/v1/task/BaAK-As6Q66QQapQlMtaJw/artifacts/public/build/target.common.tests.zip into memory 05:30:07 INFO - Content-Length response header: 55021200 05:30:07 INFO - Bytes received: 55021200 05:30:20 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/BaAK-As6Q66QQapQlMtaJw/artifacts/public/build/target.talos.tests.zip 05:30:20 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/BaAK-As6Q66QQapQlMtaJw/artifacts/public/build/target.talos.tests.zip'}, attempt #1 05:30:20 INFO - Fetch https://queue.taskcluster.net/v1/task/BaAK-As6Q66QQapQlMtaJw/artifacts/public/build/target.talos.tests.zip into memory 05:30:21 INFO - Content-Length response header: 14496665 05:30:21 INFO - Bytes received: 14496665 05:30:21 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')]} 05:30:21 INFO - trying https://queue.taskcluster.net/v1/task/BaAK-As6Q66QQapQlMtaJw/artifacts/public/build/target.tar.bz2 05:30:21 INFO - Downloading https://queue.taskcluster.net/v1/task/BaAK-As6Q66QQapQlMtaJw/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 05:30:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BaAK-As6Q66QQapQlMtaJw/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 05:30:22 INFO - Downloaded 62783660 bytes. 05:30:22 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/BaAK-As6Q66QQapQlMtaJw/artifacts/public/build/target.tar.bz2 05:30:22 INFO - mkdir: /builds/slave/test/properties 05:30:22 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:30:22 INFO - Writing to file /builds/slave/test/properties/build_url 05:30:22 INFO - Contents: 05:30:22 INFO - build_url:https://queue.taskcluster.net/v1/task/BaAK-As6Q66QQapQlMtaJw/artifacts/public/build/target.tar.bz2 05:30:22 INFO - Running post-action listener: _resource_record_post_action 05:30:22 INFO - Running post-action listener: find_tests_for_verification 05:30:22 INFO - Running post-action listener: set_extra_try_arguments 05:30:22 INFO - [mozharness: 2017-09-07 12:30:22.775102Z] Finished download-and-extract step (success) 05:30:22 INFO - [mozharness: 2017-09-07 12:30:22.775200Z] Running populate-webroot step. 05:30:22 INFO - Running pre-action listener: _resource_record_pre_action 05:30:22 INFO - Running main action method: populate_webroot 05:30:22 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:30:22 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 05:30:22 INFO - 'tests': ['tresize', 05:30:22 INFO - 'tcanvasmark']}, 05:30:22 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:30:22 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 05:30:22 INFO - 'tests': ['dromaeo_css', 05:30:22 INFO - 'kraken']}, 05:30:22 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 05:30:22 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:30:22 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 05:30:22 INFO - 'talos_options': ['--disable-stylo'], 05:30:22 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:30:22 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 05:30:22 INFO - 'tests': ['damp', 'tps']}, 05:30:22 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 05:30:22 INFO - 'talos_options': ['--disable-stylo'], 05:30:22 INFO - 'tests': ['damp', 'tps']}, 05:30:22 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:30:22 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 05:30:22 INFO - 'tests': ['dromaeo_dom']}, 05:30:22 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 05:30:22 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 05:30:22 INFO - 'tests': ['basic_compositor_video', 05:30:22 INFO - 'glvideo']}, 05:30:22 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 05:30:22 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 05:30:22 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 05:30:22 INFO - 'talos_options': ['--disable-stylo'], 05:30:22 INFO - 'tests': ['ts_paint_webext', 05:30:22 INFO - 'tp5o_webext']}, 05:30:22 INFO - 'other-e10s': {'tests': ['a11yr', 05:30:22 INFO - 'ts_paint', 05:30:22 INFO - 'tpaint', 05:30:22 INFO - 'sessionrestore', 05:30:22 INFO - 'sessionrestore_many_windows', 05:30:22 INFO - 'sessionrestore_no_auto_restore', 05:30:22 INFO - 'tabpaint']}, 05:30:22 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 05:30:22 INFO - 'tests': ['a11yr', 05:30:22 INFO - 'ts_paint', 05:30:22 INFO - 'tpaint', 05:30:22 INFO - 'sessionrestore', 05:30:22 INFO - 'sessionrestore_many_windows', 05:30:22 INFO - 'sessionrestore_no_auto_restore', 05:30:22 INFO - 'tabpaint']}, 05:30:22 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 05:30:22 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 05:30:22 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 05:30:22 INFO - 'tests': ['bloom_basic_singleton']}, 05:30:22 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 05:30:22 INFO - 'tests': ['bloom_basic']}, 05:30:22 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:30:22 INFO - 'tsvgr_opacity', 05:30:22 INFO - 'tart', 05:30:22 INFO - 'tscrollx', 05:30:22 INFO - 'tsvg_static']}, 05:30:22 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 05:30:22 INFO - 'tests': ['tsvgx', 05:30:22 INFO - 'tsvgr_opacity', 05:30:22 INFO - 'tart', 05:30:22 INFO - 'tscrollx', 05:30:22 INFO - 'tsvg_static']}, 05:30:22 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 05:30:22 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 05:30:22 INFO - 'talos_options': ['--disable-stylo'], 05:30:22 INFO - 'tests': ['tp5o']}, 05:30:22 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 05:30:22 INFO - 'talos_options': ['--mitmproxy', 05:30:22 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 05:30:22 INFO - '--firstNonBlankPaint'], 05:30:22 INFO - 'tests': ['tp6_google', 05:30:22 INFO - 'tp6_youtube', 05:30:22 INFO - 'tp6_amazon', 05:30:22 INFO - 'tp6_facebook']}, 05:30:22 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 05:30:22 INFO - 'talos_options': ['--disable-stylo', 05:30:22 INFO - '--mitmproxy', 05:30:22 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 05:30:22 INFO - '--firstNonBlankPaint'], 05:30:22 INFO - 'tests': ['tp6_google', 05:30:22 INFO - 'tp6_youtube', 05:30:22 INFO - 'tp6_amazon', 05:30:22 INFO - 'tp6_facebook']}, 05:30:22 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 05:30:22 INFO - 'talos_options': ['--stylo-threads=1', 05:30:22 INFO - '--mitmproxy', 05:30:22 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 05:30:22 INFO - '--firstNonBlankPaint'], 05:30:22 INFO - 'tests': ['tp6_google', 05:30:22 INFO - 'tp6_youtube', 05:30:22 INFO - 'tp6_amazon', 05:30:22 INFO - 'tp6_facebook']}, 05:30:22 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 05:30:22 INFO - 'talos_options': ['--xperf_path', 05:30:22 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:30:22 INFO - 'tests': ['tp5n']}, 05:30:22 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 05:30:22 INFO - 'talos_options': ['--disable-stylo', 05:30:22 INFO - '--xperf_path', 05:30:22 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:30:22 INFO - 'tests': ['tp5n']}}} 05:30:22 INFO - Running post-action listener: _resource_record_post_action 05:30:22 INFO - [mozharness: 2017-09-07 12:30:22.790301Z] Finished populate-webroot step (success) 05:30:22 INFO - [mozharness: 2017-09-07 12:30:22.790390Z] Running create-virtualenv step. 05:30:22 INFO - Running pre-action listener: _resource_record_pre_action 05:30:22 INFO - Running main action method: create_virtualenv 05:30:22 INFO - Creating virtualenv /builds/slave/test/build/venv 05:30:22 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 05:30:22 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 05:30:22 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 05:30:22 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:30:22 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:30:23 INFO - Installing distribute.............................................................................................................................................................................................done. 05:30:23 INFO - Installing pip...............done. 05:30:23 INFO - Return code: 0 05:30:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 05:30:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 05:30:24 INFO - Reading from file tmpfile_stdout 05:30:24 INFO - Output received: 05:30:24 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 05:30:24 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:30: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')]} 05:30:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:30: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')]} 05:30:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:30:24 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 0x13881f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1551a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16f4a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16f8090>, '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 0x16f8d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16f2f90>, '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': '1258088b6da462a47ba2cd90000001a2-1504787377.144078-1273233852', '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 05:30:24 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 05:30:24 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 05:30:24 INFO - Using env: {'DISPLAY': ':0', 05:30:24 INFO - 'HOME': '/home/cltbld', 05:30:24 INFO - 'LANG': 'en_US.UTF-8', 05:30:24 INFO - 'LANGUAGE': 'en_US:en', 05:30:24 INFO - 'LOGNAME': 'cltbld', 05:30:24 INFO - 'MAIL': '/var/mail/cltbld', 05:30:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:30:24 INFO - 'MOZ_NO_REMOTE': '1', 05:30:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:30:24 INFO - 'NO_EM_RESTART': '1', 05:30:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:30:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:30:24 INFO - 'PWD': '/builds/slave/test', 05:30:24 INFO - 'SHELL': '/bin/bash', 05:30:24 INFO - 'SHLVL': '1', 05:30:24 INFO - 'TERM': 'linux', 05:30:24 INFO - 'TMOUT': '86400', 05:30:24 INFO - 'USER': 'cltbld', 05:30:24 INFO - 'XDG_SESSION_COOKIE': '1258088b6da462a47ba2cd90000001a2-1504787377.144078-1273233852', 05:30:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:30:24 INFO - '_': '/tools/buildbot/bin/python'} 05:30:25 INFO - Ignoring indexes: http://pypi.python.org/simple/ 05:30:25 INFO - Downloading/unpacking pip>=1.5 05:30:25 INFO - Running setup.py egg_info for package pip 05:30:25 INFO - warning: no files found matching 'pip/cacert.pem' 05:30:25 INFO - warning: no files found matching '*.html' under directory 'docs' 05:30:25 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 05:30:25 INFO - no previously-included directories found matching 'docs/_build/_sources' 05:30:25 INFO - Installing collected packages: pip 05:30:25 INFO - Found existing installation: pip 1.1 05:30:25 INFO - Uninstalling pip: 05:30:25 INFO - Successfully uninstalled pip 05:30:25 INFO - Running setup.py install for pip 05:30:25 INFO - warning: no files found matching 'pip/cacert.pem' 05:30:25 INFO - warning: no files found matching '*.html' under directory 'docs' 05:30:25 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 05:30:25 INFO - no previously-included directories found matching 'docs/_build/_sources' 05:30:25 INFO - Installing pip script to /builds/slave/test/build/venv/bin 05:30:25 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 05:30:25 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 05:30:25 INFO - Successfully installed pip 05:30:25 INFO - Cleaning up... 05:30:25 INFO - Return code: 0 05:30:25 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:30:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:30:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:30:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:30:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:30:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x13881f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1551a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16f4a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16f8090>, '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 0x16f8d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16f2f90>, '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': '1258088b6da462a47ba2cd90000001a2-1504787377.144078-1273233852', '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 05:30:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 05:30:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 05:30:25 INFO - Using env: {'DISPLAY': ':0', 05:30:25 INFO - 'HOME': '/home/cltbld', 05:30:25 INFO - 'LANG': 'en_US.UTF-8', 05:30:25 INFO - 'LANGUAGE': 'en_US:en', 05:30:25 INFO - 'LOGNAME': 'cltbld', 05:30:25 INFO - 'MAIL': '/var/mail/cltbld', 05:30:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:30:25 INFO - 'MOZ_NO_REMOTE': '1', 05:30:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:30:25 INFO - 'NO_EM_RESTART': '1', 05:30:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:30:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:30:25 INFO - 'PWD': '/builds/slave/test', 05:30:25 INFO - 'SHELL': '/bin/bash', 05:30:25 INFO - 'SHLVL': '1', 05:30:25 INFO - 'TERM': 'linux', 05:30:25 INFO - 'TMOUT': '86400', 05:30:25 INFO - 'USER': 'cltbld', 05:30:25 INFO - 'XDG_SESSION_COOKIE': '1258088b6da462a47ba2cd90000001a2-1504787377.144078-1273233852', 05:30:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:30:25 INFO - '_': '/tools/buildbot/bin/python'} 05:30:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:30:25 INFO - Downloading/unpacking psutil>=3.1.1 05:30:25 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 05:30:25 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 05:30:25 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 05:30:25 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 05:30:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:30:26 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:30:26 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:30:26 INFO - Installing collected packages: psutil 05:30:26 INFO - Running setup.py install for psutil 05:30:26 INFO - building 'psutil._psutil_linux' extension 05:30:26 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 05:30:27 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 05:30:27 INFO - building 'psutil._psutil_posix' extension 05:30:27 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 05:30:27 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 05:30:27 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:30:27 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:30:27 INFO - Successfully installed psutil 05:30:27 INFO - Cleaning up... 05:30:28 INFO - Return code: 0 05:30:28 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:30:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:30:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:30:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:30:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:30:28 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13881f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1551a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16f4a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16f8090>, '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 0x16f8d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16f2f90>, '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': '1258088b6da462a47ba2cd90000001a2-1504787377.144078-1273233852', '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 05:30:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 05:30:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 05:30:28 INFO - Using env: {'DISPLAY': ':0', 05:30:28 INFO - 'HOME': '/home/cltbld', 05:30:28 INFO - 'LANG': 'en_US.UTF-8', 05:30:28 INFO - 'LANGUAGE': 'en_US:en', 05:30:28 INFO - 'LOGNAME': 'cltbld', 05:30:28 INFO - 'MAIL': '/var/mail/cltbld', 05:30:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:30:28 INFO - 'MOZ_NO_REMOTE': '1', 05:30:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:30:28 INFO - 'NO_EM_RESTART': '1', 05:30:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:30:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:30:28 INFO - 'PWD': '/builds/slave/test', 05:30:28 INFO - 'SHELL': '/bin/bash', 05:30:28 INFO - 'SHLVL': '1', 05:30:28 INFO - 'TERM': 'linux', 05:30:28 INFO - 'TMOUT': '86400', 05:30:28 INFO - 'USER': 'cltbld', 05:30:28 INFO - 'XDG_SESSION_COOKIE': '1258088b6da462a47ba2cd90000001a2-1504787377.144078-1273233852', 05:30:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:30:28 INFO - '_': '/tools/buildbot/bin/python'} 05:30:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:30:28 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:30:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:30:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:30:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:30:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:30:29 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:30:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:30:29 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:30:29 INFO - Installing collected packages: mozsystemmonitor 05:30:29 INFO - Running setup.py install for mozsystemmonitor 05:30:29 INFO - Successfully installed mozsystemmonitor 05:30:29 INFO - Cleaning up... 05:30:29 INFO - Return code: 0 05:30:29 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:30:29 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')]} 05:30:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:30:29 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')]} 05:30:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:30:29 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 0x13881f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1551a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16f4a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16f8090>, '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 0x16f8d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16f2f90>, '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': '1258088b6da462a47ba2cd90000001a2-1504787377.144078-1273233852', '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 05:30:29 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 05:30:29 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 05:30:29 INFO - Using env: {'DISPLAY': ':0', 05:30:29 INFO - 'HOME': '/home/cltbld', 05:30:29 INFO - 'LANG': 'en_US.UTF-8', 05:30:29 INFO - 'LANGUAGE': 'en_US:en', 05:30:29 INFO - 'LOGNAME': 'cltbld', 05:30:29 INFO - 'MAIL': '/var/mail/cltbld', 05:30:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:30:29 INFO - 'MOZ_NO_REMOTE': '1', 05:30:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:30:29 INFO - 'NO_EM_RESTART': '1', 05:30:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:30:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:30:29 INFO - 'PWD': '/builds/slave/test', 05:30:29 INFO - 'SHELL': '/bin/bash', 05:30:29 INFO - 'SHLVL': '1', 05:30:29 INFO - 'TERM': 'linux', 05:30:29 INFO - 'TMOUT': '86400', 05:30:29 INFO - 'USER': 'cltbld', 05:30:29 INFO - 'XDG_SESSION_COOKIE': '1258088b6da462a47ba2cd90000001a2-1504787377.144078-1273233852', 05:30:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:30:29 INFO - '_': '/tools/buildbot/bin/python'} 05:30:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:30:29 INFO - Downloading/unpacking jsonschema==2.5.1 05:30:29 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 05:30:29 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 05:30:29 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 05:30:29 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 05:30:30 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:30:30 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:30:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:30:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:30:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:30:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:30:30 INFO - Downloading functools32-3.2.3-2.tar.gz 05:30:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:30:31 INFO - warning: no files found matching '*.txt' 05:30:31 INFO - no previously-included directories found matching 'build' 05:30:31 INFO - no previously-included directories found matching 'dist' 05:30:31 INFO - no previously-included directories found matching '.git*' 05:30:31 INFO - Installing collected packages: jsonschema, functools32 05:30:31 INFO - Running setup.py install for functools32 05:30:31 INFO - warning: no files found matching '*.txt' 05:30:31 INFO - no previously-included directories found matching 'build' 05:30:31 INFO - no previously-included directories found matching 'dist' 05:30:31 INFO - no previously-included directories found matching '.git*' 05:30:31 INFO - Successfully installed jsonschema functools32 05:30:31 INFO - Cleaning up... 05:30:31 INFO - Return code: 0 05:30:31 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:30:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:30:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:30:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:30:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:30:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13881f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1551a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16f4a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16f8090>, '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 0x16f8d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16f2f90>, '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': '1258088b6da462a47ba2cd90000001a2-1504787377.144078-1273233852', '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 05:30:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 05:30:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 05:30:31 INFO - Using env: {'DISPLAY': ':0', 05:30:31 INFO - 'HOME': '/home/cltbld', 05:30:31 INFO - 'LANG': 'en_US.UTF-8', 05:30:31 INFO - 'LANGUAGE': 'en_US:en', 05:30:31 INFO - 'LOGNAME': 'cltbld', 05:30:31 INFO - 'MAIL': '/var/mail/cltbld', 05:30:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:30:31 INFO - 'MOZ_NO_REMOTE': '1', 05:30:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:30:31 INFO - 'NO_EM_RESTART': '1', 05:30:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:30:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:30:31 INFO - 'PWD': '/builds/slave/test', 05:30:31 INFO - 'SHELL': '/bin/bash', 05:30:31 INFO - 'SHLVL': '1', 05:30:31 INFO - 'TERM': 'linux', 05:30:31 INFO - 'TMOUT': '86400', 05:30:31 INFO - 'USER': 'cltbld', 05:30:31 INFO - 'XDG_SESSION_COOKIE': '1258088b6da462a47ba2cd90000001a2-1504787377.144078-1273233852', 05:30:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:30:31 INFO - '_': '/tools/buildbot/bin/python'} 05:30:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:30:31 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:30:31 INFO - Cleaning up... 05:30:31 INFO - Return code: 0 05:30:31 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:30:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:30:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:30:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:30:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:30:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13881f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1551a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16f4a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16f8090>, '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 0x16f8d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16f2f90>, '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': '1258088b6da462a47ba2cd90000001a2-1504787377.144078-1273233852', '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 05:30:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 05:30:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 05:30:31 INFO - Using env: {'DISPLAY': ':0', 05:30:31 INFO - 'HOME': '/home/cltbld', 05:30:31 INFO - 'LANG': 'en_US.UTF-8', 05:30:31 INFO - 'LANGUAGE': 'en_US:en', 05:30:31 INFO - 'LOGNAME': 'cltbld', 05:30:31 INFO - 'MAIL': '/var/mail/cltbld', 05:30:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:30:31 INFO - 'MOZ_NO_REMOTE': '1', 05:30:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:30:31 INFO - 'NO_EM_RESTART': '1', 05:30:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:30:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:30:31 INFO - 'PWD': '/builds/slave/test', 05:30:31 INFO - 'SHELL': '/bin/bash', 05:30:31 INFO - 'SHLVL': '1', 05:30:31 INFO - 'TERM': 'linux', 05:30:31 INFO - 'TMOUT': '86400', 05:30:31 INFO - 'USER': 'cltbld', 05:30:31 INFO - 'XDG_SESSION_COOKIE': '1258088b6da462a47ba2cd90000001a2-1504787377.144078-1273233852', 05:30:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:30:31 INFO - '_': '/tools/buildbot/bin/python'} 05:30:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:30:31 INFO - Downloading/unpacking blobuploader==1.2.4 05:30:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:30:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:30:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:30:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:30:32 INFO - Downloading blobuploader-1.2.4.tar.gz 05:30:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:30:32 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:30:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:30:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:30:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:30:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:30:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:30:33 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:30:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:30:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:30:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:30:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:30:33 INFO - Downloading docopt-0.6.1.tar.gz 05:30:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:30:33 INFO - Installing collected packages: blobuploader, requests, docopt 05:30:33 INFO - Running setup.py install for blobuploader 05:30:33 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:30:33 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:30:33 INFO - Running setup.py install for requests 05:30:33 INFO - Running setup.py install for docopt 05:30:33 INFO - Successfully installed blobuploader requests docopt 05:30:33 INFO - Cleaning up... 05:30:33 INFO - Return code: 0 05:30:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:30:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:30:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:30:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:30:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:30:33 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 0x13881f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1551a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16f4a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16f8090>, '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 0x16f8d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16f2f90>, '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': '1258088b6da462a47ba2cd90000001a2-1504787377.144078-1273233852', '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 05:30:33 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 05:30:33 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 05:30:33 INFO - Using env: {'DISPLAY': ':0', 05:30:33 INFO - 'HOME': '/home/cltbld', 05:30:33 INFO - 'LANG': 'en_US.UTF-8', 05:30:33 INFO - 'LANGUAGE': 'en_US:en', 05:30:33 INFO - 'LOGNAME': 'cltbld', 05:30:33 INFO - 'MAIL': '/var/mail/cltbld', 05:30:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:30:33 INFO - 'MOZ_NO_REMOTE': '1', 05:30:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:30:33 INFO - 'NO_EM_RESTART': '1', 05:30:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:30:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:30:33 INFO - 'PWD': '/builds/slave/test', 05:30:33 INFO - 'SHELL': '/bin/bash', 05:30:33 INFO - 'SHLVL': '1', 05:30:33 INFO - 'TERM': 'linux', 05:30:33 INFO - 'TMOUT': '86400', 05:30:33 INFO - 'USER': 'cltbld', 05:30:33 INFO - 'XDG_SESSION_COOKIE': '1258088b6da462a47ba2cd90000001a2-1504787377.144078-1273233852', 05:30:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:30:33 INFO - '_': '/tools/buildbot/bin/python'} 05:30:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:30:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:30:34 INFO - Running setup.py (path:/tmp/pip-trslwi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:30:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:30:34 INFO - Running setup.py (path:/tmp/pip-3DYOUj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:30:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:30:34 INFO - Running setup.py (path:/tmp/pip-JD1sJk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:30:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:30:34 INFO - Running setup.py (path:/tmp/pip-6q5Ui6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:30:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:30:34 INFO - Running setup.py (path:/tmp/pip-Wo25gJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:30:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:30:34 INFO - Running setup.py (path:/tmp/pip-OK4cfh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:30:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:30:34 INFO - Running setup.py (path:/tmp/pip-V7NApp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:30:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:30:34 INFO - Running setup.py (path:/tmp/pip-pmiSpk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:30:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:30:34 INFO - Running setup.py (path:/tmp/pip-LbctCz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:30:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:30:34 INFO - Running setup.py (path:/tmp/pip-CvfAJE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:30:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:30:34 INFO - Running setup.py (path:/tmp/pip-cX4iSg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:30:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:30:34 INFO - Running setup.py (path:/tmp/pip-fYNEDs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:30:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:30:34 INFO - Running setup.py (path:/tmp/pip-mj5s2e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:30:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:30:35 INFO - Running setup.py (path:/tmp/pip-J4QGRH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:30:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:30:35 INFO - Running setup.py (path:/tmp/pip-7OkZoS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:30:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:30:35 INFO - Running setup.py (path:/tmp/pip-i5bNz9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:30:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:30:35 INFO - Running setup.py (path:/tmp/pip-9U9W0V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:30:35 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:30:35 INFO - Running setup.py install for manifestparser 05:30:35 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:30:35 INFO - Running setup.py install for mozcrash 05:30:35 INFO - Running setup.py install for mozdebug 05:30:35 INFO - Running setup.py install for mozdevice 05:30:35 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:30:35 INFO - Running setup.py install for mozfile 05:30:35 INFO - Running setup.py install for mozhttpd 05:30:35 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:30:35 INFO - Running setup.py install for mozinfo 05:30:36 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:30:36 INFO - Running setup.py install for mozInstall 05:30:36 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:30:36 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:30:36 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:30:36 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:30:36 INFO - Running setup.py install for mozleak 05:30:36 INFO - Running setup.py install for mozlog 05:30:36 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:30:36 INFO - Running setup.py install for moznetwork 05:30:36 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:30:36 INFO - Running setup.py install for mozprocess 05:30:36 INFO - Running setup.py install for mozprofile 05:30:36 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:30:36 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:30:36 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:30:36 INFO - Running setup.py install for mozrunner 05:30:36 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:30:36 INFO - Running setup.py install for mozscreenshot 05:30:36 INFO - Running setup.py install for moztest 05:30:36 INFO - Running setup.py install for mozversion 05:30:37 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:30:37 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:30:37 INFO - Cleaning up... 05:30:37 INFO - Return code: 0 05:30:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:30:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:30:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:30:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:30:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:30:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13881f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1551a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16f4a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16f8090>, '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 0x16f8d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16f2f90>, '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': '1258088b6da462a47ba2cd90000001a2-1504787377.144078-1273233852', '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 05:30:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:30:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:30:37 INFO - Using env: {'DISPLAY': ':0', 05:30:37 INFO - 'HOME': '/home/cltbld', 05:30:37 INFO - 'LANG': 'en_US.UTF-8', 05:30:37 INFO - 'LANGUAGE': 'en_US:en', 05:30:37 INFO - 'LOGNAME': 'cltbld', 05:30:37 INFO - 'MAIL': '/var/mail/cltbld', 05:30:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:30:37 INFO - 'MOZ_NO_REMOTE': '1', 05:30:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:30:37 INFO - 'NO_EM_RESTART': '1', 05:30:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:30:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:30:37 INFO - 'PWD': '/builds/slave/test', 05:30:37 INFO - 'SHELL': '/bin/bash', 05:30:37 INFO - 'SHLVL': '1', 05:30:37 INFO - 'TERM': 'linux', 05:30:37 INFO - 'TMOUT': '86400', 05:30:37 INFO - 'USER': 'cltbld', 05:30:37 INFO - 'XDG_SESSION_COOKIE': '1258088b6da462a47ba2cd90000001a2-1504787377.144078-1273233852', 05:30:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:30:37 INFO - '_': '/tools/buildbot/bin/python'} 05:30:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:30:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:30:37 INFO - Running setup.py (path:/tmp/pip-x__X5t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:30:37 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 05:30:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:30:37 INFO - Running setup.py (path:/tmp/pip-VR17_j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:30:37 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)) 05:30:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:30:37 INFO - Running setup.py (path:/tmp/pip-M3TYMW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:30:37 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)) 05:30:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:30:37 INFO - Running setup.py (path:/tmp/pip-HczjhQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:30:37 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)) 05:30:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:30:37 INFO - Running setup.py (path:/tmp/pip-Q6kpOa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:30:37 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)) 05:30:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:30:37 INFO - Running setup.py (path:/tmp/pip-5Nkdko-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:30:37 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)) 05:30:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:30:37 INFO - Running setup.py (path:/tmp/pip-fVClq7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:30:37 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)) 05:30:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:30:37 INFO - Running setup.py (path:/tmp/pip-rxdESq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:30:37 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)) 05:30:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:30:37 INFO - Running setup.py (path:/tmp/pip-uKNi5l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:30:37 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)) 05:30:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:30:37 INFO - Running setup.py (path:/tmp/pip-tQtGOE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:30:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.5 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:30:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:30:38 INFO - Running setup.py (path:/tmp/pip-MI8dNF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:30:38 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 05:30:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:30:38 INFO - Running setup.py (path:/tmp/pip-nMfRsM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:30:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 05:30:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:30:38 INFO - Running setup.py (path:/tmp/pip-ERA_YU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:30:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 05:30:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:30:38 INFO - Running setup.py (path:/tmp/pip-AQd8Sv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:30:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 05:30:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:30:38 INFO - Running setup.py (path:/tmp/pip-b6vFTG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:30:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 05:30:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:30:38 INFO - Running setup.py (path:/tmp/pip-ZxuFGG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:30:38 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)) 05:30:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:30:38 INFO - Running setup.py (path:/tmp/pip-9PAXHg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:30:38 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)) 05:30:38 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)) 05:30:38 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)) 05:30:38 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)) 05:30:38 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)) 05:30:38 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)) 05:30:38 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)) 05:30:38 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:30:38 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 05:30:38 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 05:30:38 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 05:30:38 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 05:30:39 INFO - Downloading blessings-1.6.tar.gz 05:30:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:30:39 INFO - Installing collected packages: blessings 05:30:39 INFO - Running setup.py install for blessings 05:30:39 INFO - Successfully installed blessings 05:30:39 INFO - Cleaning up... 05:30:39 INFO - Return code: 0 05:30:39 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:30:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:30:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:30:40 INFO - Reading from file tmpfile_stdout 05:30:40 INFO - Current package versions: 05:30:40 INFO - argparse == 1.2.1 05:30:40 INFO - blessings == 1.6 05:30:40 INFO - blobuploader == 1.2.4 05:30:40 INFO - docopt == 0.6.1 05:30:40 INFO - functools32 == 3.2.3-2 05:30:40 INFO - jsonschema == 2.5.1 05:30:40 INFO - manifestparser == 1.1 05:30:40 INFO - mozInstall == 1.13 05:30:40 INFO - mozcrash == 1.0 05:30:40 INFO - mozdebug == 0.1 05:30:40 INFO - mozdevice == 0.51 05:30:40 INFO - mozfile == 1.2 05:30:40 INFO - mozhttpd == 0.7 05:30:40 INFO - mozinfo == 0.10 05:30:40 INFO - mozleak == 0.1 05:30:40 INFO - mozlog == 3.5 05:30:40 INFO - moznetwork == 0.27 05:30:40 INFO - mozprocess == 0.25 05:30:40 INFO - mozprofile == 0.28 05:30:40 INFO - mozrunner == 6.13 05:30:40 INFO - mozscreenshot == 0.1 05:30:40 INFO - mozsystemmonitor == 0.3 05:30:40 INFO - moztest == 0.8 05:30:40 INFO - mozversion == 1.4 05:30:40 INFO - psutil == 3.1.1 05:30:40 INFO - requests == 1.2.3 05:30:40 INFO - wsgiref == 0.1.2 05:30:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:30:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:30:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:30:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:30:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:30:40 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13881f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1551a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16f4a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16f8090>, '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 0x16f8d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16f2f90>, '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': '1258088b6da462a47ba2cd90000001a2-1504787377.144078-1273233852', '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 05:30:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 05:30:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:30:40 INFO - Using env: {'DISPLAY': ':0', 05:30:40 INFO - 'HOME': '/home/cltbld', 05:30:40 INFO - 'LANG': 'en_US.UTF-8', 05:30:40 INFO - 'LANGUAGE': 'en_US:en', 05:30:40 INFO - 'LOGNAME': 'cltbld', 05:30:40 INFO - 'MAIL': '/var/mail/cltbld', 05:30:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:30:40 INFO - 'MOZ_NO_REMOTE': '1', 05:30:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:30:40 INFO - 'NO_EM_RESTART': '1', 05:30:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:30:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:30:40 INFO - 'PWD': '/builds/slave/test', 05:30:40 INFO - 'SHELL': '/bin/bash', 05:30:40 INFO - 'SHLVL': '1', 05:30:40 INFO - 'TERM': 'linux', 05:30:40 INFO - 'TMOUT': '86400', 05:30:40 INFO - 'USER': 'cltbld', 05:30:40 INFO - 'XDG_SESSION_COOKIE': '1258088b6da462a47ba2cd90000001a2-1504787377.144078-1273233852', 05:30:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:30:40 INFO - '_': '/tools/buildbot/bin/python'} 05:30:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:30:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:30:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 05:30:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 05:30:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:30:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 05:30:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 05:30:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 05:30:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 05:30:40 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 05:30:40 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 05:30:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:30:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:30:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:30:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:30:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 05:30:41 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)) 05:30:41 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)) 05:30:41 INFO - Installing collected packages: simplejson 05:30:41 INFO - Running setup.py install for simplejson 05:30:41 INFO - building 'simplejson._speedups' extension 05:30:41 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 05:30:42 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 05:30:42 INFO - Successfully installed simplejson 05:30:42 INFO - Cleaning up... 05:30:42 INFO - Return code: 0 05:30:42 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:30:42 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')]} 05:30:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:30:42 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')]} 05:30:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:30:42 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 0x13881f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1551a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16f4a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16f8090>, '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 0x16f8d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16f2f90>, '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': '1258088b6da462a47ba2cd90000001a2-1504787377.144078-1273233852', '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 05:30:42 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 05:30:42 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 05:30:42 INFO - Using env: {'DISPLAY': ':0', 05:30:42 INFO - 'HOME': '/home/cltbld', 05:30:42 INFO - 'LANG': 'en_US.UTF-8', 05:30:42 INFO - 'LANGUAGE': 'en_US:en', 05:30:42 INFO - 'LOGNAME': 'cltbld', 05:30:42 INFO - 'MAIL': '/var/mail/cltbld', 05:30:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:30:42 INFO - 'MOZ_NO_REMOTE': '1', 05:30:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:30:42 INFO - 'NO_EM_RESTART': '1', 05:30:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:30:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:30:42 INFO - 'PWD': '/builds/slave/test', 05:30:42 INFO - 'SHELL': '/bin/bash', 05:30:42 INFO - 'SHLVL': '1', 05:30:42 INFO - 'TERM': 'linux', 05:30:42 INFO - 'TMOUT': '86400', 05:30:42 INFO - 'USER': 'cltbld', 05:30:42 INFO - 'XDG_SESSION_COOKIE': '1258088b6da462a47ba2cd90000001a2-1504787377.144078-1273233852', 05:30:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:30:42 INFO - '_': '/tools/buildbot/bin/python'} 05:30:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:30:42 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:30:42 INFO - Cleaning up... 05:30:42 INFO - Return code: 0 05:30:42 INFO - Running post-action listener: _resource_record_post_action 05:30:42 INFO - Running post-action listener: _start_resource_monitoring 05:30:42 INFO - Starting resource monitoring. 05:30:42 INFO - [mozharness: 2017-09-07 12:30:42.787138Z] Finished create-virtualenv step (success) 05:30:42 INFO - [mozharness: 2017-09-07 12:30:42.788169Z] Running install step. 05:30:42 INFO - Running pre-action listener: _resource_record_pre_action 05:30:42 INFO - Running main action method: install 05:30:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:30:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:30:43 INFO - Reading from file tmpfile_stdout 05:30:43 INFO - Detecting whether we're running mozinstall >=1.0... 05:30:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:30:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:30:43 INFO - Reading from file tmpfile_stdout 05:30:43 INFO - Output received: 05:30:43 INFO - Usage: mozinstall [options] installer 05:30:43 INFO - Options: 05:30:43 INFO - -h, --help show this help message and exit 05:30:43 INFO - -d DEST, --destination=DEST 05:30:43 INFO - Directory to install application into. [default: 05:30:43 INFO - "/builds/slave/test"] 05:30:43 INFO - --app=APP Application being installed. [default: firefox] 05:30:43 INFO - mkdir: /builds/slave/test/build/application 05:30:43 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'] 05:30:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 05:30:59 INFO - Reading from file tmpfile_stdout 05:30:59 INFO - Output received: 05:30:59 INFO - /builds/slave/test/build/application/firefox/firefox 05:30:59 INFO - Running post-action listener: _resource_record_post_action 05:30:59 INFO - [mozharness: 2017-09-07 12:30:59.641217Z] Finished install step (success) 05:30:59 INFO - [mozharness: 2017-09-07 12:30:59.641361Z] Running setup-mitmproxy step. 05:30:59 INFO - Running pre-action listener: _resource_record_pre_action 05:30:59 INFO - Running main action method: setup_mitmproxy 05:30:59 INFO - Skipping: mitmproxy is not required 05:30:59 INFO - Running post-action listener: _resource_record_post_action 05:30:59 INFO - [mozharness: 2017-09-07 12:30:59.642351Z] Finished setup-mitmproxy step (success) 05:30:59 INFO - [mozharness: 2017-09-07 12:30:59.642570Z] Running run-tests step. 05:30:59 INFO - Running pre-action listener: _resource_record_pre_action 05:30:59 INFO - Running pre-action listener: _set_gcov_prefix 05:30:59 INFO - Running main action method: run_tests 05:30:59 WARNING - Try message not found. 05:30:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:30:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:30:59 INFO - Python 2.7.3 05:30:59 INFO - Return code: 0 05:30:59 INFO - grabbing minidump binary from tooltool 05:30:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:30:59 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 0x16f8090>, '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 0x16f8d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16f2f90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:30:59 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 05:30:59 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 05:30:59 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 05:30:59 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:30:59 INFO - Return code: 0 05:30:59 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 05:30:59 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:30:59 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:30:59 INFO - ENV: RUST_BACKTRACE is now full 05:30:59 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 05:30:59 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:30:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/BaAK-As6Q66QQapQlMtaJw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-079', '--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 05:30:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/BaAK-As6Q66QQapQlMtaJw/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-079 --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 05:30:59 INFO - Using env: {'DISPLAY': ':0', 05:30:59 INFO - 'HOME': '/home/cltbld', 05:30:59 INFO - 'LANG': 'en_US.UTF-8', 05:30:59 INFO - 'LANGUAGE': 'en_US:en', 05:30:59 INFO - 'LOGNAME': 'cltbld', 05:30:59 INFO - 'MAIL': '/var/mail/cltbld', 05:30:59 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:30:59 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 05:30:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:30:59 INFO - 'MOZ_NO_REMOTE': '1', 05:30:59 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:30:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:30:59 INFO - 'NO_EM_RESTART': '1', 05:30:59 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:30:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:30:59 INFO - 'PWD': '/builds/slave/test', 05:30:59 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:30:59 INFO - 'RUST_BACKTRACE': 'full', 05:30:59 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 05:30:59 INFO - 'SHELL': '/bin/bash', 05:30:59 INFO - 'SHLVL': '1', 05:30:59 INFO - 'TERM': 'linux', 05:30:59 INFO - 'TMOUT': '86400', 05:30:59 INFO - 'USER': 'cltbld', 05:30:59 INFO - 'XDG_SESSION_COOKIE': '1258088b6da462a47ba2cd90000001a2-1504787377.144078-1273233852', 05:30:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:30:59 INFO - '_': '/tools/buildbot/bin/python'} 05:30:59 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/BaAK-As6Q66QQapQlMtaJw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-079', '--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 05:31:00 INFO - mozversion application_buildid: 20170907103107 05:31:00 INFO - mozversion application_changeset: 6469a2cb7df662afb23493b5566f99b6802fcb5a 05:31:00 INFO - mozversion application_display_name: Nightly 05:31:00 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:31:00 INFO - mozversion application_name: Firefox 05:31:00 INFO - mozversion application_remotingname: firefox 05:31:00 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:31:00 INFO - mozversion application_vendor: Mozilla 05:31:00 INFO - mozversion application_version: 57.0a1 05:31:00 INFO - mozversion platform_buildid: 20170907103107 05:31:00 INFO - mozversion platform_changeset: 6469a2cb7df662afb23493b5566f99b6802fcb5a 05:31:00 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:31:00 INFO - mozversion platform_version: 57.0a1 05:31:00 INFO - using testdate: 1504787460 05:31:00 INFO - actual date: 1504787460 05:31:00 INFO - starting webserver on 'localhost:45978' 05:31:00 INFO - SUITE-START | Running 2 tests 05:31:00 INFO - TEST-START | dromaeo_css 05:31:00 INFO - Initialising browser for dromaeo_css test... 05:31:00 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:45978/getInfo.html -profile /tmp/tmp2YLNx9/profile 05:31:00 INFO - TEST-INFO | started process 3575 (/builds/slave/test/build/application/firefox/firefox http://localhost:45978/getInfo.html) 05:31:08 INFO - TEST-INFO | 3575: exit 0 05:31:08 INFO - Browser initialized. 05:31:08 INFO - Running cycle 1/1 for dromaeo_css test... 05:31:08 INFO - Using env: {'DISPLAY': ':0', 05:31:08 INFO - 'HOME': '/home/cltbld', 05:31:08 INFO - 'JSGC_DISABLE_POISONING': '1', 05:31:08 INFO - 'LANG': 'en_US.UTF-8', 05:31:08 INFO - 'LANGUAGE': 'en_US:en', 05:31:08 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 05:31:08 INFO - 'LOGNAME': 'cltbld', 05:31:08 INFO - 'MAIL': '/var/mail/cltbld', 05:31:08 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:31:08 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 05:31:08 INFO - 'MOZ_CRASHREPORTER': '1', 05:31:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:31:08 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 05:31:08 INFO - 'MOZ_NO_REMOTE': '1', 05:31:08 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:31:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:31:08 INFO - 'NO_EM_RESTART': '1', 05:31:08 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:31:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:31:08 INFO - 'PWD': '/builds/slave/test', 05:31:08 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:31:08 INFO - 'RUST_BACKTRACE': 'full', 05:31:08 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 05:31:08 INFO - 'SHELL': '/bin/bash', 05:31:08 INFO - 'SHLVL': '1', 05:31:08 INFO - 'TERM': 'linux', 05:31:08 INFO - 'TMOUT': '86400', 05:31:08 INFO - 'USER': 'cltbld', 05:31:08 INFO - 'XDG_SESSION_COOKIE': '1258088b6da462a47ba2cd90000001a2-1504787377.144078-1273233852', 05:31:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:31:08 INFO - '_': '/tools/buildbot/bin/python'} 05:31:08 INFO - TEST-INFO | started process 3774 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp2YLNx9/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 05:31:09 INFO - PID 3774 | 05:31:09 INFO - PID 3774 | (/builds/slave/test/build/application/firefox/firefox:3825): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 05:31:09 INFO - PID 3774 | 05:31:20 INFO - PID 3774 | RSS: Main: 166674432 05:31:20 INFO - PID 3774 | 05:34:18 INFO - PID 3774 | Cycle 1(1): loaded http://localhost:45978/tests/dromaeo/cssquery-dojo.html (next: http://localhost:45978/tests/dromaeo/cssquery-ext.html) 05:34:18 INFO - PID 3774 | RSS: Main: 172830720 05:34:18 INFO - PID 3774 | 05:37:20 INFO - PID 3774 | Cycle 1(1): loaded http://localhost:45978/tests/dromaeo/cssquery-ext.html (next: http://localhost:45978/tests/dromaeo/cssquery-jquery.html) 05:37:21 INFO - PID 3774 | RSS: Main: 170270720 05:37:21 INFO - PID 3774 | 05:40:22 INFO - PID 3774 | Cycle 1(1): loaded http://localhost:45978/tests/dromaeo/cssquery-jquery.html (next: http://localhost:45978/tests/dromaeo/cssquery-mootools.html) 05:40:23 INFO - PID 3774 | RSS: Main: 173326336 05:40:23 INFO - PID 3774 | 05:43:25 INFO - PID 3774 | Cycle 1(1): loaded http://localhost:45978/tests/dromaeo/cssquery-mootools.html (next: http://localhost:45978/tests/dromaeo/cssquery-prototype.html) 05:43:25 INFO - PID 3774 | RSS: Main: 169766912 05:43:25 INFO - PID 3774 | 05:46:29 INFO - PID 3774 | Cycle 1(1): loaded http://localhost:45978/tests/dromaeo/cssquery-prototype.html (next: http://localhost:45978/tests/dromaeo/cssquery-yui.html) 05:46:29 INFO - PID 3774 | RSS: Main: 172077056 05:46:29 INFO - PID 3774 | 05:49:31 INFO - PID 3774 | Cycle 1(1): loaded http://localhost:45978/tests/dromaeo/cssquery-yui.html (next: http://localhost:45978/tests/dromaeo/cssquery-dojo.html) 05:49:31 INFO - PID 3774 | RSS: Main: 174911488 05:49:31 INFO - PID 3774 | 05:49:31 INFO - PID 3774 | __start_tp_report 05:49:31 INFO - PID 3774 | _x_x_mozilla_page_load 05:49:31 INFO - PID 3774 | _x_x_mozilla_page_load_details 05:49:31 INFO - PID 3774 | |i|pagename|runs| 05:49:31 INFO - PID 3774 | |0;dojo.html;3262.4616938205213;3306.156929982854;3315.0883507035564;3316.0549243465616;3325.7173140283076;6491.058796474515;6499.1876015498065;6546.247181574115;6548.27968923418;6598.043283723863;6296.338884417139;6305.369463053692;6388.137601423807;6390.552661313698;6410.038494225857;5885.793997210109;5886.764529418818;5890.410958904118;5910.34986151523;5964.463198312151;5947.6134051286745;5959.284885813709;5965.642061476326;5966.045432730764;5967.522598192156;6020;6060.030395136779;6063.878722425548;6081.939180608181;6097.8170654880205;5893.58744887858;5990.9400905990815;5996.040633498663;5996.580239383245;6043.305019922699;5705.914411283834;5812.796552120698;5848.502877255439;5880.0003999320215;5945.821625351246;6455.451286640641;6501.414872661436;6501.544891857572;6520.250171230322;6528.249251336062;6664.666766661696;6910.619915904624;6912.447004239649;7000.789976300719;7037.296270372973;6858.3827455529245;6872.312768723138;6875.48433867462;6879.89680154798;6884.380405763505;6397.944339183982;6399.648019358933;6401.263854656728;6403.775867844603;6414.839629009265;6995.545289556264;7067.257937916547;7078.681459334342;7078.893816592756;7090.858182836314;6753.1220941277315;6756.695948682321;6761.0872532208505;6761.898571521431;6768.153980752406;6828.5220034597105;6843.110395648534;6863.210730766026;6926.2034865990045;7021.262767409451;6884.242733299433;6901.896471552931;6925.445964322943;7012.474064445085;7015.614141222281;6741;6817.897731534031;6866.896996545056;6936.618485983319;7035.964820175969;5516.255305533701;5519.862003449946;5530.170474428868;5530.474604912527;5530.861728456821;4725.149473094876;4783.258594917794;4791.6406269529925;4810.518948105161;4831.806727730922;5931.792387266425;5992.850178745566;5999.160117583542;6018.849528761816;6137.60105593135;6913.827154321081;6927.203371612329;6932.480063995221;6939.340762627643;6939.548929319578;5978.5814992950945;5983.760649573968;5985.221921150222;5986.5809393342915;5991.041433370727;5897.5871688982215;5960.612560183574;5972.492338151219;5973.014452615356;5976.073708575178;5567.498925096761;6669.966650166815;6749.460043196533;6836.418904393182;6838.281980392087;6986.196587392312;7025.086738723933;7062.364387205073;7066.681999309941;7128.037714908421;6564.573302735307;6652.968789837583;6656.7004484797335;6663.533552651268;6698.129243198353;6732.158480189976;6797.28628494001;6838.452923766186;6920.238773734784;6926.896096558455;7015.579065256101;7037.225905150532;7039.54242974205;7039.824004399931;7094.290570942864;6666.866662666816;6752.527323087534;6828.214755303204;6845.863082738218;6856.6228857413325;6687.164104486939;6716.1604799400075;6738.8652226956165;6842.178938527409;6843.62360070181;6511.609303441809;6601.240857301472;6614.206295244601;6656.500762442739;6774.051632771318;6695.497837662292;6718.899216511854;6747.628880411625;6775.525713200029;6888.896666550106;6430.903536447051;6543.051257567721;6564.80305590833;6668.099806526056;6669.6331701754925 05:49:31 INFO - PID 3774 | |1;ext.html;719.568259044573;733.4059411876381;774.4965772248034;774.7443343696578;786.7993661616289;40700.796496017516;41124.17751644965;41136.58863411369;41421.79289103558;41506.7924660377;38281.23437531248;38461.80769096158;38493.61506384936;38504;38550.61449385505;6869.347411995865;6973.198082220559;6977.3022697730075;6997.930020699804;7000.439964802804;6837.076994605739;6874.553154044971;6907.205671347784;6925.619090949996;6942.409895158918;6874.931250687504;6912.930870691304;6915.066466027072;6923.342282483181;6925.757598484054;6827;6837.076994605714;6838.760643377483;6863.382295593396;6887.104676392087;6233.376662333776;6269.655168965705;6288;6290.748370065192;6297.1813664223355;33747.32505349904;34092.31815363704;34252.828735856165;34258.14354641129;34350.312993740234;3369.494575813623;3379.205886616643;3388.034410193083;3388.8305584720665;3391.3895498810202;27921.301967450978;27968.160955171377;28119.156425307272;28145;28167.718322816716;4387.495438024636;4453.933191002137;4468.955310446919;4483.663725220622;4489.483709373399;217150;222530;224286;226065.86967065223;226301;47635.761821191365;49910.75044624753;49942.75028624834;50112.74943625258;50497;50582.24126638103;50596;50670.74664626653;50674.23988640173;50683.74658126686;14748.557543273719;14762.70474590502;14781.113133212042;14915.552533424014;14971.251437428304;11795.174337796272;11802.40987950599;11817.822732659017;11940.641780746591;12028.939855300843;14043.08719933201;14054;14085.15489070659;14154.433822646979;14306;5727.942720572824;5825.7087145642545;5832.329282132524;5880.9414305425435;5886.264216972879;1010.8079464901646;1017.7353887989028;1027.6454623155;1031.2111234905303;1033.194108595297;22858.199963001214;23486.412839679146;28852;29371.706282937324;29779.851100744792;1092.322759888874;1102.80149573076;1105.3865104866877;1112.8720197177267;1114.3202646385698;12615.558455454198;12738.490460381481;12741;12768;12771.808422873664;239250;241808;242088;242274;243129;52183.217251741255;52637.73681131646;52789.736051319494;56072;56140.71929640326;1385.9792103118461;1436.590571687064;1438.4102517967374;1447.2474313357206;1454.0330680097827;27783.58324625173;28052.85973570119;28278.43443131167;28340;28469.288267793472;38392.80803595964;38696.41955370728;38711.6128838708;38729.41905871358;38755;206954;206976;207818.8436231297;207890;208009;34513.827430862686;37293;38636.420453692655;38773;38789.806050969564;46547.30179047249;46649.53350466588;46891.531084688715;47411;47460;29461;29781.851090745273;29917.55123673103;29922;30029.399412012073;34092.829535852165;34107.31785364328;34184.316313674084;34515.48226776651;34578.48132278068;38355.424668629435;38536;38770.806145970215;38820.80589597034;39051.41422878716;33049.17377065593;33097.338053238316;33107;33191.83404082964;33887;24432.755672443047;24556.50886982286;24874;25428;25668.486630267664 05:49:31 INFO - PID 3774 | |2;jquery.html;560.1345920552745;572.2560671127533;577.9277590301213;581.604508933925;583.3029529711995;22264.777352226472;22480.775192248093;23131.421714457127;23138.190163344287;23290.65064024037;22696.54606907861;24448.75551244487;24449.144279950204;24500.75499245007;24506.75493245067;6638.7344506219815;6772.69522871472;6774.661266936658;6781.423578995791;6781.423578995791;6365.331640177784;6650.833729156786;6686.1308029956035;6748.561343512681;6754.358335958076;6633.502487313447;6639.170103737033;6639.502037347194;6640.468762499013;6640.667966601651;6632.137822083147;6639.302873198293;6642.269350371455;6647.700853461582;6656.667166641672;6157.568970172045;6166.7533298667995;6167.568270221086;6170.167027451326;6181.938180618226;28692.569611455845;29104.708952910412;29514.85242573795;29901.401971960655;29928;2060.5672808710187;2106.031225636209;2108.3569511299047;2109.8839563823985;2110.472381904524;19498.70751938722;19973.50066248341;20054.899725501426;20088.99555022243;20095.397138085882;2348.788609025196;2361.9527609447887;2400.59189937711;2405.350555350063;2474.7772700457044;128645;134165;134376.32811835976;134504.32747836297;134937.3253133738;14258.786118208236;15021.324040418207;15040;15143.469978550698;15179.165145916859;22884.65673014906;22970.19604313841;23606.409839754142;23642.290731278088;23692.05231790709;9079.137481939206;9388.342816002814;9439.433633981984;9478.526073696288;9590.136887680002;8397.538135402612;8418.915810841812;8433.95783021081;8549.187827156411;8573.871391929126;8967.641294348283;8987.640494380152;9231.676891308905;9233.353665243503;9358.57886395114;2706.7157948415525;2750.22993561803;2778.819376740546;2779.958300625492;2864.1550742531003;969.9757506062405;989.8960609136082;993.3394292795355;1006.6376104602338;1014.878214614251;19806.16933055246;19858.404247872586;20133;20447.28434504785;24244.393890152536;1019.928604997643;1041.4584416103585;1048.3238311289176;1053.0364716284514;1057.5558265528496;11274.774504510027;11399.373034483016;11424.143189260838;11472.54109835614;11521.827172592419;501841;507151;510010;510934;517934;123492;124083;126729;127701;129076;1200.789861774186;1211.8303437518755;1244.6577191272472;1244.887960083597;1249.6376050945305;20426.67226630264;20705.27531536389;20967.21778648832;21049.263275785273;21181.6822747662;51361;51960;57387;57688;61739.38260617336;69827;70579.29420705933;70597;70948;71159;14009.08940918837;14469.348879300247;14541.85458145405;14575.416983320973;14597.708045839236;20519.281825136048;20580;20628.17487300491;20663.656862303902;20674.17303307911;16608;16805.327786888876;17030.659386812444;17043.488695339158;17046.744298835776;16332.183390830649;16743;17604.91197544004;18048.187831547348;18253.361132360304;18083;18127.546811329823;18166.273349066418;18247.635047299245;18346.724799127758;14736.77894831557;14866.331015104563;14945.925270374013;14946.925265373604;14980.475683351031;16967.91516042412;17035.063071531185;17125.14374281256;17130.31478740886;17143.228554714817 05:49:31 INFO - PID 3774 | |3;mootools.html;1292.4313302147054;1324;1358.7825947848344;1359.5445525748873;1373.0868972133533;12882;15030.398784048639;15670.84329156708;15698.293576789043;15707.842921570795;13092.607221783337;13196.53812116576;15152.924235378807;15752.054876707405;15798.684026319495;2723.087765598527;2779.4441111777624;2784.958225626617;2786.192004318755;2787.3031742064486;2620.908268210613;2671.7061123276426;2736.0287098080094;2746.1075150576044;2793.1201671473523;2761.0888406825798;2806.985965070172;2812.535931571288;2813.08574713218;2815.1695249901327;2578.084779903145;2608.8434693918234;2620.2139358192467;2624.8556329401877;2647.311698958275;2694.2321438389963;2698.5007773561956;2708.9864550677316;2714.945701085987;2720.170348043846;14861.256937153206;15171.468998584996;15185.696286074328;15199.468018619405;15203.163825989566;7599.848003039963;7906.920930790733;7907.2488113629115;7912.208779122099;7913.366930645535;4920.228717661639;5091.9236211456855;5096.923546146811;5319.494648008433;5495.120780675113;1882.9152688129068;1910.4077735901915;1916.0802814649046;1916.377177417331;1955.735975643284;26293.21120366392;26552.73447265522;26622.33444163892;26627.06805261826;26736.73263267362;6628;6660;6868.416184624363;6911.101556797584;6914.377706006484;16893.746593801105;17479.825201748074;17526.036068016125;17539.210735516932;17542.29830806779;7483.738069167553;7648.617569121521;7650.923490765133;7653.540787552764;7677.808054798675;6660.96669516659;6920.653967301615;6937.236903940562;7105.182903966007;7110.004599356094;5190.92213616796;5346.465353464622;5351.224072509464;5383.703896285742;5388.8113916013535;1524.7268530776703;1526.1758650328698;1539.09962671836;1562.8905976581757;1568.9607759805867;893.3746377535751;922.856957171626;926.3515539122641;931.3015238571072;937.4422218779815;7717.382609391235;7923.801904952422;7939.722109726132;7947.920520794718;7995.080565734898;703.8733028055;717.3579646216607;721.105828772329;741.5439504704676;752.6913965274211;5035.345405097387;5363.919541206885;5595.496405323459;5641.323041235078;5855.736491857877;21325.89337053305;22208.777912220994;22274.220402285842;22535.323940281818;22809.543809124058;21747;21836.89081554614;22200.555988880133;22239.22162724297;22310.442238944157;10820.621278255223;10853.728656783644;11161.665150045512;11189.272697274813;11206.215564910379;8298.958505207434;8371.95814020926;8656.956715216384;8722.476651400937;8744.387892847439;18935.863848169152;19123.617527649647;19168.233270669018;19177.904110479358;19259.807401925802;19002.714959275345;19154.138063787443;19543.511412214808;19632.60734785325;19739.506512337306;11507.367094809866;11841.82237266459;11844.70388240301;11862.40687965581;11868.76262474763;14213.360398781873;14757.92621036888;14785.630359241106;14808.555743327717;14869.405223790927;10521.526531306263;10604.310719803188;10623.681289561016;10653.733656658645;10817.459127043774;10983.286086404358;11094.11247100214;11124.388158651029;11196.832047519456;11324.263922844988;9032.45805251687;9136.360454768102;9315.953420232856;9337.159655631025;9344.95327523358;6695.899561506484;6825.044493770975;6835.521513494008;6882.793516194522;6887.862242755217;7025.367716905503;7115.679794409368;7148.749793757193;7161.749338773327;7188.856222875618 05:49:31 INFO - PID 3774 | |4;prototype.html;499.8050760203519;500.2071716329617;533.2587703092704;534.284059360457;537.9435159308273;6536.379043990819;6545.247296560897;6566.606003639779;6609.933900660992;6616.470682345413;6263.874722505547;6410.551261411703;6424.903626445595;6459.90310145347;6474.320196379383;5323.680579165243;5388.164834450667;5397.46025397461;5442.183672449125;5443.237946687447;5249.897521520466;5322.387925388591;5406.486383793534;5408.567314614841;5408.91886621701;5348;5356.839294821161;5378.623496355257;5385.892282154355;5400.29796126503;5318.590573498026;5349.839504814861;5367.731613419313;5372.650777699437;5385.730713464331;4958.355413796184;4979.377577802775;5009.323741294951;5013.423456302536;5018.222175562793;6406.519511036644;6417.743290268384;6442.774502892376;6444.645544495051;6468.935310646881;6091.11678806575;6196.721147548371;6268.122462855204;6268.843278918018;6279.905801412982;6804.897926531106;6819.215790184093;6833.726650933957;6846.726130954756;6909.861802763917;5404.594655400861;5407.675539467606;5421.566274698014;5454.372747134051;5487.64330318528;7222.313880181375;7224.819379515449;7304.853902921912;7312.159101703319;7333.726544771138;5592.692401917852;5612.7754889803955;5651.632643878134;5657.32112146545;5681.829545113653;5742.48317651405;5762;5766.654000759968;5776.104703770922;5790.189373487715;6103.908441373383;6141.508679305645;6203.6587987660205;6223.719932603044;6246.250449946036;6750.763723269662;6762.357576030269;6823.658817059128;6858.759943401957;6883.827904302332;1845.2711179084185;1905.8284754372173;1907.6184763047168;1950.4538729155859;1968.1143485431612;1426.2013272567397;1440.7694768837146;1444.2562080528537;1459.5110637936198;1488.1740633948045;5833.329167145747;5842.5618078644275;5867.119932010232;5878.177055212188;5907.852303692356;6633.170853643294;6763.7632682855865;6880.69266839299;6933.653317334113;7001.194862590765;5864.941350586525;5917.751254523339;5933.81323735246;5956.493698035629;5989.191459153046;3642.3443780119833;3650.5254316938626;3658.5609726832417;3660.743747937619;3684.705223582107;6670.7665231716655;6692.698828552727;6706.195256569243;6711.194656641137;6748.662566871637;7097.964510177416;7119.3592576667315;7131.465140114512;7180.527991761676;7208.459365547605;6657.500687448364;6684.532082754258;6762.120924279812;6776.966115169491;6811.4210292124735;6956.443484521327;7013.964930175316;7019.5788252705;7031.101602567381;7032.191298000591;6865.725370985105;6884.896726549206;6888.931110688829;6895.724171033103;6933.5493192942295;6663.26704062543;6694.83262918431;6734.898976515454;6765.289644587444;6766.15423072116;5080.669756465818;5090.134677104827;5102.515261050195;5138.332016837937;5141.460146684544;6584.242812076673;6788.93211067883;6910.136232970879;6959.826004349932;6996.300369962963;6422.710978005907;6510.479161666961;6565.961933411013;6627.436667883284;6638.468922486093;6528;6544.640044797391;6552.63960482159;6635.303293154342;6648.800535983928;6451.161349024596;6500.317466666121;6511.218653761579;6523.195026138806;6658.367455091952;6605.735770569124;6620.735170593122;6639.6680165992475;6695.627396383632;6734.124563806869;6419.807405777834;6444.742210311722;6522.706478208587;6552.443042341454;6563.245226798788 05:49:31 INFO - PID 3774 | |5;yui.html;891.5631340643087;902.801383695587;910.5356268303163;919.1406035356941;924.1267002682465;16291.592710182236;16646.417375391848;16666.833331666694;16670.749938750927;21517;21021;21485.785142148572;21541.67687484689;21561;21584.676229856526;957.4111921168466;975.7365511311978;977.7164622259536;981.9165370943479;982.4989255479685;991.6182269826097;994.6121012804975;995.044757033249;998.7453199434134;1000.2348203624233;985.2069084387066;1013.8225810483173;1023.6468418395679;1041.1566631028852;1059.1209296284105;1015.7359086637454;1017.9898201018007;1019.734868934075;1021.0096206679583;1022.4632068164274;746.1382103670215;746.391690772019;746.895434639151;748.3078152708777;751.5378042503821;17951.281948722037;18020.819791802045;18046.548836279067;18055;19903.203871845108;21016.579668406546;21062.578748424945;21296.680549791767;21610.56778864414;21665;15563.688726225411;15834.841651583452;15891.602709932344;15977.360905563764;15986.76019859703;3630.909227269313;3665.2852693724735;3677.099110717891;3690.5940346561856;3691.3724666806706;122320;124114.37942810319;124284;125741;127632.36183819114;15685.137317447421;15717.921410392875;16126.516204513884;16246.75629865553;16255;29701.554476682868;30218.395632087235;30611.540826887616;30615.387692246477;30671.079867604007;7649.617519124022;7989.640466179036;8051.355891528664;8083.514989100673;8467.237948584658;4235.4917409911;4272.551382104896;4343.522212556616;4344.9354908047735;4349.173657005159;5104.05574968632;5216.26972223889;5220.765065572058;5242.1088414969545;5247.580193584504;1072.2654981337653;1080.4921686807188;1091.1598069486452;1092.5029111754134;1099.5546803544416;675.7263308360122;692.3007762160154;701.3792793377897;707.0243064570859;713.3223437734069;11351.205415620992;11421.200515963968;11896.405179740977;12271.202371845802;12346.876531234753;700.3206889317354;706.8268274272733;711.5837235217456;712.1881055596624;727.1492353945896;5353.116735738559;5493.395726470085;6109.327973922865;6195.380461953859;6517.478601711947;111160.44419777849;112389;112838.43580782044;113462;115869;94938;95590.52204738931;96056;99400.50299748455;99456.50271748594;86100.56949715212;87971.56014219888;88517;91107.54446227726;92507.53746231226;11977.401129943293;12410.81383779226;12624.242545447303;12760.042996775093;13000.479980800663;39238.607613923494;39595.2080958385;39833;41563.584364157185;41969.580304197785;50821.745891271785;51655.48344516507;52294.73852630865;53229.201561977374;53868.46131538791;17495.737563936807;18166.90916545409;18851.057447127314;18887;19092.904535477235;20556.383308500768;20604.484887877923;20765.58468830645;20939.16243350249;21323.893380532998;18323.633527329646;18538.81461185425;18543.35098271608;18976.715349269496;19237.519062023563;17738.822611773718;18229.361972330906;19052.714209286594;19120.235190592775;19244.518887027938;16124;16234.107124108288;17649.117544123;18531.536711582317;18645.347412840485;13150.934245328712;13751.724965500434;13845.653858653613;14010.859891401364;14255.358508867334;14556.563303100924;14839.629009274855;14984.47554335593;15133.54599362021;15175 05:49:31 INFO - PID 3774 | __end_tp_report 05:49:31 INFO - PID 3774 | __start_cc_report 05:49:31 INFO - PID 3774 | _x_x_mozilla_cycle_collect,373 05:49:31 INFO - PID 3774 | __end_cc_report 05:49:31 INFO - PID 3774 | __startTimestamp1504788571907__endTimestamp 05:49:31 INFO - PID 3774 | 05:49:31 INFO - PID 3774 | ------- Summary: start ------- 05:49:31 INFO - PID 3774 | Number of tests: 6 05:49:31 INFO - PID 3774 | 05:49:31 INFO - PID 3774 | [#0] dojo.html Cycles:165 Average:6364.77 Median:6614.21 stddev:751.94 (11.4%) stddev-sans-first:713.77 05:49:31 INFO - PID 3774 | Values: 3262.5 3306.2 3315.1 3316.1 3325.7 6491.1 6499.2 6546.2 6548.3 6598.0 6296.3 6305.4 6388.1 6390.6 6410.0 5885.8 5886.8 5890.4 5910.3 5964.5 5947.6 5959.3 5965.6 5966.0 5967.5 6020.0 6060.0 6063.9 6081.9 6097.8 5893.6 5990.9 5996.0 5996.6 6043.3 5705.9 5812.8 5848.5 5880.0 5945.8 6455.5 6501.4 6501.5 6520.3 6528.2 6664.7 6910.6 6912.4 7000.8 7037.3 6858.4 6872.3 6875.5 6879.9 6884.4 6397.9 6399.6 6401.3 6403.8 6414.8 6995.5 7067.3 7078.7 7078.9 7090.9 6753.1 6756.7 6761.1 6761.9 6768.2 6828.5 6843.1 6863.2 6926.2 7021.3 6884.2 6901.9 6925.4 7012.5 7015.6 6741.0 6817.9 6866.9 6936.6 7036.0 5516.3 5519.9 5530.2 5530.5 5530.9 4725.1 4783.3 4791.6 4810.5 4831.8 5931.8 5992.9 5999.2 6018.8 6137.6 6913.8 6927.2 6932.5 6939.3 6939.5 5978.6 5983.8 5985.2 5986.6 5991.0 5897.6 5960.6 5972.5 5973.0 5976.1 5567.5 6670.0 6749.5 6836.4 6838.3 6986.2 7025.1 7062.4 7066.7 7128.0 6564.6 6653.0 6656.7 6663.5 6698.1 6732.2 6797.3 6838.5 6920.2 6926.9 7015.6 7037.2 7039.5 7039.8 7094.3 6666.9 6752.5 6828.2 6845.9 6856.6 6687.2 6716.2 6738.9 6842.2 6843.6 6511.6 6601.2 6614.2 6656.5 6774.1 6695.5 6718.9 6747.6 6775.5 6888.9 6430.9 6543.1 6564.8 6668.1 6669.6 05:49:31 INFO - PID 3774 | 05:49:31 INFO - PID 3774 | [#1] ext.html Cycles:180 Average:39261.59 Median:34255.49 stddev:58351.37 (170.3%) stddev-sans-first:58443.30 05:49:31 INFO - PID 3774 | Values: 719.6 733.4 774.5 774.7 786.8 40700.8 41124.2 41136.6 41421.8 41506.8 38281.2 38461.8 38493.6 38504.0 38550.6 6869.3 6973.2 6977.3 6997.9 7000.4 6837.1 6874.6 6907.2 6925.6 6942.4 6874.9 6912.9 6915.1 6923.3 6925.8 6827.0 6837.1 6838.8 6863.4 6887.1 6233.4 6269.7 6288.0 6290.7 6297.2 33747.3 34092.3 34252.8 34258.1 34350.3 3369.5 3379.2 3388.0 3388.8 3391.4 27921.3 27968.2 28119.2 28145.0 28167.7 4387.5 4453.9 4469.0 4483.7 4489.5 217150.0 222530.0 224286.0 226065.9 226301.0 47635.8 49910.8 49942.8 50112.7 50497.0 50582.2 50596.0 50670.7 50674.2 50683.7 14748.6 14762.7 14781.1 14915.6 14971.3 11795.2 11802.4 11817.8 11940.6 12028.9 14043.1 14054.0 14085.2 14154.4 14306.0 5727.9 5825.7 5832.3 5880.9 5886.3 1010.8 1017.7 1027.6 1031.2 1033.2 22858.2 23486.4 28852.0 29371.7 29779.9 1092.3 1102.8 1105.4 1112.9 1114.3 12615.6 12738.5 12741.0 12768.0 12771.8 239250.0 241808.0 242088.0 242274.0 243129.0 52183.2 52637.7 52789.7 56072.0 56140.7 1386.0 1436.6 1438.4 1447.2 1454.0 27783.6 28052.9 28278.4 28340.0 28469.3 38392.8 38696.4 38711.6 38729.4 38755.0 206954.0 206976.0 207818.8 207890.0 208009.0 34513.8 37293.0 38636.4 38773.0 38789.8 46547.3 46649.5 46891.5 47411.0 47460.0 29461.0 29781.9 29917.6 29922.0 30029.4 34092.8 34107.3 34184.3 34515.5 34578.5 38355.4 38536.0 38770.8 38820.8 39051.4 33049.2 33097.3 33107.0 33191.8 33887.0 24432.8 24556.5 24874.0 25428.0 25668.5 05:49:31 INFO - PID 3774 | 05:49:31 INFO - PID 3774 | [#2] jquery.html Cycles:180 Average:35391.59 Median:23050.81 stddev:85735.58 (371.9%) stddev-sans-first:85936.21 05:49:31 INFO - PID 3774 | Values: 560.1 572.3 577.9 581.6 583.3 22264.8 22480.8 23131.4 23138.2 23290.7 22696.5 24448.8 24449.1 24500.8 24506.8 6638.7 6772.7 6774.7 6781.4 6781.4 6365.3 6650.8 6686.1 6748.6 6754.4 6633.5 6639.2 6639.5 6640.5 6640.7 6632.1 6639.3 6642.3 6647.7 6656.7 6157.6 6166.8 6167.6 6170.2 6181.9 28692.6 29104.7 29514.9 29901.4 29928.0 2060.6 2106.0 2108.4 2109.9 2110.5 19498.7 19973.5 20054.9 20089.0 20095.4 2348.8 2362.0 2400.6 2405.4 2474.8 128645.0 134165.0 134376.3 134504.3 134937.3 14258.8 15021.3 15040.0 15143.5 15179.2 22884.7 22970.2 23606.4 23642.3 23692.1 9079.1 9388.3 9439.4 9478.5 9590.1 8397.5 8418.9 8434.0 8549.2 8573.9 8967.6 8987.6 9231.7 9233.4 9358.6 2706.7 2750.2 2778.8 2780.0 2864.2 970.0 989.9 993.3 1006.6 1014.9 19806.2 19858.4 20133.0 20447.3 24244.4 1019.9 1041.5 1048.3 1053.0 1057.6 11274.8 11399.4 11424.1 11472.5 11521.8 501841.0 507151.0 510010.0 510934.0 517934.0 123492.0 124083.0 126729.0 127701.0 129076.0 1200.8 1211.8 1244.7 1244.9 1249.6 20426.7 20705.3 20967.2 21049.3 21181.7 51361.0 51960.0 57387.0 57688.0 61739.4 69827.0 70579.3 70597.0 70948.0 71159.0 14009.1 14469.3 14541.9 14575.4 14597.7 20519.3 20580.0 20628.2 20663.7 20674.2 16608.0 16805.3 17030.7 17043.5 17046.7 16332.2 16743.0 17604.9 18048.2 18253.4 18083.0 18127.5 18166.3 18247.6 18346.7 14736.8 14866.3 14945.9 14946.9 14980.5 16967.9 17035.1 17125.1 17130.3 17143.2 05:49:31 INFO - PID 3774 | 05:49:31 INFO - PID 3774 | [#3] mootools.html Cycles:180 Average:9327.17 Median:14704.22 stddev:6787.96 (46.2%) stddev-sans-first:6780.16 05:49:31 INFO - PID 3774 | Values: 1292.4 1324.0 1358.8 1359.5 1373.1 12882.0 15030.4 15670.8 15698.3 15707.8 13092.6 13196.5 15152.9 15752.1 15798.7 2723.1 2779.4 2785.0 2786.2 2787.3 2620.9 2671.7 2736.0 2746.1 2793.1 2761.1 2807.0 2812.5 2813.1 2815.2 2578.1 2608.8 2620.2 2624.9 2647.3 2694.2 2698.5 2709.0 2714.9 2720.2 14861.3 15171.5 15185.7 15199.5 15203.2 7599.8 7906.9 7907.2 7912.2 7913.4 4920.2 5091.9 5096.9 5319.5 5495.1 1882.9 1910.4 1916.1 1916.4 1955.7 26293.2 26552.7 26622.3 26627.1 26736.7 6628.0 6660.0 6868.4 6911.1 6914.4 16893.7 17479.8 17526.0 17539.2 17542.3 7483.7 7648.6 7650.9 7653.5 7677.8 6661.0 6920.7 6937.2 7105.2 7110.0 5190.9 5346.5 5351.2 5383.7 5388.8 1524.7 1526.2 1539.1 1562.9 1569.0 893.4 922.9 926.4 931.3 937.4 7717.4 7923.8 7939.7 7947.9 7995.1 703.9 717.4 721.1 741.5 752.7 5035.3 5363.9 5595.5 5641.3 5855.7 21325.9 22208.8 22274.2 22535.3 22809.5 21747.0 21836.9 22200.6 22239.2 22310.4 10820.6 10853.7 11161.7 11189.3 11206.2 8299.0 8372.0 8657.0 8722.5 8744.4 18935.9 19123.6 19168.2 19177.9 19259.8 19002.7 19154.1 19543.5 19632.6 19739.5 11507.4 11841.8 11844.7 11862.4 11868.8 14213.4 14757.9 14785.6 14808.6 14869.4 10521.5 10604.3 10623.7 10653.7 10817.5 10983.3 11094.1 11124.4 11196.8 11324.3 9032.5 9136.4 9316.0 9337.2 9345.0 6695.9 6825.0 6835.5 6882.8 6887.9 7025.4 7115.7 7148.7 7161.7 7188.9 05:49:31 INFO - PID 3774 | 05:49:31 INFO - PID 3774 | [#4] prototype.html Cycles:180 Average:5785.59 Median:6423.81 stddev:1556.59 (24.2%) stddev-sans-first:1509.55 05:49:31 INFO - PID 3774 | Values: 499.8 500.2 533.3 534.3 537.9 6536.4 6545.2 6566.6 6609.9 6616.5 6263.9 6410.6 6424.9 6459.9 6474.3 5323.7 5388.2 5397.5 5442.2 5443.2 5249.9 5322.4 5406.5 5408.6 5408.9 5348.0 5356.8 5378.6 5385.9 5400.3 5318.6 5349.8 5367.7 5372.7 5385.7 4958.4 4979.4 5009.3 5013.4 5018.2 6406.5 6417.7 6442.8 6444.6 6468.9 6091.1 6196.7 6268.1 6268.8 6279.9 6804.9 6819.2 6833.7 6846.7 6909.9 5404.6 5407.7 5421.6 5454.4 5487.6 7222.3 7224.8 7304.9 7312.2 7333.7 5592.7 5612.8 5651.6 5657.3 5681.8 5742.5 5762.0 5766.7 5776.1 5790.2 6103.9 6141.5 6203.7 6223.7 6246.3 6750.8 6762.4 6823.7 6858.8 6883.8 1845.3 1905.8 1907.6 1950.5 1968.1 1426.2 1440.8 1444.3 1459.5 1488.2 5833.3 5842.6 5867.1 5878.2 5907.9 6633.2 6763.8 6880.7 6933.7 7001.2 5864.9 5917.8 5933.8 5956.5 5989.2 3642.3 3650.5 3658.6 3660.7 3684.7 6670.8 6692.7 6706.2 6711.2 6748.7 7098.0 7119.4 7131.5 7180.5 7208.5 6657.5 6684.5 6762.1 6777.0 6811.4 6956.4 7014.0 7019.6 7031.1 7032.2 6865.7 6884.9 6888.9 6895.7 6933.5 6663.3 6694.8 6734.9 6765.3 6766.2 5080.7 5090.1 5102.5 5138.3 5141.5 6584.2 6788.9 6910.1 6959.8 6996.3 6422.7 6510.5 6566.0 6627.4 6638.5 6528.0 6544.6 6552.6 6635.3 6648.8 6451.2 6500.3 6511.2 6523.2 6658.4 6605.7 6620.7 6639.7 6695.6 6734.1 6419.8 6444.7 6522.7 6552.4 6563.2 05:49:31 INFO - PID 3774 | 05:49:31 INFO - PID 3774 | [#5] yui.html Cycles:180 Average:23353.16 Median:21179.63 stddev:31794.61 (150.1%) stddev-sans-first:31839.07 05:49:31 INFO - PID 3774 | Values: 891.6 902.8 910.5 919.1 924.1 16291.6 16646.4 16666.8 16670.7 21517.0 21021.0 21485.8 21541.7 21561.0 21584.7 957.4 975.7 977.7 981.9 982.5 991.6 994.6 995.0 998.7 1000.2 985.2 1013.8 1023.6 1041.2 1059.1 1015.7 1018.0 1019.7 1021.0 1022.5 746.1 746.4 746.9 748.3 751.5 17951.3 18020.8 18046.5 18055.0 19903.2 21016.6 21062.6 21296.7 21610.6 21665.0 15563.7 15834.8 15891.6 15977.4 15986.8 3630.9 3665.3 3677.1 3690.6 3691.4 122320.0 124114.4 124284.0 125741.0 127632.4 15685.1 15717.9 16126.5 16246.8 16255.0 29701.6 30218.4 30611.5 30615.4 30671.1 7649.6 7989.6 8051.4 8083.5 8467.2 4235.5 4272.6 4343.5 4344.9 4349.2 5104.1 5216.3 5220.8 5242.1 5247.6 1072.3 1080.5 1091.2 1092.5 1099.6 675.7 692.3 701.4 707.0 713.3 11351.2 11421.2 11896.4 12271.2 12346.9 700.3 706.8 711.6 712.2 727.1 5353.1 5493.4 6109.3 6195.4 6517.5 111160.4 112389.0 112838.4 113462.0 115869.0 94938.0 95590.5 96056.0 99400.5 99456.5 86100.6 87971.6 88517.0 91107.5 92507.5 11977.4 12410.8 12624.2 12760.0 13000.5 39238.6 39595.2 39833.0 41563.6 41969.6 50821.7 51655.5 52294.7 53229.2 53868.5 17495.7 18166.9 18851.1 18887.0 19092.9 20556.4 20604.5 20765.6 20939.2 21323.9 18323.6 18538.8 18543.4 18976.7 19237.5 17738.8 18229.4 19052.7 19120.2 19244.5 16124.0 16234.1 17649.1 18531.5 18645.3 13150.9 13751.7 13845.7 14010.9 14255.4 14556.6 14839.6 14984.5 15133.5 15175.0 05:49:31 INFO - PID 3774 | -------- Summary: end -------- 05:49:31 INFO - PID 3774 | 05:49:32 INFO - TEST-INFO | 3774: exit 0 05:49:32 INFO - TEST-OK | dromaeo_css | took 1112535ms 05:49:32 INFO - TEST-START | kraken 05:49:32 INFO - Initialising browser for kraken test... 05:49:32 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:45978/getInfo.html -profile /tmp/tmpRhAKa1/profile 05:49:32 INFO - TEST-INFO | started process 4196 (/builds/slave/test/build/application/firefox/firefox http://localhost:45978/getInfo.html) 05:49:38 INFO - TEST-INFO | 4196: exit 0 05:49:38 INFO - Browser initialized. 05:49:38 INFO - Running cycle 1/1 for kraken test... 05:49:38 INFO - Using env: {'DISPLAY': ':0', 05:49:38 INFO - 'HOME': '/home/cltbld', 05:49:38 INFO - 'JSGC_DISABLE_POISONING': '1', 05:49:38 INFO - 'LANG': 'en_US.UTF-8', 05:49:38 INFO - 'LANGUAGE': 'en_US:en', 05:49:38 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 05:49:38 INFO - 'LOGNAME': 'cltbld', 05:49:38 INFO - 'MAIL': '/var/mail/cltbld', 05:49:38 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:49:38 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 05:49:38 INFO - 'MOZ_CRASHREPORTER': '1', 05:49:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:49:38 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 05:49:38 INFO - 'MOZ_NO_REMOTE': '1', 05:49:38 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:49:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:49:38 INFO - 'NO_EM_RESTART': '1', 05:49:38 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:49:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:49:38 INFO - 'PWD': '/builds/slave/test', 05:49:38 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:49:38 INFO - 'RUST_BACKTRACE': 'full', 05:49:38 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 05:49:38 INFO - 'SHELL': '/bin/bash', 05:49:38 INFO - 'SHLVL': '1', 05:49:38 INFO - 'TERM': 'linux', 05:49:38 INFO - 'TMOUT': '86400', 05:49:38 INFO - 'USER': 'cltbld', 05:49:38 INFO - 'XDG_SESSION_COOKIE': '1258088b6da462a47ba2cd90000001a2-1504787377.144078-1273233852', 05:49:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:49:38 INFO - '_': '/tools/buildbot/bin/python'} 05:49:38 INFO - TEST-INFO | started process 4391 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpRhAKa1/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 05:49:39 INFO - PID 4391 | 05:49:39 INFO - PID 4391 | (/builds/slave/test/build/application/firefox/firefox:4441): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 05:49:39 INFO - PID 4391 | 05:49:49 INFO - PID 4391 | RSS: Main: 171847680 05:49:49 INFO - PID 4391 | 05:49:52 INFO - PID 4391 | Cycle 1(1): loaded http://localhost:45978/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:45978/tests/kraken/driver.html?testName=audio-beat-detection) 05:49:53 INFO - PID 4391 | RSS: Main: 178151424 05:49:53 INFO - PID 4391 | 05:49:55 INFO - PID 4391 | Cycle 1(1): loaded http://localhost:45978/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:45978/tests/kraken/driver.html?testName=audio-dft) 05:49:56 INFO - PID 4391 | RSS: Main: 179142656 05:49:56 INFO - PID 4391 | 05:49:59 INFO - PID 4391 | Cycle 1(1): loaded http://localhost:45978/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:45978/tests/kraken/driver.html?testName=audio-fft) 05:49:59 INFO - PID 4391 | RSS: Main: 172900352 05:49:59 INFO - PID 4391 | 05:50:02 INFO - PID 4391 | Cycle 1(1): loaded http://localhost:45978/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:45978/tests/kraken/driver.html?testName=audio-oscillator) 05:50:02 INFO - PID 4391 | RSS: Main: 173461504 05:50:02 INFO - PID 4391 | 05:50:04 INFO - PID 4391 | Cycle 1(1): loaded http://localhost:45978/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:45978/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:50:05 INFO - PID 4391 | RSS: Main: 173707264 05:50:05 INFO - PID 4391 | 05:50:08 INFO - PID 4391 | Cycle 1(1): loaded http://localhost:45978/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:45978/tests/kraken/driver.html?testName=imaging-darkroom) 05:50:09 INFO - PID 4391 | RSS: Main: 176726016 05:50:09 INFO - PID 4391 | 05:50:12 INFO - PID 4391 | Cycle 1(1): loaded http://localhost:45978/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:45978/tests/kraken/driver.html?testName=imaging-desaturate) 05:50:13 INFO - PID 4391 | RSS: Main: 176742400 05:50:13 INFO - PID 4391 | 05:50:16 INFO - PID 4391 | Cycle 1(1): loaded http://localhost:45978/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:45978/tests/kraken/driver.html?testName=json-parse-financial) 05:50:16 INFO - PID 4391 | RSS: Main: 176787456 05:50:16 INFO - PID 4391 | 05:50:18 INFO - PID 4391 | Cycle 1(1): loaded http://localhost:45978/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:45978/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:50:19 INFO - PID 4391 | RSS: Main: 176521216 05:50:19 INFO - PID 4391 | 05:50:20 INFO - PID 4391 | Cycle 1(1): loaded http://localhost:45978/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:45978/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:50:21 INFO - PID 4391 | RSS: Main: 176918528 05:50:21 INFO - PID 4391 | 05:50:23 INFO - PID 4391 | Cycle 1(1): loaded http://localhost:45978/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:45978/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:50:24 INFO - PID 4391 | RSS: Main: 173412352 05:50:24 INFO - PID 4391 | 05:50:26 INFO - PID 4391 | Cycle 1(1): loaded http://localhost:45978/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:45978/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:50:27 INFO - PID 4391 | RSS: Main: 174436352 05:50:27 INFO - PID 4391 | 05:50:30 INFO - PID 4391 | Cycle 1(1): loaded http://localhost:45978/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:45978/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:50:31 INFO - PID 4391 | RSS: Main: 173821952 05:50:31 INFO - PID 4391 | 05:50:32 INFO - PID 4391 | Cycle 1(1): loaded http://localhost:45978/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:45978/tests/kraken/driver.html?testName=ai-astar) 05:50:33 INFO - PID 4391 | RSS: Main: 175669248 05:50:33 INFO - PID 4391 | 05:50:33 INFO - PID 4391 | __start_tp_report 05:50:33 INFO - PID 4391 | _x_x_mozilla_page_load 05:50:33 INFO - PID 4391 | _x_x_mozilla_page_load_details 05:50:33 INFO - PID 4391 | |i|pagename|runs| 05:50:33 INFO - PID 4391 | |0;ai-astar;98;107;100;108;107;113;108;97;115;108 05:50:33 INFO - PID 4391 | |1;audio-beat-detection;146;115;136;165;117;143;146;121;168;117 05:50:33 INFO - PID 4391 | |2;audio-dft;163;161;162;160;161;161;160;166;165;162 05:50:33 INFO - PID 4391 | |3;audio-fft;75;74;74;169;77;75;77;76;149;76 05:50:33 INFO - PID 4391 | |4;audio-oscillator;79;79;132;79;80;79;133;79;80;128 05:50:33 INFO - PID 4391 | |5;imaging-gaussian-blur;114;114;114;114;113;114;114;113;114;113 05:50:33 INFO - PID 4391 | |6;imaging-darkroom;148;148;147;147;147;147;148;153;148;147 05:50:33 INFO - PID 4391 | |7;imaging-desaturate;94;94;94;94;95;94;94;93;93;94 05:50:33 INFO - PID 4391 | |8;json-parse-financial;52;54;54;53;52;53;52;52;52;54 05:50:33 INFO - PID 4391 | |9;json-stringify-tinderbox;38;38;38;39;37;37;37;38;38;37 05:50:33 INFO - PID 4391 | |10;stanford-crypto-aes;73;75;73;75;75;75;74;75;73;74 05:50:33 INFO - PID 4391 | |11;stanford-crypto-ccm;121;121;125;120;126;125;119;124;115;163 05:50:33 INFO - PID 4391 | |12;stanford-crypto-pbkdf2;172;168;166;188;168;174;170;168;164;167 05:50:33 INFO - PID 4391 | |13;stanford-crypto-sha256-iterative;63;65;65;68;66;65;65;64;62;62 05:50:33 INFO - PID 4391 | __end_tp_report 05:50:33 INFO - PID 4391 | __start_cc_report 05:50:33 INFO - PID 4391 | _x_x_mozilla_cycle_collect,974 05:50:33 INFO - PID 4391 | __end_cc_report 05:50:33 INFO - PID 4391 | __startTimestamp1504788633073__endTimestamp 05:50:33 INFO - PID 4391 | 05:50:33 INFO - PID 4391 | ------- Summary: start ------- 05:50:33 INFO - PID 4391 | Number of tests: 14 05:50:33 INFO - PID 4391 | 05:50:33 INFO - PID 4391 | [#0] ai-astar Cycles:10 Average:106.10 Median:110.50 stddev:6.01 (5.4%) stddev-sans-first:5.61 05:50:33 INFO - PID 4391 | Values: 98.0 107.0 100.0 108.0 107.0 113.0 108.0 97.0 115.0 108.0 05:50:33 INFO - PID 4391 | 05:50:33 INFO - PID 4391 | [#1] audio-beat-detection Cycles:10 Average:137.40 Median:144.50 stddev:19.67 (13.6%) stddev-sans-first:20.62 05:50:33 INFO - PID 4391 | Values: 146.0 115.0 136.0 165.0 117.0 143.0 146.0 121.0 168.0 117.0 05:50:33 INFO - PID 4391 | 05:50:33 INFO - PID 4391 | [#2] audio-dft Cycles:10 Average:162.10 Median:162.00 stddev:2.02 (1.2%) stddev-sans-first:2.12 05:50:33 INFO - PID 4391 | Values: 163.0 161.0 162.0 160.0 161.0 161.0 160.0 166.0 165.0 162.0 05:50:33 INFO - PID 4391 | 05:50:33 INFO - PID 4391 | [#3] audio-fft Cycles:10 Average:92.20 Median:75.50 stddev:35.54 (47.1%) stddev-sans-first:37.14 05:50:33 INFO - PID 4391 | Values: 75.0 74.0 74.0 169.0 77.0 75.0 77.0 76.0 149.0 76.0 05:50:33 INFO - PID 4391 | 05:50:33 INFO - PID 4391 | [#4] audio-oscillator Cycles:10 Average:94.80 Median:79.00 stddev:25.01 (31.7%) stddev-sans-first:25.87 05:50:33 INFO - PID 4391 | Values: 79.0 79.0 132.0 79.0 80.0 79.0 133.0 79.0 80.0 128.0 05:50:33 INFO - PID 4391 | 05:50:33 INFO - PID 4391 | [#5] imaging-gaussian-blur Cycles:10 Average:113.70 Median:114.00 stddev:0.48 (0.4%) stddev-sans-first:0.50 05:50:33 INFO - PID 4391 | Values: 114.0 114.0 114.0 114.0 113.0 114.0 114.0 113.0 114.0 113.0 05:50:33 INFO - PID 4391 | 05:50:33 INFO - PID 4391 | [#6] imaging-darkroom Cycles:10 Average:148.00 Median:148.00 stddev:1.83 (1.2%) stddev-sans-first:1.94 05:50:33 INFO - PID 4391 | Values: 148.0 148.0 147.0 147.0 147.0 147.0 148.0 153.0 148.0 147.0 05:50:33 INFO - PID 4391 | 05:50:33 INFO - PID 4391 | [#7] imaging-desaturate Cycles:10 Average:93.90 Median:94.00 stddev:0.57 (0.6%) stddev-sans-first:0.60 05:50:33 INFO - PID 4391 | Values: 94.0 94.0 94.0 94.0 95.0 94.0 94.0 93.0 93.0 94.0 05:50:33 INFO - PID 4391 | 05:50:33 INFO - PID 4391 | [#8] json-parse-financial Cycles:10 Average:52.80 Median:53.00 stddev:0.92 (1.7%) stddev-sans-first:0.93 05:50:33 INFO - PID 4391 | Values: 52.0 54.0 54.0 53.0 52.0 53.0 52.0 52.0 52.0 54.0 05:50:33 INFO - PID 4391 | 05:50:33 INFO - PID 4391 | [#9] json-stringify-tinderbox Cycles:10 Average:37.70 Median:38.00 stddev:0.67 (1.8%) stddev-sans-first:0.71 05:50:33 INFO - PID 4391 | Values: 38.0 38.0 38.0 39.0 37.0 37.0 37.0 38.0 38.0 37.0 05:50:33 INFO - PID 4391 | 05:50:33 INFO - PID 4391 | [#10] stanford-crypto-aes Cycles:10 Average:74.20 Median:75.00 stddev:0.92 (1.2%) stddev-sans-first:0.87 05:50:33 INFO - PID 4391 | Values: 73.0 75.0 73.0 75.0 75.0 75.0 74.0 75.0 73.0 74.0 05:50:33 INFO - PID 4391 | 05:50:33 INFO - PID 4391 | [#11] stanford-crypto-ccm Cycles:10 Average:125.90 Median:124.50 stddev:13.46 (10.8%) stddev-sans-first:14.16 05:50:33 INFO - PID 4391 | Values: 121.0 121.0 125.0 120.0 126.0 125.0 119.0 124.0 115.0 163.0 05:50:33 INFO - PID 4391 | 05:50:33 INFO - PID 4391 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:170.50 Median:169.00 stddev:6.79 (4.0%) stddev-sans-first:7.18 05:50:33 INFO - PID 4391 | Values: 172.0 168.0 166.0 188.0 168.0 174.0 170.0 168.0 164.0 167.0 05:50:33 INFO - PID 4391 | 05:50:33 INFO - PID 4391 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.50 Median:65.00 stddev:1.84 (2.8%) stddev-sans-first:1.87 05:50:33 INFO - PID 4391 | Values: 63.0 65.0 65.0 68.0 66.0 65.0 65.0 64.0 62.0 62.0 05:50:33 INFO - PID 4391 | -------- Summary: end -------- 05:50:33 INFO - PID 4391 | 05:50:33 INFO - PID 4391 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 05:50:33 INFO - TEST-INFO | 4391: exit 0 05:50:33 INFO - TEST-OK | kraken | took 61138ms 05:50:33 INFO - SUITE-END | took 1173s 05:50:34 INFO - Completed test suite (00:19:34) 05:50:34 INFO - javascript benchmark 05:50:34 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8624.54979758652, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3262.4616938205213, 3306.156929982854, 3315.0883507035564, 3316.0549243465616, 3325.7173140283076, 6491.058796474515, 6499.1876015498065, 6546.247181574115, 6548.27968923418, 6598.043283723863, 6296.338884417139, 6305.369463053692, 6388.137601423807, 6390.552661313698, 6410.038494225857, 5885.793997210109, 5886.764529418818, 5890.410958904118, 5910.34986151523, 5964.463198312151, 5947.6134051286745, 5959.284885813709, 5965.642061476326, 5966.045432730764, 5967.522598192156, 6020.0, 6060.030395136779, 6063.878722425548, 6081.939180608181, 6097.8170654880205, 5893.58744887858, 5990.9400905990815, 5996.040633498663, 5996.580239383245, 6043.305019922699, 5705.914411283834, 5812.796552120698, 5848.502877255439, 5880.0003999320215, 5945.821625351246, 6455.451286640641, 6501.414872661436, 6501.544891857572, 6520.250171230322, 6528.249251336062, 6664.666766661696, 6910.619915904624, 6912.447004239649, 7000.789976300719, 7037.296270372973, 6858.3827455529245, 6872.312768723138, 6875.48433867462, 6879.89680154798, 6884.380405763505, 6397.944339183982, 6399.648019358933, 6401.263854656728, 6403.775867844603, 6414.839629009265, 6995.545289556264, 7067.257937916547, 7078.681459334342, 7078.893816592756, 7090.858182836314, 6753.1220941277315, 6756.695948682321, 6761.0872532208505, 6761.898571521431, 6768.153980752406, 6828.5220034597105, 6843.110395648534, 6863.210730766026, 6926.2034865990045, 7021.262767409451, 6884.242733299433, 6901.896471552931, 6925.445964322943, 7012.474064445085, 7015.614141222281, 6741.0, 6817.897731534031, 6866.896996545056, 6936.618485983319, 7035.964820175969, 5516.255305533701, 5519.862003449946, 5530.170474428868, 5530.474604912527, 5530.861728456821, 4725.149473094876, 4783.258594917794, 4791.6406269529925, 4810.518948105161, 4831.806727730922, 5931.792387266425, 5992.850178745566, 5999.160117583542, 6018.849528761816, 6137.60105593135, 6913.827154321081, 6927.203371612329, 6932.480063995221, 6939.340762627643, 6939.548929319578, 5978.5814992950945, 5983.760649573968, 5985.221921150222, 5986.5809393342915, 5991.041433370727, 5897.5871688982215, 5960.612560183574, 5972.492338151219, 5973.014452615356, 5976.073708575178, 5567.498925096761, 6669.966650166815, 6749.460043196533, 6836.418904393182, 6838.281980392087, 6986.196587392312, 7025.086738723933, 7062.364387205073, 7066.681999309941, 7128.037714908421, 6564.573302735307, 6652.968789837583, 6656.7004484797335, 6663.533552651268, 6698.129243198353, 6732.158480189976, 6797.28628494001, 6838.452923766186, 6920.238773734784, 6926.896096558455, 7015.579065256101, 7037.225905150532, 7039.54242974205, 7039.824004399931, 7094.290570942864, 6666.866662666816, 6752.527323087534, 6828.214755303204, 6845.863082738218, 6856.6228857413325, 6687.164104486939, 6716.1604799400075, 6738.8652226956165, 6842.178938527409, 6843.62360070181, 6511.609303441809, 6601.240857301472, 6614.206295244601, 6656.500762442739, 6774.051632771318, 6695.497837662292, 6718.899216511854, 6747.628880411625, 6775.525713200029, 6888.896666550106, 6430.903536447051, 6543.051257567721, 6564.80305590833, 6668.099806526056, 6669.6331701754925], "value": 6309.325001742416, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [719.568259044573, 733.4059411876381, 774.4965772248034, 774.7443343696578, 786.7993661616289, 40700.796496017516, 41124.17751644965, 41136.58863411369, 41421.79289103558, 41506.7924660377, 38281.23437531248, 38461.80769096158, 38493.61506384936, 38504.0, 38550.61449385505, 6869.347411995865, 6973.198082220559, 6977.3022697730075, 6997.930020699804, 7000.439964802804, 6837.076994605739, 6874.553154044971, 6907.205671347784, 6925.619090949996, 6942.409895158918, 6874.931250687504, 6912.930870691304, 6915.066466027072, 6923.342282483181, 6925.757598484054, 6827.0, 6837.076994605714, 6838.760643377483, 6863.382295593396, 6887.104676392087, 6233.376662333776, 6269.655168965705, 6288.0, 6290.748370065192, 6297.1813664223355, 33747.32505349904, 34092.31815363704, 34252.828735856165, 34258.14354641129, 34350.312993740234, 3369.494575813623, 3379.205886616643, 3388.034410193083, 3388.8305584720665, 3391.3895498810202, 27921.301967450978, 27968.160955171377, 28119.156425307272, 28145.0, 28167.718322816716, 4387.495438024636, 4453.933191002137, 4468.955310446919, 4483.663725220622, 4489.483709373399, 217150.0, 222530.0, 224286.0, 226065.86967065223, 226301.0, 47635.761821191365, 49910.75044624753, 49942.75028624834, 50112.74943625258, 50497.0, 50582.24126638103, 50596.0, 50670.74664626653, 50674.23988640173, 50683.74658126686, 14748.557543273719, 14762.70474590502, 14781.113133212042, 14915.552533424014, 14971.251437428304, 11795.174337796272, 11802.40987950599, 11817.822732659017, 11940.641780746591, 12028.939855300843, 14043.08719933201, 14054.0, 14085.15489070659, 14154.433822646979, 14306.0, 5727.942720572824, 5825.7087145642545, 5832.329282132524, 5880.9414305425435, 5886.264216972879, 1010.8079464901646, 1017.7353887989028, 1027.6454623155, 1031.2111234905303, 1033.194108595297, 22858.199963001214, 23486.412839679146, 28852.0, 29371.706282937324, 29779.851100744792, 1092.322759888874, 1102.80149573076, 1105.3865104866877, 1112.8720197177267, 1114.3202646385698, 12615.558455454198, 12738.490460381481, 12741.0, 12768.0, 12771.808422873664, 239250.0, 241808.0, 242088.0, 242274.0, 243129.0, 52183.217251741255, 52637.73681131646, 52789.736051319494, 56072.0, 56140.71929640326, 1385.9792103118461, 1436.590571687064, 1438.4102517967374, 1447.2474313357206, 1454.0330680097827, 27783.58324625173, 28052.85973570119, 28278.43443131167, 28340.0, 28469.288267793472, 38392.80803595964, 38696.41955370728, 38711.6128838708, 38729.41905871358, 38755.0, 206954.0, 206976.0, 207818.8436231297, 207890.0, 208009.0, 34513.827430862686, 37293.0, 38636.420453692655, 38773.0, 38789.806050969564, 46547.30179047249, 46649.53350466588, 46891.531084688715, 47411.0, 47460.0, 29461.0, 29781.851090745273, 29917.55123673103, 29922.0, 30029.399412012073, 34092.829535852165, 34107.31785364328, 34184.316313674084, 34515.48226776651, 34578.48132278068, 38355.424668629435, 38536.0, 38770.806145970215, 38820.80589597034, 39051.41422878716, 33049.17377065593, 33097.338053238316, 33107.0, 33191.83404082964, 33887.0, 24432.755672443047, 24556.50886982286, 24874.0, 25428.0, 25668.486630267664], "value": 16987.10477131733, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [560.1345920552745, 572.2560671127533, 577.9277590301213, 581.604508933925, 583.3029529711995, 22264.777352226472, 22480.775192248093, 23131.421714457127, 23138.190163344287, 23290.65064024037, 22696.54606907861, 24448.75551244487, 24449.144279950204, 24500.75499245007, 24506.75493245067, 6638.7344506219815, 6772.69522871472, 6774.661266936658, 6781.423578995791, 6781.423578995791, 6365.331640177784, 6650.833729156786, 6686.1308029956035, 6748.561343512681, 6754.358335958076, 6633.502487313447, 6639.170103737033, 6639.502037347194, 6640.468762499013, 6640.667966601651, 6632.137822083147, 6639.302873198293, 6642.269350371455, 6647.700853461582, 6656.667166641672, 6157.568970172045, 6166.7533298667995, 6167.568270221086, 6170.167027451326, 6181.938180618226, 28692.569611455845, 29104.708952910412, 29514.85242573795, 29901.401971960655, 29928.0, 2060.5672808710187, 2106.031225636209, 2108.3569511299047, 2109.8839563823985, 2110.472381904524, 19498.70751938722, 19973.50066248341, 20054.899725501426, 20088.99555022243, 20095.397138085882, 2348.788609025196, 2361.9527609447887, 2400.59189937711, 2405.350555350063, 2474.7772700457044, 128645.0, 134165.0, 134376.32811835976, 134504.32747836297, 134937.3253133738, 14258.786118208236, 15021.324040418207, 15040.0, 15143.469978550698, 15179.165145916859, 22884.65673014906, 22970.19604313841, 23606.409839754142, 23642.290731278088, 23692.05231790709, 9079.137481939206, 9388.342816002814, 9439.433633981984, 9478.526073696288, 9590.136887680002, 8397.538135402612, 8418.915810841812, 8433.95783021081, 8549.187827156411, 8573.871391929126, 8967.641294348283, 8987.640494380152, 9231.676891308905, 9233.353665243503, 9358.57886395114, 2706.7157948415525, 2750.22993561803, 2778.819376740546, 2779.958300625492, 2864.1550742531003, 969.9757506062405, 989.8960609136082, 993.3394292795355, 1006.6376104602338, 1014.878214614251, 19806.16933055246, 19858.404247872586, 20133.0, 20447.28434504785, 24244.393890152536, 1019.928604997643, 1041.4584416103585, 1048.3238311289176, 1053.0364716284514, 1057.5558265528496, 11274.774504510027, 11399.373034483016, 11424.143189260838, 11472.54109835614, 11521.827172592419, 501841.0, 507151.0, 510010.0, 510934.0, 517934.0, 123492.0, 124083.0, 126729.0, 127701.0, 129076.0, 1200.789861774186, 1211.8303437518755, 1244.6577191272472, 1244.887960083597, 1249.6376050945305, 20426.67226630264, 20705.27531536389, 20967.21778648832, 21049.263275785273, 21181.6822747662, 51361.0, 51960.0, 57387.0, 57688.0, 61739.38260617336, 69827.0, 70579.29420705933, 70597.0, 70948.0, 71159.0, 14009.08940918837, 14469.348879300247, 14541.85458145405, 14575.416983320973, 14597.708045839236, 20519.281825136048, 20580.0, 20628.17487300491, 20663.656862303902, 20674.17303307911, 16608.0, 16805.327786888876, 17030.659386812444, 17043.488695339158, 17046.744298835776, 16332.183390830649, 16743.0, 17604.91197544004, 18048.187831547348, 18253.361132360304, 18083.0, 18127.546811329823, 18166.273349066418, 18247.635047299245, 18346.724799127758, 14736.77894831557, 14866.331015104563, 14945.925270374013, 14946.925265373604, 14980.475683351031, 16967.91516042412, 17035.063071531185, 17125.14374281256, 17130.31478740886, 17143.228554714817], "value": 11999.986745974595, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1292.4313302147054, 1324.0, 1358.7825947848344, 1359.5445525748873, 1373.0868972133533, 12882.0, 15030.398784048639, 15670.84329156708, 15698.293576789043, 15707.842921570795, 13092.607221783337, 13196.53812116576, 15152.924235378807, 15752.054876707405, 15798.684026319495, 2723.087765598527, 2779.4441111777624, 2784.958225626617, 2786.192004318755, 2787.3031742064486, 2620.908268210613, 2671.7061123276426, 2736.0287098080094, 2746.1075150576044, 2793.1201671473523, 2761.0888406825798, 2806.985965070172, 2812.535931571288, 2813.08574713218, 2815.1695249901327, 2578.084779903145, 2608.8434693918234, 2620.2139358192467, 2624.8556329401877, 2647.311698958275, 2694.2321438389963, 2698.5007773561956, 2708.9864550677316, 2714.945701085987, 2720.170348043846, 14861.256937153206, 15171.468998584996, 15185.696286074328, 15199.468018619405, 15203.163825989566, 7599.848003039963, 7906.920930790733, 7907.2488113629115, 7912.208779122099, 7913.366930645535, 4920.228717661639, 5091.9236211456855, 5096.923546146811, 5319.494648008433, 5495.120780675113, 1882.9152688129068, 1910.4077735901915, 1916.0802814649046, 1916.377177417331, 1955.735975643284, 26293.21120366392, 26552.73447265522, 26622.33444163892, 26627.06805261826, 26736.73263267362, 6628.0, 6660.0, 6868.416184624363, 6911.101556797584, 6914.377706006484, 16893.746593801105, 17479.825201748074, 17526.036068016125, 17539.210735516932, 17542.29830806779, 7483.738069167553, 7648.617569121521, 7650.923490765133, 7653.540787552764, 7677.808054798675, 6660.96669516659, 6920.653967301615, 6937.236903940562, 7105.182903966007, 7110.004599356094, 5190.92213616796, 5346.465353464622, 5351.224072509464, 5383.703896285742, 5388.8113916013535, 1524.7268530776703, 1526.1758650328698, 1539.09962671836, 1562.8905976581757, 1568.9607759805867, 893.3746377535751, 922.856957171626, 926.3515539122641, 931.3015238571072, 937.4422218779815, 7717.382609391235, 7923.801904952422, 7939.722109726132, 7947.920520794718, 7995.080565734898, 703.8733028055, 717.3579646216607, 721.105828772329, 741.5439504704676, 752.6913965274211, 5035.345405097387, 5363.919541206885, 5595.496405323459, 5641.323041235078, 5855.736491857877, 21325.89337053305, 22208.777912220994, 22274.220402285842, 22535.323940281818, 22809.543809124058, 21747.0, 21836.89081554614, 22200.555988880133, 22239.22162724297, 22310.442238944157, 10820.621278255223, 10853.728656783644, 11161.665150045512, 11189.272697274813, 11206.215564910379, 8298.958505207434, 8371.95814020926, 8656.956715216384, 8722.476651400937, 8744.387892847439, 18935.863848169152, 19123.617527649647, 19168.233270669018, 19177.904110479358, 19259.807401925802, 19002.714959275345, 19154.138063787443, 19543.511412214808, 19632.60734785325, 19739.506512337306, 11507.367094809866, 11841.82237266459, 11844.70388240301, 11862.40687965581, 11868.76262474763, 14213.360398781873, 14757.92621036888, 14785.630359241106, 14808.555743327717, 14869.405223790927, 10521.526531306263, 10604.310719803188, 10623.681289561016, 10653.733656658645, 10817.459127043774, 10983.286086404358, 11094.11247100214, 11124.388158651029, 11196.832047519456, 11324.263922844988, 9032.45805251687, 9136.360454768102, 9315.953420232856, 9337.159655631025, 9344.95327523358, 6695.899561506484, 6825.044493770975, 6835.521513494008, 6882.793516194522, 6887.862242755217, 7025.367716905503, 7115.679794409368, 7148.749793757193, 7161.749338773327, 7188.856222875618], "value": 6605.876641538393, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [499.8050760203519, 500.2071716329617, 533.2587703092704, 534.284059360457, 537.9435159308273, 6536.379043990819, 6545.247296560897, 6566.606003639779, 6609.933900660992, 6616.470682345413, 6263.874722505547, 6410.551261411703, 6424.903626445595, 6459.90310145347, 6474.320196379383, 5323.680579165243, 5388.164834450667, 5397.46025397461, 5442.183672449125, 5443.237946687447, 5249.897521520466, 5322.387925388591, 5406.486383793534, 5408.567314614841, 5408.91886621701, 5348.0, 5356.839294821161, 5378.623496355257, 5385.892282154355, 5400.29796126503, 5318.590573498026, 5349.839504814861, 5367.731613419313, 5372.650777699437, 5385.730713464331, 4958.355413796184, 4979.377577802775, 5009.323741294951, 5013.423456302536, 5018.222175562793, 6406.519511036644, 6417.743290268384, 6442.774502892376, 6444.645544495051, 6468.935310646881, 6091.11678806575, 6196.721147548371, 6268.122462855204, 6268.843278918018, 6279.905801412982, 6804.897926531106, 6819.215790184093, 6833.726650933957, 6846.726130954756, 6909.861802763917, 5404.594655400861, 5407.675539467606, 5421.566274698014, 5454.372747134051, 5487.64330318528, 7222.313880181375, 7224.819379515449, 7304.853902921912, 7312.159101703319, 7333.726544771138, 5592.692401917852, 5612.7754889803955, 5651.632643878134, 5657.32112146545, 5681.829545113653, 5742.48317651405, 5762.0, 5766.654000759968, 5776.104703770922, 5790.189373487715, 6103.908441373383, 6141.508679305645, 6203.6587987660205, 6223.719932603044, 6246.250449946036, 6750.763723269662, 6762.357576030269, 6823.658817059128, 6858.759943401957, 6883.827904302332, 1845.2711179084185, 1905.8284754372173, 1907.6184763047168, 1950.4538729155859, 1968.1143485431612, 1426.2013272567397, 1440.7694768837146, 1444.2562080528537, 1459.5110637936198, 1488.1740633948045, 5833.329167145747, 5842.5618078644275, 5867.119932010232, 5878.177055212188, 5907.852303692356, 6633.170853643294, 6763.7632682855865, 6880.69266839299, 6933.653317334113, 7001.194862590765, 5864.941350586525, 5917.751254523339, 5933.81323735246, 5956.493698035629, 5989.191459153046, 3642.3443780119833, 3650.5254316938626, 3658.5609726832417, 3660.743747937619, 3684.705223582107, 6670.7665231716655, 6692.698828552727, 6706.195256569243, 6711.194656641137, 6748.662566871637, 7097.964510177416, 7119.3592576667315, 7131.465140114512, 7180.527991761676, 7208.459365547605, 6657.500687448364, 6684.532082754258, 6762.120924279812, 6776.966115169491, 6811.4210292124735, 6956.443484521327, 7013.964930175316, 7019.5788252705, 7031.101602567381, 7032.191298000591, 6865.725370985105, 6884.896726549206, 6888.931110688829, 6895.724171033103, 6933.5493192942295, 6663.26704062543, 6694.83262918431, 6734.898976515454, 6765.289644587444, 6766.15423072116, 5080.669756465818, 5090.134677104827, 5102.515261050195, 5138.332016837937, 5141.460146684544, 6584.242812076673, 6788.93211067883, 6910.136232970879, 6959.826004349932, 6996.300369962963, 6422.710978005907, 6510.479161666961, 6565.961933411013, 6627.436667883284, 6638.468922486093, 6528.0, 6544.640044797391, 6552.63960482159, 6635.303293154342, 6648.800535983928, 6451.161349024596, 6500.317466666121, 6511.218653761579, 6523.195026138806, 6658.367455091952, 6605.735770569124, 6620.735170593122, 6639.6680165992475, 6695.627396383632, 6734.124563806869, 6419.807405777834, 6444.742210311722, 6522.706478208587, 6552.443042341454, 6563.245226798788], "value": 5332.361363098438, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [891.5631340643087, 902.801383695587, 910.5356268303163, 919.1406035356941, 924.1267002682465, 16291.592710182236, 16646.417375391848, 16666.833331666694, 16670.749938750927, 21517.0, 21021.0, 21485.785142148572, 21541.67687484689, 21561.0, 21584.676229856526, 957.4111921168466, 975.7365511311978, 977.7164622259536, 981.9165370943479, 982.4989255479685, 991.6182269826097, 994.6121012804975, 995.044757033249, 998.7453199434134, 1000.2348203624233, 985.2069084387066, 1013.8225810483173, 1023.6468418395679, 1041.1566631028852, 1059.1209296284105, 1015.7359086637454, 1017.9898201018007, 1019.734868934075, 1021.0096206679583, 1022.4632068164274, 746.1382103670215, 746.391690772019, 746.895434639151, 748.3078152708777, 751.5378042503821, 17951.281948722037, 18020.819791802045, 18046.548836279067, 18055.0, 19903.203871845108, 21016.579668406546, 21062.578748424945, 21296.680549791767, 21610.56778864414, 21665.0, 15563.688726225411, 15834.841651583452, 15891.602709932344, 15977.360905563764, 15986.76019859703, 3630.909227269313, 3665.2852693724735, 3677.099110717891, 3690.5940346561856, 3691.3724666806706, 122320.0, 124114.37942810319, 124284.0, 125741.0, 127632.36183819114, 15685.137317447421, 15717.921410392875, 16126.516204513884, 16246.75629865553, 16255.0, 29701.554476682868, 30218.395632087235, 30611.540826887616, 30615.387692246477, 30671.079867604007, 7649.617519124022, 7989.640466179036, 8051.355891528664, 8083.514989100673, 8467.237948584658, 4235.4917409911, 4272.551382104896, 4343.522212556616, 4344.9354908047735, 4349.173657005159, 5104.05574968632, 5216.26972223889, 5220.765065572058, 5242.1088414969545, 5247.580193584504, 1072.2654981337653, 1080.4921686807188, 1091.1598069486452, 1092.5029111754134, 1099.5546803544416, 675.7263308360122, 692.3007762160154, 701.3792793377897, 707.0243064570859, 713.3223437734069, 11351.205415620992, 11421.200515963968, 11896.405179740977, 12271.202371845802, 12346.876531234753, 700.3206889317354, 706.8268274272733, 711.5837235217456, 712.1881055596624, 727.1492353945896, 5353.116735738559, 5493.395726470085, 6109.327973922865, 6195.380461953859, 6517.478601711947, 111160.44419777849, 112389.0, 112838.43580782044, 113462.0, 115869.0, 94938.0, 95590.52204738931, 96056.0, 99400.50299748455, 99456.50271748594, 86100.56949715212, 87971.56014219888, 88517.0, 91107.54446227726, 92507.53746231226, 11977.401129943293, 12410.81383779226, 12624.242545447303, 12760.042996775093, 13000.479980800663, 39238.607613923494, 39595.2080958385, 39833.0, 41563.584364157185, 41969.580304197785, 50821.745891271785, 51655.48344516507, 52294.73852630865, 53229.201561977374, 53868.46131538791, 17495.737563936807, 18166.90916545409, 18851.057447127314, 18887.0, 19092.904535477235, 20556.383308500768, 20604.484887877923, 20765.58468830645, 20939.16243350249, 21323.893380532998, 18323.633527329646, 18538.81461185425, 18543.35098271608, 18976.715349269496, 19237.519062023563, 17738.822611773718, 18229.361972330906, 19052.714209286594, 19120.235190592775, 19244.518887027938, 16124.0, 16234.107124108288, 17649.117544123, 18531.536711582317, 18645.347412840485, 13150.934245328712, 13751.724965500434, 13845.653858653613, 14010.859891401364, 14255.358508867334, 14556.563303100924, 14839.629009274855, 14984.47554335593, 15133.54599362021, 15175.0], "value": 9083.685520191411, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1473.8000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 107.0, 100.0, 108.0, 107.0, 113.0, 108.0, 97.0, 115.0, 108.0], "value": 106.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 115.0, 136.0, 165.0, 117.0, 143.0, 146.0, 121.0, 168.0, 117.0], "value": 137.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 161.0, 162.0, 160.0, 161.0, 161.0, 160.0, 166.0, 165.0, 162.0], "value": 162.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 74.0, 74.0, 169.0, 77.0, 75.0, 77.0, 76.0, 149.0, 76.0], "value": 92.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 79.0, 132.0, 79.0, 80.0, 79.0, 133.0, 79.0, 80.0, 128.0], "value": 94.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 114.0, 114.0, 113.0, 114.0, 114.0, 113.0, 114.0, 113.0], "value": 113.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 148.0, 147.0, 147.0, 147.0, 147.0, 148.0, 153.0, 148.0, 147.0], "value": 148.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 94.0, 95.0, 94.0, 94.0, 93.0, 93.0, 94.0], "value": 93.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 54.0, 54.0, 53.0, 52.0, 53.0, 52.0, 52.0, 52.0, 54.0], "value": 52.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [38.0, 38.0, 38.0, 39.0, 37.0, 37.0, 37.0, 38.0, 38.0, 37.0], "value": 37.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 75.0, 73.0, 75.0, 75.0, 75.0, 74.0, 75.0, 73.0, 74.0], "value": 74.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [121.0, 121.0, 125.0, 120.0, 126.0, 125.0, 119.0, 124.0, 115.0, 163.0], "value": 125.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 168.0, 166.0, 188.0, 168.0, 174.0, 170.0, 168.0, 164.0, 167.0], "value": 170.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 65.0, 65.0, 68.0, 66.0, 65.0, 65.0, 64.0, 62.0, 62.0], "value": 64.5, "unit": "score"}]}]} 05:50:34 INFO - Return code: 0 05:50:34 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:50:34 INFO - # TBPL SUCCESS # 05:50:34 INFO - Running post-action listener: _package_coverage_data 05:50:34 INFO - Running post-action listener: _resource_record_post_action 05:50:34 INFO - [mozharness: 2017-09-07 12:50:34.362873Z] Finished run-tests step (success) 05:50:34 INFO - Running post-run listener: _resource_record_post_run 05:50:34 INFO - Total resource usage - Wall time: 1191s; CPU: 13.0%; Read bytes: 8753152; Write bytes: 409391104; Read time: 2408; Write time: 361536 05:50:34 INFO - TinderboxPrint: CPU usage
13.1% 05:50:34 INFO - TinderboxPrint: I/O read bytes / time
8,753,152 / 2,408 05:50:34 INFO - TinderboxPrint: I/O write bytes / time
409,391,104 / 361,536 05:50:34 INFO - TinderboxPrint: CPU idle
8,248.1 (86.9%) 05:50:34 INFO - TinderboxPrint: CPU user
1,192.2 (12.6%) 05:50:34 INFO - TinderboxPrint: Swap in / out
0 / 0 05:50:34 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 4096; Write bytes: 121085952; Read time: 212; Write time: 80600 05:50:34 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 05:50:34 INFO - run-tests - Wall time: 1175s; CPU: 13.0%; Read bytes: 7004160; Write bytes: 288296960; Read time: 2156; Write time: 280932 05:50:34 INFO - Running post-run listener: _upload_blobber_files 05:50:34 INFO - Blob upload gear active. 05:50:34 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:50:34 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 05:50:34 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-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:50:34 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-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:50:35 INFO - (blobuploader) - INFO - Open directory for files ... 05:50:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 05:50:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:50:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:50:36 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 05:50:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:50:36 INFO - (blobuploader) - INFO - Done attempting. 05:50:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 05:50:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:50:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:50:37 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 05:50:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:50:37 INFO - (blobuploader) - INFO - Done attempting. 05:50:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 05:50:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:50:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:50:38 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 05:50:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:50:38 INFO - (blobuploader) - INFO - Done attempting. 05:50:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:50:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:50:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:50:41 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:50:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:50:41 INFO - (blobuploader) - INFO - Done attempting. 05:50:41 INFO - (blobuploader) - INFO - Iteration through files over. 05:50:41 INFO - Return code: 0 05:50:41 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:50:41 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:50:41 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/64d57ccf7cd34fb5d807f90277ecda03a65dcc9959a04ecebc858934653459d128ad5a97f74fe2bac7050c316b031174af5b3f1a7fffe36b9f3057831a4b0bd7", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b51640e651bda7f22e02c54d435955ba28cd652d5e398e92d6099c3f48a1223e054e11df76747c8f6f800f83c1117059c296f964b39379efd5b528f05fa4b8b3", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/0711cfc3507db75f4254366f480c9a684646109009c596d1d20983ebb4868035555d7aa3a63bfd226687db1055b28aab2ade9b1faf41d365d988856f24147332", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3be6b07374039da05210cb411303c1d106d24704c77020d45701fee75d9cfb0ace7a7b3a526992abb3f71b5e880d438e84af67c917ab14f3dbd8f41be43db486"} 05:50:41 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:50:41 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:50:41 INFO - Contents: 05:50:41 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/64d57ccf7cd34fb5d807f90277ecda03a65dcc9959a04ecebc858934653459d128ad5a97f74fe2bac7050c316b031174af5b3f1a7fffe36b9f3057831a4b0bd7", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b51640e651bda7f22e02c54d435955ba28cd652d5e398e92d6099c3f48a1223e054e11df76747c8f6f800f83c1117059c296f964b39379efd5b528f05fa4b8b3", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/0711cfc3507db75f4254366f480c9a684646109009c596d1d20983ebb4868035555d7aa3a63bfd226687db1055b28aab2ade9b1faf41d365d988856f24147332", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3be6b07374039da05210cb411303c1d106d24704c77020d45701fee75d9cfb0ace7a7b3a526992abb3f71b5e880d438e84af67c917ab14f3dbd8f41be43db486"} 05:50:41 INFO - Running post-run listener: copy_logs_to_upload_dir 05:50:41 INFO - Copying logs to upload dir... 05:50:41 INFO - mkdir: /builds/slave/test/build/upload/logs 05:50:41 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1251.783858 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 51 secs) (at 2017-09-07 05:50:41.379227) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-07 05:50:41.383125) ========= 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=1258088b6da462a47ba2cd90000001a2-1504787377.144078-1273233852 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/64d57ccf7cd34fb5d807f90277ecda03a65dcc9959a04ecebc858934653459d128ad5a97f74fe2bac7050c316b031174af5b3f1a7fffe36b9f3057831a4b0bd7", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b51640e651bda7f22e02c54d435955ba28cd652d5e398e92d6099c3f48a1223e054e11df76747c8f6f800f83c1117059c296f964b39379efd5b528f05fa4b8b3", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/0711cfc3507db75f4254366f480c9a684646109009c596d1d20983ebb4868035555d7aa3a63bfd226687db1055b28aab2ade9b1faf41d365d988856f24147332", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3be6b07374039da05210cb411303c1d106d24704c77020d45701fee75d9cfb0ace7a7b3a526992abb3f71b5e880d438e84af67c917ab14f3dbd8f41be43db486"} build_url:https://queue.taskcluster.net/v1/task/BaAK-As6Q66QQapQlMtaJw/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011255 build_url: 'https://queue.taskcluster.net/v1/task/BaAK-As6Q66QQapQlMtaJw/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/64d57ccf7cd34fb5d807f90277ecda03a65dcc9959a04ecebc858934653459d128ad5a97f74fe2bac7050c316b031174af5b3f1a7fffe36b9f3057831a4b0bd7", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b51640e651bda7f22e02c54d435955ba28cd652d5e398e92d6099c3f48a1223e054e11df76747c8f6f800f83c1117059c296f964b39379efd5b528f05fa4b8b3", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/0711cfc3507db75f4254366f480c9a684646109009c596d1d20983ebb4868035555d7aa3a63bfd226687db1055b28aab2ade9b1faf41d365d988856f24147332", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3be6b07374039da05210cb411303c1d106d24704c77020d45701fee75d9cfb0ace7a7b3a526992abb3f71b5e880d438e84af67c917ab14f3dbd8f41be43db486"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-07 05:50:41.417184) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-07 05:50:41.417849) ========= 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=1258088b6da462a47ba2cd90000001a2-1504787377.144078-1273233852 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004488 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-07 05:50:41.501060) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-07 05:50:41.501604) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-07 05:50:41.502198) ========= ========= Total master_lag: 0.19 =========