builder: mozilla-central_ubuntu64_hw_stylo_test-dromaeojs-e10s slave: talos-linux64-ix-072 starttime: 1504520179.38 results: success (0) revision: 632e42dca494ec3d90b70325d9c359f80cb3f38a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-04 03:16:19.380333) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-04 03:16:19.380761) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-04 03:16:19.381043) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-04 03:16:19.399111) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-04 03:16:19.399436) ========= 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=199fb4c72b90437b990b419b000001c5-1504520177.184169-1287861291 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-04 03:16:19-- 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% 41.6M=0s 2017-09-04 03:16:19 (41.6 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.157649 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-04 03:16:19.574454) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-04 03:16:19.574772) ========= 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=199fb4c72b90437b990b419b000001c5-1504520177.184169-1287861291 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017834 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-04 03:16:19.623434) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-04 03:16:19.623729) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 632e42dca494ec3d90b70325d9c359f80cb3f38a --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 632e42dca494ec3d90b70325d9c359f80cb3f38a --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=199fb4c72b90437b990b419b000001c5-1504520177.184169-1287861291 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-04 03:16:19,695 truncating revision to first 12 chars 2017-09-04 03:16:19,695 Setting DEBUG logging. 2017-09-04 03:16:19,695 attempt 1/10 2017-09-04 03:16:19,695 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/632e42dca494?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-04 03:16:20,203 unpacking tar archive at: mozilla-central-632e42dca494/testing/mozharness/ program finished with exit code 0 elapsedTime=0.906916 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-04 03:16:20.546688) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-04 03:16:20.547019) ========= script_repo_revision: 632e42dca494ec3d90b70325d9c359f80cb3f38a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-04 03:16:20.547416) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-04 03:16:20.547675) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-04 03:16:20.560212) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 58 secs) (at 2017-09-04 03:16:20.560505) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=199fb4c72b90437b990b419b000001c5-1504520177.184169-1287861291 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 03:16:20 INFO - MultiFileLogger online at 20170904 03:16:20 in /builds/slave/test 03:16:20 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 03:16:20 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:16:20 INFO - {'append_to_log': False, 03:16:20 INFO - 'base_work_dir': '/builds/slave/test', 03:16:20 INFO - 'blob_upload_branch': 'Firefox', 03:16:20 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:16:20 INFO - 'branch': 'Firefox', 03:16:20 INFO - 'buildbot_json_path': 'buildprops.json', 03:16:20 INFO - 'code_coverage': False, 03:16:20 INFO - 'config_files': ('talos/linux_config.py',), 03:16:20 INFO - 'default_actions': ('clobber', 03:16:20 INFO - 'read-buildbot-config', 03:16:20 INFO - 'download-and-extract', 03:16:20 INFO - 'populate-webroot', 03:16:20 INFO - 'create-virtualenv', 03:16:20 INFO - 'install', 03:16:20 INFO - 'setup-mitmproxy', 03:16:20 INFO - 'run-tests'), 03:16:20 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:16:20 INFO - 'disable_ccov_upload': False, 03:16:20 INFO - 'disable_stylo': False, 03:16:20 INFO - 'download_minidump_stackwalk': True, 03:16:20 INFO - 'download_symbols': 'ondemand', 03:16:20 INFO - 'enable_stylo': False, 03:16:20 INFO - 'enable_webrender': False, 03:16:20 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 03:16:20 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:16:20 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:16:20 INFO - 'gecko_profile': False, 03:16:20 INFO - 'gecko_profile_interval': 0, 03:16:20 INFO - 'installer_path': 'installer.exe', 03:16:20 INFO - 'log_level': 'info', 03:16:20 INFO - 'log_name': 'talos', 03:16:20 INFO - 'log_to_console': True, 03:16:20 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 03:16:20 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 03:16:20 INFO - 'opt_config_files': (), 03:16:20 INFO - 'pip_index': False, 03:16:20 INFO - 'suite': 'dromaeojs-e10s', 03:16:20 INFO - 'system_bits': '32', 03:16:20 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:16:20 INFO - 'title': 'talos-linux64-ix-072', 03:16:20 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:16:20 INFO - 'use_talos_json': True, 03:16:20 INFO - 'verify': 'False', 03:16:20 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:16:20 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:16:20 INFO - 'work_dir': 'build'} 03:16:20 INFO - [mozharness: 2017-09-04 10:16:20.861557Z] Running clobber step. 03:16:20 INFO - Running pre-action listener: _resource_record_pre_action 03:16:20 INFO - Running main action method: clobber 03:16:20 INFO - rmtree: /builds/slave/test/build 03:16:20 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:16:21 INFO - Running post-action listener: _resource_record_post_action 03:16:21 INFO - [mozharness: 2017-09-04 10:16:21.826390Z] Finished clobber step (success) 03:16:21 INFO - [mozharness: 2017-09-04 10:16:21.826518Z] Running read-buildbot-config step. 03:16:21 INFO - Running pre-action listener: _resource_record_pre_action 03:16:21 INFO - Running main action method: read_buildbot_config 03:16:21 INFO - Using buildbot properties: 03:16:21 INFO - { 03:16:21 INFO - "project": "", 03:16:21 INFO - "product": "firefox", 03:16:21 INFO - "who": "archaeopteryx@coole-files.de", 03:16:21 INFO - "installer_path": "public/build/target.tar.bz2", 03:16:21 INFO - "builddir": "mozilla-central_ubuntu64_hw_stylo_test-dromaeojs-e10s", 03:16:21 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 03:16:21 INFO - "buildername": "Ubuntu HW 12.04 x64 stylo mozilla-central talos dromaeojs-e10s", 03:16:21 INFO - "stage_platform": "linux64-stylo", 03:16:21 INFO - "basedir": "/builds/slave/test", 03:16:21 INFO - "buildnumber": 298, 03:16:21 INFO - "platform": "ubuntu64_hw_stylo", 03:16:21 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 03:16:21 INFO - "slavebuilddir": "test", 03:16:21 INFO - "taskId": "Ro6xKYbRTzKE8-aJG8UdWg", 03:16:21 INFO - "branch": "mozilla-central", 03:16:21 INFO - "script_repo_revision": "production", 03:16:21 INFO - "revision": "632e42dca494ec3d90b70325d9c359f80cb3f38a", 03:16:21 INFO - "slavename": "talos-linux64-ix-072", 03:16:21 INFO - "repo_path": "mozilla-central" 03:16:21 INFO - } 03:16:21 INFO - Finding installer, test and symbols from parent task. 03:16:21 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Ro6xKYbRTzKE8-aJG8UdWg'}, attempt #1 03:16:22 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Ro6xKYbRTzKE8-aJG8UdWg'}, attempt #1 03:16:23 INFO - Task dependencies: ZH0GnAa4QqyzRlNalh5SXw 03:16:23 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ZH0GnAa4QqyzRlNalh5SXw'}, attempt #1 03:16:23 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/ZH0GnAa4QqyzRlNalh5SXw/artifacts/public/build/target.tar.bz2 03:16:23 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/ZH0GnAa4QqyzRlNalh5SXw/artifacts/public/build/target.test_packages.json 03:16:23 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/ZH0GnAa4QqyzRlNalh5SXw/artifacts/public/build/target.crashreporter-symbols.zip 03:16:23 INFO - Running post-action listener: _resource_record_post_action 03:16:23 INFO - [mozharness: 2017-09-04 10:16:23.800485Z] Finished read-buildbot-config step (success) 03:16:23 INFO - [mozharness: 2017-09-04 10:16:23.800704Z] Running download-and-extract step. 03:16:23 INFO - Running pre-action listener: _resource_record_pre_action 03:16:23 INFO - Running main action method: download_and_extract 03:16:23 INFO - mkdir: /builds/slave/test/build/tests 03:16:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:16:23 INFO - trying https://queue.taskcluster.net/v1/task/ZH0GnAa4QqyzRlNalh5SXw/artifacts/public/build/target.test_packages.json 03:16:23 INFO - Downloading https://queue.taskcluster.net/v1/task/ZH0GnAa4QqyzRlNalh5SXw/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 03:16:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ZH0GnAa4QqyzRlNalh5SXw/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 03:16:24 INFO - Downloaded 989 bytes. 03:16:24 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 03:16:24 INFO - Using the following test package requirements: 03:16:24 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 03:16:24 INFO - u'common': [u'target.common.tests.zip'], 03:16:24 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 03:16:24 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 03:16:24 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 03:16:24 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 03:16:24 INFO - u'mozbase': [u'target.common.tests.zip'], 03:16:24 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 03:16:24 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 03:16:24 INFO - u'web-platform': [u'target.common.tests.zip', 03:16:24 INFO - u'target.web-platform.tests.tar.gz'], 03:16:24 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 03:16:24 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 03:16:24 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ZH0GnAa4QqyzRlNalh5SXw/artifacts/public/build/target.common.tests.zip 03:16:24 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ZH0GnAa4QqyzRlNalh5SXw/artifacts/public/build/target.common.tests.zip'}, attempt #1 03:16:24 INFO - Fetch https://queue.taskcluster.net/v1/task/ZH0GnAa4QqyzRlNalh5SXw/artifacts/public/build/target.common.tests.zip into memory 03:16:25 INFO - Content-Length response header: 55022446 03:16:25 INFO - Bytes received: 55022446 03:16:36 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 03:16:36 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ZH0GnAa4QqyzRlNalh5SXw/artifacts/public/build/target.common.tests.zip 03:16:36 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ZH0GnAa4QqyzRlNalh5SXw/artifacts/public/build/target.common.tests.zip'}, attempt #1 03:16:36 INFO - Fetch https://queue.taskcluster.net/v1/task/ZH0GnAa4QqyzRlNalh5SXw/artifacts/public/build/target.common.tests.zip into memory 03:16:37 INFO - Content-Length response header: 55022446 03:16:37 INFO - Bytes received: 55022446 03:16:49 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ZH0GnAa4QqyzRlNalh5SXw/artifacts/public/build/target.talos.tests.zip 03:16:49 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ZH0GnAa4QqyzRlNalh5SXw/artifacts/public/build/target.talos.tests.zip'}, attempt #1 03:16:49 INFO - Fetch https://queue.taskcluster.net/v1/task/ZH0GnAa4QqyzRlNalh5SXw/artifacts/public/build/target.talos.tests.zip into memory 03:16:50 INFO - Content-Length response header: 14496650 03:16:50 INFO - Bytes received: 14496650 03:16:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:16:50 INFO - trying https://queue.taskcluster.net/v1/task/ZH0GnAa4QqyzRlNalh5SXw/artifacts/public/build/target.tar.bz2 03:16:50 INFO - Downloading https://queue.taskcluster.net/v1/task/ZH0GnAa4QqyzRlNalh5SXw/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 03:16:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ZH0GnAa4QqyzRlNalh5SXw/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 03:16:51 INFO - Downloaded 62725116 bytes. 03:16:51 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ZH0GnAa4QqyzRlNalh5SXw/artifacts/public/build/target.tar.bz2 03:16:51 INFO - mkdir: /builds/slave/test/properties 03:16:51 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:16:51 INFO - Writing to file /builds/slave/test/properties/build_url 03:16:51 INFO - Contents: 03:16:51 INFO - build_url:https://queue.taskcluster.net/v1/task/ZH0GnAa4QqyzRlNalh5SXw/artifacts/public/build/target.tar.bz2 03:16:51 INFO - Running post-action listener: _resource_record_post_action 03:16:51 INFO - Running post-action listener: find_tests_for_verification 03:16:51 INFO - Running post-action listener: set_extra_try_arguments 03:16:51 INFO - [mozharness: 2017-09-04 10:16:51.661241Z] Finished download-and-extract step (success) 03:16:51 INFO - [mozharness: 2017-09-04 10:16:51.661338Z] Running populate-webroot step. 03:16:51 INFO - Running pre-action listener: _resource_record_pre_action 03:16:51 INFO - Running main action method: populate_webroot 03:16:51 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:16:51 INFO - 'chromez-stylo-e10s': {'talos_options': ['--enable-stylo'], 03:16:51 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:16:51 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:16:51 INFO - 'dromaeojs-stylo-e10s': {'talos_options': ['--enable-stylo'], 03:16:51 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:16:51 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 03:16:51 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:16:51 INFO - 'g1-stylo-e10s': {'pagesets_name': 'tp5n.zip', 03:16:51 INFO - 'talos_options': ['--enable-stylo'], 03:16:51 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:16:51 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 03:16:51 INFO - 'tests': ['damp', 'tps']}, 03:16:51 INFO - 'g2-stylo-e10s': {'pagesets_name': 'tp5n.zip', 03:16:51 INFO - 'talos_options': ['--enable-stylo'], 03:16:51 INFO - 'tests': ['damp', 'tps']}, 03:16:51 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:16:51 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 03:16:51 INFO - 'g4-stylo-e10s': {'talos_options': ['--enable-stylo'], 03:16:51 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 03:16:51 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 03:16:51 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 03:16:51 INFO - 'g5-stylo-e10s': {'pagesets_name': 'tp5n.zip', 03:16:51 INFO - 'talos_options': ['--enable-stylo'], 03:16:51 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 03:16:51 INFO - 'other-e10s': {'tests': ['a11yr', 03:16:51 INFO - 'ts_paint', 03:16:51 INFO - 'tpaint', 03:16:51 INFO - 'sessionrestore', 03:16:51 INFO - 'sessionrestore_many_windows', 03:16:51 INFO - 'sessionrestore_no_auto_restore', 03:16:51 INFO - 'tabpaint']}, 03:16:51 INFO - 'other-stylo-e10s': {'talos_options': ['--enable-stylo'], 03:16:51 INFO - 'tests': ['a11yr', 03:16:51 INFO - 'ts_paint', 03:16:51 INFO - 'tpaint', 03:16:51 INFO - 'sessionrestore', 03:16:51 INFO - 'sessionrestore_many_windows', 03:16:51 INFO - 'sessionrestore_no_auto_restore', 03:16:51 INFO - 'tabpaint']}, 03:16:51 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 03:16:51 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 03:16:51 INFO - 'perf-reftest-singletons-stylo-e10s': {'talos_options': ['--enable-stylo'], 03:16:51 INFO - 'tests': ['bloom_basic_singleton']}, 03:16:51 INFO - 'perf-reftest-stylo-e10s': {'talos_options': ['--enable-stylo'], 03:16:51 INFO - 'tests': ['bloom_basic']}, 03:16:51 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:16:51 INFO - 'tsvgr_opacity', 03:16:51 INFO - 'tart', 03:16:51 INFO - 'tscrollx', 03:16:51 INFO - 'tsvg_static']}, 03:16:51 INFO - 'svgr-stylo-e10s': {'talos_options': ['--enable-stylo'], 03:16:51 INFO - 'tests': ['tsvgx', 03:16:51 INFO - 'tsvgr_opacity', 03:16:51 INFO - 'tart', 03:16:51 INFO - 'tscrollx', 03:16:51 INFO - 'tsvg_static']}, 03:16:51 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 03:16:51 INFO - 'tp5o-stylo-e10s': {'pagesets_name': 'tp5n.zip', 03:16:51 INFO - 'talos_options': ['--enable-stylo'], 03:16:51 INFO - 'tests': ['tp5o']}, 03:16:51 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 03:16:51 INFO - 'talos_options': ['--mitmproxy', 03:16:51 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 03:16:51 INFO - '--firstNonBlankPaint'], 03:16:51 INFO - 'tests': ['tp6_google', 03:16:51 INFO - 'tp6_youtube', 03:16:51 INFO - 'tp6_amazon', 03:16:51 INFO - 'tp6_facebook']}, 03:16:51 INFO - 'tp6-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 03:16:51 INFO - 'talos_options': ['--enable-stylo', 03:16:51 INFO - '--mitmproxy', 03:16:51 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 03:16:51 INFO - '--firstNonBlankPaint'], 03:16:51 INFO - 'tests': ['tp6_google', 03:16:51 INFO - 'tp6_youtube', 03:16:51 INFO - 'tp6_amazon', 03:16:51 INFO - 'tp6_facebook']}, 03:16:51 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 03:16:51 INFO - 'talos_options': ['--enable-stylo', 03:16:51 INFO - '--stylo-threads=1', 03:16:51 INFO - '--mitmproxy', 03:16:51 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 03:16:51 INFO - '--firstNonBlankPaint'], 03:16:51 INFO - 'tests': ['tp6_google', 03:16:51 INFO - 'tp6_youtube', 03:16:51 INFO - 'tp6_amazon', 03:16:51 INFO - 'tp6_facebook']}, 03:16:51 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 03:16:51 INFO - 'talos_options': ['--xperf_path', 03:16:51 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:16:51 INFO - 'tests': ['tp5n']}, 03:16:51 INFO - 'xperf-stylo-e10s': {'pagesets_name': 'tp5n.zip', 03:16:51 INFO - 'talos_options': ['--enable-stylo', 03:16:51 INFO - '--xperf_path', 03:16:51 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:16:51 INFO - 'tests': ['tp5n']}}} 03:16:51 INFO - Running post-action listener: _resource_record_post_action 03:16:51 INFO - [mozharness: 2017-09-04 10:16:51.675705Z] Finished populate-webroot step (success) 03:16:51 INFO - [mozharness: 2017-09-04 10:16:51.675795Z] Running create-virtualenv step. 03:16:51 INFO - Running pre-action listener: _resource_record_pre_action 03:16:51 INFO - Running main action method: create_virtualenv 03:16:51 INFO - Creating virtualenv /builds/slave/test/build/venv 03:16:51 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 03:16:51 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 03:16:51 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 03:16:51 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:16:51 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:16:52 INFO - Installing distribute.............................................................................................................................................................................................done. 03:16:52 INFO - Installing pip...............done. 03:16:52 INFO - Return code: 0 03:16:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 03:16:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 03:16:52 INFO - Reading from file tmpfile_stdout 03:16:52 INFO - Output received: 03:16:52 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 03:16:52 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:16:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:16:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:16:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:16:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:16:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13251f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14eea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1691a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1695090>, '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 0x1695d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x168ff90>, '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': '199fb4c72b90437b990b419b000001c5-1504520177.184169-1287861291', '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 03:16:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 03:16:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 03:16:52 INFO - Using env: {'DISPLAY': ':0', 03:16:52 INFO - 'HOME': '/home/cltbld', 03:16:52 INFO - 'LANG': 'en_US.UTF-8', 03:16:52 INFO - 'LANGUAGE': 'en_US:en', 03:16:52 INFO - 'LOGNAME': 'cltbld', 03:16:52 INFO - 'MAIL': '/var/mail/cltbld', 03:16:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:16:52 INFO - 'MOZ_NO_REMOTE': '1', 03:16:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:16:52 INFO - 'NO_EM_RESTART': '1', 03:16:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:16:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:16:52 INFO - 'PWD': '/builds/slave/test', 03:16:52 INFO - 'SHELL': '/bin/bash', 03:16:52 INFO - 'SHLVL': '1', 03:16:52 INFO - 'TERM': 'linux', 03:16:52 INFO - 'TMOUT': '86400', 03:16:52 INFO - 'USER': 'cltbld', 03:16:52 INFO - 'XDG_SESSION_COOKIE': '199fb4c72b90437b990b419b000001c5-1504520177.184169-1287861291', 03:16:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:16:52 INFO - '_': '/tools/buildbot/bin/python'} 03:16:54 INFO - Ignoring indexes: http://pypi.python.org/simple/ 03:16:54 INFO - Downloading/unpacking pip>=1.5 03:16:54 INFO - Running setup.py egg_info for package pip 03:16:54 INFO - warning: no files found matching 'pip/cacert.pem' 03:16:54 INFO - warning: no files found matching '*.html' under directory 'docs' 03:16:54 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 03:16:54 INFO - no previously-included directories found matching 'docs/_build/_sources' 03:16:54 INFO - Installing collected packages: pip 03:16:54 INFO - Found existing installation: pip 1.1 03:16:54 INFO - Uninstalling pip: 03:16:54 INFO - Successfully uninstalled pip 03:16:54 INFO - Running setup.py install for pip 03:16:54 INFO - warning: no files found matching 'pip/cacert.pem' 03:16:54 INFO - warning: no files found matching '*.html' under directory 'docs' 03:16:54 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 03:16:54 INFO - no previously-included directories found matching 'docs/_build/_sources' 03:16:54 INFO - Installing pip script to /builds/slave/test/build/venv/bin 03:16:54 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 03:16:54 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 03:16:54 INFO - Successfully installed pip 03:16:54 INFO - Cleaning up... 03:16:54 INFO - Return code: 0 03:16:54 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:16:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:16:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:16:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:16:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:16:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13251f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14eea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1691a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1695090>, '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 0x1695d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x168ff90>, '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': '199fb4c72b90437b990b419b000001c5-1504520177.184169-1287861291', '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 03:16:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 03:16:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 03:16:54 INFO - Using env: {'DISPLAY': ':0', 03:16:54 INFO - 'HOME': '/home/cltbld', 03:16:54 INFO - 'LANG': 'en_US.UTF-8', 03:16:54 INFO - 'LANGUAGE': 'en_US:en', 03:16:54 INFO - 'LOGNAME': 'cltbld', 03:16:54 INFO - 'MAIL': '/var/mail/cltbld', 03:16:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:16:54 INFO - 'MOZ_NO_REMOTE': '1', 03:16:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:16:54 INFO - 'NO_EM_RESTART': '1', 03:16:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:16:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:16:54 INFO - 'PWD': '/builds/slave/test', 03:16:54 INFO - 'SHELL': '/bin/bash', 03:16:54 INFO - 'SHLVL': '1', 03:16:54 INFO - 'TERM': 'linux', 03:16:54 INFO - 'TMOUT': '86400', 03:16:54 INFO - 'USER': 'cltbld', 03:16:54 INFO - 'XDG_SESSION_COOKIE': '199fb4c72b90437b990b419b000001c5-1504520177.184169-1287861291', 03:16:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:16:54 INFO - '_': '/tools/buildbot/bin/python'} 03:16:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:16:55 INFO - Downloading/unpacking psutil>=3.1.1 03:16:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:16:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:16:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:16:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:16:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:16:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:16:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:16:56 INFO - Installing collected packages: psutil 03:16:56 INFO - Running setup.py install for psutil 03:16:56 INFO - building 'psutil._psutil_linux' extension 03:16:56 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 03:16:58 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 03:16:58 INFO - building 'psutil._psutil_posix' extension 03:16:58 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 03:16:59 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 03:16:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:16:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:16:59 INFO - Successfully installed psutil 03:16:59 INFO - Cleaning up... 03:16:59 INFO - Return code: 0 03:16:59 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:16: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')]} 03:16:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:16: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')]} 03:16:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:16:59 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13251f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14eea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1691a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1695090>, '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 0x1695d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x168ff90>, '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': '199fb4c72b90437b990b419b000001c5-1504520177.184169-1287861291', '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 03:16:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 03:16:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 03:16:59 INFO - Using env: {'DISPLAY': ':0', 03:16:59 INFO - 'HOME': '/home/cltbld', 03:16:59 INFO - 'LANG': 'en_US.UTF-8', 03:16:59 INFO - 'LANGUAGE': 'en_US:en', 03:16:59 INFO - 'LOGNAME': 'cltbld', 03:16:59 INFO - 'MAIL': '/var/mail/cltbld', 03:16:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:16:59 INFO - 'MOZ_NO_REMOTE': '1', 03:16:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:16:59 INFO - 'NO_EM_RESTART': '1', 03:16:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:16:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:16:59 INFO - 'PWD': '/builds/slave/test', 03:16:59 INFO - 'SHELL': '/bin/bash', 03:16:59 INFO - 'SHLVL': '1', 03:16:59 INFO - 'TERM': 'linux', 03:16:59 INFO - 'TMOUT': '86400', 03:16:59 INFO - 'USER': 'cltbld', 03:16:59 INFO - 'XDG_SESSION_COOKIE': '199fb4c72b90437b990b419b000001c5-1504520177.184169-1287861291', 03:16:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:16:59 INFO - '_': '/tools/buildbot/bin/python'} 03:16:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:16:59 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:16:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:16:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:16:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:16:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:17:00 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:17:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:17:00 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:17:00 INFO - Installing collected packages: mozsystemmonitor 03:17:00 INFO - Running setup.py install for mozsystemmonitor 03:17:00 INFO - Successfully installed mozsystemmonitor 03:17:00 INFO - Cleaning up... 03:17:00 INFO - Return code: 0 03:17:00 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:17:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:17:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:17:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:17:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:17:00 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 0x13251f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14eea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1691a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1695090>, '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 0x1695d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x168ff90>, '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': '199fb4c72b90437b990b419b000001c5-1504520177.184169-1287861291', '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 03:17:00 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 03:17:00 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 03:17:00 INFO - Using env: {'DISPLAY': ':0', 03:17:00 INFO - 'HOME': '/home/cltbld', 03:17:00 INFO - 'LANG': 'en_US.UTF-8', 03:17:00 INFO - 'LANGUAGE': 'en_US:en', 03:17:00 INFO - 'LOGNAME': 'cltbld', 03:17:00 INFO - 'MAIL': '/var/mail/cltbld', 03:17:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:17:00 INFO - 'MOZ_NO_REMOTE': '1', 03:17:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:17:00 INFO - 'NO_EM_RESTART': '1', 03:17:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:17:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:17:00 INFO - 'PWD': '/builds/slave/test', 03:17:00 INFO - 'SHELL': '/bin/bash', 03:17:00 INFO - 'SHLVL': '1', 03:17:00 INFO - 'TERM': 'linux', 03:17:00 INFO - 'TMOUT': '86400', 03:17:00 INFO - 'USER': 'cltbld', 03:17:00 INFO - 'XDG_SESSION_COOKIE': '199fb4c72b90437b990b419b000001c5-1504520177.184169-1287861291', 03:17:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:17:00 INFO - '_': '/tools/buildbot/bin/python'} 03:17:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:17:00 INFO - Downloading/unpacking jsonschema==2.5.1 03:17:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:17:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:17:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:17:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:17:02 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:17:02 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:17:02 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 03:17:02 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 03:17:02 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 03:17:02 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 03:17:02 INFO - Downloading functools32-3.2.3-2.tar.gz 03:17:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:17:02 INFO - warning: no files found matching '*.txt' 03:17:02 INFO - no previously-included directories found matching 'build' 03:17:02 INFO - no previously-included directories found matching 'dist' 03:17:02 INFO - no previously-included directories found matching '.git*' 03:17:02 INFO - Installing collected packages: jsonschema, functools32 03:17:02 INFO - Running setup.py install for functools32 03:17:02 INFO - warning: no files found matching '*.txt' 03:17:02 INFO - no previously-included directories found matching 'build' 03:17:02 INFO - no previously-included directories found matching 'dist' 03:17:02 INFO - no previously-included directories found matching '.git*' 03:17:02 INFO - Successfully installed jsonschema functools32 03:17:02 INFO - Cleaning up... 03:17:02 INFO - Return code: 0 03:17:02 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 03:17:02 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')]} 03:17:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:17:02 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')]} 03:17:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:17:02 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 0x13251f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14eea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1691a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1695090>, '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 0x1695d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x168ff90>, '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': '199fb4c72b90437b990b419b000001c5-1504520177.184169-1287861291', '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 03:17:02 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 03:17:02 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 03:17:02 INFO - Using env: {'DISPLAY': ':0', 03:17:02 INFO - 'HOME': '/home/cltbld', 03:17:02 INFO - 'LANG': 'en_US.UTF-8', 03:17:02 INFO - 'LANGUAGE': 'en_US:en', 03:17:02 INFO - 'LOGNAME': 'cltbld', 03:17:02 INFO - 'MAIL': '/var/mail/cltbld', 03:17:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:17:02 INFO - 'MOZ_NO_REMOTE': '1', 03:17:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:17:02 INFO - 'NO_EM_RESTART': '1', 03:17:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:17:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:17:02 INFO - 'PWD': '/builds/slave/test', 03:17:02 INFO - 'SHELL': '/bin/bash', 03:17:02 INFO - 'SHLVL': '1', 03:17:02 INFO - 'TERM': 'linux', 03:17:02 INFO - 'TMOUT': '86400', 03:17:02 INFO - 'USER': 'cltbld', 03:17:02 INFO - 'XDG_SESSION_COOKIE': '199fb4c72b90437b990b419b000001c5-1504520177.184169-1287861291', 03:17:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:17:02 INFO - '_': '/tools/buildbot/bin/python'} 03:17:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:17:02 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 03:17:02 INFO - Cleaning up... 03:17:02 INFO - Return code: 0 03:17:02 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:17:02 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')]} 03:17:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:17:02 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')]} 03:17:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:17:02 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 0x13251f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14eea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1691a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1695090>, '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 0x1695d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x168ff90>, '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': '199fb4c72b90437b990b419b000001c5-1504520177.184169-1287861291', '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 03:17:02 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 03:17:02 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 03:17:02 INFO - Using env: {'DISPLAY': ':0', 03:17:02 INFO - 'HOME': '/home/cltbld', 03:17:02 INFO - 'LANG': 'en_US.UTF-8', 03:17:02 INFO - 'LANGUAGE': 'en_US:en', 03:17:02 INFO - 'LOGNAME': 'cltbld', 03:17:02 INFO - 'MAIL': '/var/mail/cltbld', 03:17:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:17:02 INFO - 'MOZ_NO_REMOTE': '1', 03:17:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:17:02 INFO - 'NO_EM_RESTART': '1', 03:17:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:17:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:17:02 INFO - 'PWD': '/builds/slave/test', 03:17:02 INFO - 'SHELL': '/bin/bash', 03:17:02 INFO - 'SHLVL': '1', 03:17:02 INFO - 'TERM': 'linux', 03:17:02 INFO - 'TMOUT': '86400', 03:17:02 INFO - 'USER': 'cltbld', 03:17:02 INFO - 'XDG_SESSION_COOKIE': '199fb4c72b90437b990b419b000001c5-1504520177.184169-1287861291', 03:17:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:17:02 INFO - '_': '/tools/buildbot/bin/python'} 03:17:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:17:02 INFO - Downloading/unpacking blobuploader==1.2.4 03:17:02 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 03:17:02 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 03:17:02 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 03:17:02 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 03:17:03 INFO - Downloading blobuploader-1.2.4.tar.gz 03:17:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:17:04 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:17:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:17:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:17:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:17:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:17:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:17:04 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:17:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:17:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:17:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:17:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:17:04 INFO - Downloading docopt-0.6.1.tar.gz 03:17:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:17:04 INFO - Installing collected packages: blobuploader, requests, docopt 03:17:04 INFO - Running setup.py install for blobuploader 03:17:04 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:17:04 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:17:04 INFO - Running setup.py install for requests 03:17:05 INFO - Running setup.py install for docopt 03:17:05 INFO - Successfully installed blobuploader requests docopt 03:17:05 INFO - Cleaning up... 03:17:05 INFO - Return code: 0 03:17:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:17:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:17:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:17:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:17:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:17:05 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 0x13251f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14eea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1691a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1695090>, '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 0x1695d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x168ff90>, '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': '199fb4c72b90437b990b419b000001c5-1504520177.184169-1287861291', '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 03:17:05 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 03:17:05 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 03:17:05 INFO - Using env: {'DISPLAY': ':0', 03:17:05 INFO - 'HOME': '/home/cltbld', 03:17:05 INFO - 'LANG': 'en_US.UTF-8', 03:17:05 INFO - 'LANGUAGE': 'en_US:en', 03:17:05 INFO - 'LOGNAME': 'cltbld', 03:17:05 INFO - 'MAIL': '/var/mail/cltbld', 03:17:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:17:05 INFO - 'MOZ_NO_REMOTE': '1', 03:17:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:17:05 INFO - 'NO_EM_RESTART': '1', 03:17:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:17:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:17:05 INFO - 'PWD': '/builds/slave/test', 03:17:05 INFO - 'SHELL': '/bin/bash', 03:17:05 INFO - 'SHLVL': '1', 03:17:05 INFO - 'TERM': 'linux', 03:17:05 INFO - 'TMOUT': '86400', 03:17:05 INFO - 'USER': 'cltbld', 03:17:05 INFO - 'XDG_SESSION_COOKIE': '199fb4c72b90437b990b419b000001c5-1504520177.184169-1287861291', 03:17:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:17:05 INFO - '_': '/tools/buildbot/bin/python'} 03:17:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:17:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:17:05 INFO - Running setup.py (path:/tmp/pip-krg4Ef-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:17:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:17:05 INFO - Running setup.py (path:/tmp/pip-kszTru-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:17:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:17:05 INFO - Running setup.py (path:/tmp/pip-n0PX_Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:17:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:17:05 INFO - Running setup.py (path:/tmp/pip-jbQqip-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:17:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:17:05 INFO - Running setup.py (path:/tmp/pip-U2aIX_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:17:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:17:05 INFO - Running setup.py (path:/tmp/pip-l0bxpI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:17:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:17:05 INFO - Running setup.py (path:/tmp/pip-KcQqXB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:17:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:17:06 INFO - Running setup.py (path:/tmp/pip-gMWaFz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:17:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:17:06 INFO - Running setup.py (path:/tmp/pip-2HKAmx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:17:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:17:06 INFO - Running setup.py (path:/tmp/pip-m_b7Uy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:17:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:17:06 INFO - Running setup.py (path:/tmp/pip-4pSxro-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:17:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:17:06 INFO - Running setup.py (path:/tmp/pip-a8KMgN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:17:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:17:06 INFO - Running setup.py (path:/tmp/pip-vPersa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:17:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:17:06 INFO - Running setup.py (path:/tmp/pip-Cx6Rla-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:17:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:17:06 INFO - Running setup.py (path:/tmp/pip-ub9va7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:17:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:17:06 INFO - Running setup.py (path:/tmp/pip-UC8ylw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:17:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:17:06 INFO - Running setup.py (path:/tmp/pip-ajT3tf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:17:06 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:17:06 INFO - Running setup.py install for manifestparser 03:17:06 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:17:06 INFO - Running setup.py install for mozcrash 03:17:06 INFO - Running setup.py install for mozdebug 03:17:07 INFO - Running setup.py install for mozdevice 03:17:07 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:17:07 INFO - Running setup.py install for mozfile 03:17:07 INFO - Running setup.py install for mozhttpd 03:17:07 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:17:07 INFO - Running setup.py install for mozinfo 03:17:07 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:17:07 INFO - Running setup.py install for mozInstall 03:17:07 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:17:07 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:17:07 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:17:07 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:17:07 INFO - Running setup.py install for mozleak 03:17:07 INFO - Running setup.py install for mozlog 03:17:07 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:17:07 INFO - Running setup.py install for moznetwork 03:17:07 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:17:07 INFO - Running setup.py install for mozprocess 03:17:07 INFO - Running setup.py install for mozprofile 03:17:08 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:17:08 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:17:08 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:17:08 INFO - Running setup.py install for mozrunner 03:17:08 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:17:08 INFO - Running setup.py install for mozscreenshot 03:17:08 INFO - Running setup.py install for moztest 03:17:08 INFO - Running setup.py install for mozversion 03:17:08 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:17:08 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:17:08 INFO - Cleaning up... 03:17:08 INFO - Return code: 0 03:17:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:17:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:17:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:17:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:17:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:17:08 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 0x13251f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14eea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1691a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1695090>, '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 0x1695d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x168ff90>, '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': '199fb4c72b90437b990b419b000001c5-1504520177.184169-1287861291', '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 03:17:08 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 03:17:08 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 03:17:08 INFO - Using env: {'DISPLAY': ':0', 03:17:08 INFO - 'HOME': '/home/cltbld', 03:17:08 INFO - 'LANG': 'en_US.UTF-8', 03:17:08 INFO - 'LANGUAGE': 'en_US:en', 03:17:08 INFO - 'LOGNAME': 'cltbld', 03:17:08 INFO - 'MAIL': '/var/mail/cltbld', 03:17:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:17:08 INFO - 'MOZ_NO_REMOTE': '1', 03:17:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:17:08 INFO - 'NO_EM_RESTART': '1', 03:17:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:17:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:17:08 INFO - 'PWD': '/builds/slave/test', 03:17:08 INFO - 'SHELL': '/bin/bash', 03:17:08 INFO - 'SHLVL': '1', 03:17:08 INFO - 'TERM': 'linux', 03:17:08 INFO - 'TMOUT': '86400', 03:17:08 INFO - 'USER': 'cltbld', 03:17:08 INFO - 'XDG_SESSION_COOKIE': '199fb4c72b90437b990b419b000001c5-1504520177.184169-1287861291', 03:17:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:17:08 INFO - '_': '/tools/buildbot/bin/python'} 03:17:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:17:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:17:08 INFO - Running setup.py (path:/tmp/pip-6fehjX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:17:08 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)) 03:17:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:17:08 INFO - Running setup.py (path:/tmp/pip-fT7stM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:17:08 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)) 03:17:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:17:08 INFO - Running setup.py (path:/tmp/pip-70H2ex-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:17:08 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)) 03:17:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:17:08 INFO - Running setup.py (path:/tmp/pip-WnFG1g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:17:09 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)) 03:17:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:17:09 INFO - Running setup.py (path:/tmp/pip-tJgo8T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:17:09 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)) 03:17:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:17:09 INFO - Running setup.py (path:/tmp/pip-hCi6ws-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:17:09 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)) 03:17:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:17:09 INFO - Running setup.py (path:/tmp/pip-1wHkCa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:17:09 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)) 03:17:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:17:09 INFO - Running setup.py (path:/tmp/pip-0PMJ7X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:17:09 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)) 03:17:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:17:09 INFO - Running setup.py (path:/tmp/pip-k8wmHa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:17:09 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)) 03:17:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:17:09 INFO - Running setup.py (path:/tmp/pip-9vyIIx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:17:09 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)) 03:17:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:17:09 INFO - Running setup.py (path:/tmp/pip-UmCRIB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:17:09 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)) 03:17:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:17:09 INFO - Running setup.py (path:/tmp/pip-B_7UUN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:17:09 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)) 03:17:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:17:09 INFO - Running setup.py (path:/tmp/pip-G9cjr3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:17:09 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)) 03:17:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:17:09 INFO - Running setup.py (path:/tmp/pip-5lxhOw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:17:09 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)) 03:17:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:17:09 INFO - Running setup.py (path:/tmp/pip-xjszBs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:17:09 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)) 03:17:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:17:09 INFO - Running setup.py (path:/tmp/pip-vXtY6A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:17:09 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)) 03:17:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:17:09 INFO - Running setup.py (path:/tmp/pip-afC_Oh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:17:09 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)) 03:17:09 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)) 03:17:09 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)) 03:17:09 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)) 03:17:09 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)) 03:17:09 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)) 03:17:09 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)) 03:17:09 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:17:09 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 03:17:09 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 03:17:09 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 03:17:09 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 03:17:11 INFO - Downloading blessings-1.6.tar.gz 03:17:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:17:11 INFO - Installing collected packages: blessings 03:17:11 INFO - Running setup.py install for blessings 03:17:11 INFO - Successfully installed blessings 03:17:11 INFO - Cleaning up... 03:17:11 INFO - Return code: 0 03:17:11 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:17:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:17:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:17:11 INFO - Reading from file tmpfile_stdout 03:17:11 INFO - Current package versions: 03:17:11 INFO - argparse == 1.2.1 03:17:11 INFO - blessings == 1.6 03:17:11 INFO - blobuploader == 1.2.4 03:17:11 INFO - docopt == 0.6.1 03:17:11 INFO - functools32 == 3.2.3-2 03:17:11 INFO - jsonschema == 2.5.1 03:17:11 INFO - manifestparser == 1.1 03:17:11 INFO - mozInstall == 1.13 03:17:11 INFO - mozcrash == 1.0 03:17:11 INFO - mozdebug == 0.1 03:17:11 INFO - mozdevice == 0.51 03:17:11 INFO - mozfile == 1.2 03:17:11 INFO - mozhttpd == 0.7 03:17:11 INFO - mozinfo == 0.10 03:17:11 INFO - mozleak == 0.1 03:17:11 INFO - mozlog == 3.5 03:17:11 INFO - moznetwork == 0.27 03:17:11 INFO - mozprocess == 0.25 03:17:11 INFO - mozprofile == 0.28 03:17:11 INFO - mozrunner == 6.13 03:17:11 INFO - mozscreenshot == 0.1 03:17:11 INFO - mozsystemmonitor == 0.3 03:17:11 INFO - moztest == 0.8 03:17:11 INFO - mozversion == 1.4 03:17:11 INFO - psutil == 3.1.1 03:17:11 INFO - requests == 1.2.3 03:17:11 INFO - wsgiref == 0.1.2 03:17:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:17:11 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')]} 03:17:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:17:11 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')]} 03:17:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:17:11 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 0x13251f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14eea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1691a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1695090>, '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 0x1695d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x168ff90>, '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': '199fb4c72b90437b990b419b000001c5-1504520177.184169-1287861291', '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 03:17:11 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 03:17:11 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 03:17:11 INFO - Using env: {'DISPLAY': ':0', 03:17:11 INFO - 'HOME': '/home/cltbld', 03:17:11 INFO - 'LANG': 'en_US.UTF-8', 03:17:11 INFO - 'LANGUAGE': 'en_US:en', 03:17:11 INFO - 'LOGNAME': 'cltbld', 03:17:11 INFO - 'MAIL': '/var/mail/cltbld', 03:17:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:17:11 INFO - 'MOZ_NO_REMOTE': '1', 03:17:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:17:11 INFO - 'NO_EM_RESTART': '1', 03:17:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:17:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:17:11 INFO - 'PWD': '/builds/slave/test', 03:17:11 INFO - 'SHELL': '/bin/bash', 03:17:11 INFO - 'SHLVL': '1', 03:17:11 INFO - 'TERM': 'linux', 03:17:11 INFO - 'TMOUT': '86400', 03:17:11 INFO - 'USER': 'cltbld', 03:17:11 INFO - 'XDG_SESSION_COOKIE': '199fb4c72b90437b990b419b000001c5-1504520177.184169-1287861291', 03:17:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:17:11 INFO - '_': '/tools/buildbot/bin/python'} 03:17:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:17:11 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)) 03:17:11 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)) 03:17:11 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)) 03:17:11 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)) 03:17:11 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)) 03:17:11 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)) 03:17:11 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)) 03:17:11 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)) 03:17:11 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)) 03:17:11 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 03:17:11 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 03:17:11 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 03:17:11 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 03:17:11 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 03:17:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 03:17:12 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)) 03:17:12 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)) 03:17:12 INFO - Installing collected packages: simplejson 03:17:12 INFO - Running setup.py install for simplejson 03:17:12 INFO - building 'simplejson._speedups' extension 03:17:12 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 03:17:13 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 03:17:13 INFO - Successfully installed simplejson 03:17:13 INFO - Cleaning up... 03:17:13 INFO - Return code: 0 03:17:13 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:17:13 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')]} 03:17:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:17:13 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')]} 03:17:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:17:13 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 0x13251f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14eea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1691a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1695090>, '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 0x1695d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x168ff90>, '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': '199fb4c72b90437b990b419b000001c5-1504520177.184169-1287861291', '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 03:17:13 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 03:17:13 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 03:17:13 INFO - Using env: {'DISPLAY': ':0', 03:17:13 INFO - 'HOME': '/home/cltbld', 03:17:13 INFO - 'LANG': 'en_US.UTF-8', 03:17:13 INFO - 'LANGUAGE': 'en_US:en', 03:17:13 INFO - 'LOGNAME': 'cltbld', 03:17:13 INFO - 'MAIL': '/var/mail/cltbld', 03:17:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:17:13 INFO - 'MOZ_NO_REMOTE': '1', 03:17:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:17:13 INFO - 'NO_EM_RESTART': '1', 03:17:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:17:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:17:13 INFO - 'PWD': '/builds/slave/test', 03:17:13 INFO - 'SHELL': '/bin/bash', 03:17:13 INFO - 'SHLVL': '1', 03:17:13 INFO - 'TERM': 'linux', 03:17:13 INFO - 'TMOUT': '86400', 03:17:13 INFO - 'USER': 'cltbld', 03:17:13 INFO - 'XDG_SESSION_COOKIE': '199fb4c72b90437b990b419b000001c5-1504520177.184169-1287861291', 03:17:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:17:13 INFO - '_': '/tools/buildbot/bin/python'} 03:17:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:17:14 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:17:14 INFO - Cleaning up... 03:17:14 INFO - Return code: 0 03:17:14 INFO - Running post-action listener: _resource_record_post_action 03:17:14 INFO - Running post-action listener: _start_resource_monitoring 03:17:14 INFO - Starting resource monitoring. 03:17:14 INFO - [mozharness: 2017-09-04 10:17:14.241887Z] Finished create-virtualenv step (success) 03:17:14 INFO - [mozharness: 2017-09-04 10:17:14.242831Z] Running install step. 03:17:14 INFO - Running pre-action listener: _resource_record_pre_action 03:17:14 INFO - Running main action method: install 03:17:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:17:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:17:14 INFO - Reading from file tmpfile_stdout 03:17:14 INFO - Detecting whether we're running mozinstall >=1.0... 03:17:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:17:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:17:14 INFO - Reading from file tmpfile_stdout 03:17:14 INFO - Output received: 03:17:14 INFO - Usage: mozinstall [options] installer 03:17:14 INFO - Options: 03:17:14 INFO - -h, --help show this help message and exit 03:17:14 INFO - -d DEST, --destination=DEST 03:17:14 INFO - Directory to install application into. [default: 03:17:14 INFO - "/builds/slave/test"] 03:17:14 INFO - --app=APP Application being installed. [default: firefox] 03:17:14 INFO - mkdir: /builds/slave/test/build/application 03:17:14 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'] 03:17:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 03:17:32 INFO - Reading from file tmpfile_stdout 03:17:32 INFO - Output received: 03:17:32 INFO - /builds/slave/test/build/application/firefox/firefox 03:17:32 INFO - Running post-action listener: _resource_record_post_action 03:17:32 INFO - [mozharness: 2017-09-04 10:17:32.049440Z] Finished install step (success) 03:17:32 INFO - [mozharness: 2017-09-04 10:17:32.049689Z] Running setup-mitmproxy step. 03:17:32 INFO - Running pre-action listener: _resource_record_pre_action 03:17:32 INFO - Running main action method: setup_mitmproxy 03:17:32 INFO - Skipping: mitmproxy is not required 03:17:32 INFO - Running post-action listener: _resource_record_post_action 03:17:32 INFO - [mozharness: 2017-09-04 10:17:32.050801Z] Finished setup-mitmproxy step (success) 03:17:32 INFO - [mozharness: 2017-09-04 10:17:32.051018Z] Running run-tests step. 03:17:32 INFO - Running pre-action listener: _resource_record_pre_action 03:17:32 INFO - Running pre-action listener: _set_gcov_prefix 03:17:32 INFO - Running main action method: run_tests 03:17:32 WARNING - Try message not found. 03:17:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:17:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:17:32 INFO - Python 2.7.3 03:17:32 INFO - Return code: 0 03:17:32 INFO - grabbing minidump binary from tooltool 03:17:32 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')]} 03:17:32 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 0x1695090>, '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 0x1695d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x168ff90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:17:32 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 03:17:32 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 03:17:32 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 03:17:32 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:17:32 INFO - Return code: 0 03:17:32 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 03:17:32 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:17:32 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:17:32 INFO - ENV: RUST_BACKTRACE is now full 03:17:32 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 03:17:32 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:17:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ZH0GnAa4QqyzRlNalh5SXw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-072', '--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 03:17:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/ZH0GnAa4QqyzRlNalh5SXw/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-072 --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 03:17:32 INFO - Using env: {'DISPLAY': ':0', 03:17:32 INFO - 'HOME': '/home/cltbld', 03:17:32 INFO - 'LANG': 'en_US.UTF-8', 03:17:32 INFO - 'LANGUAGE': 'en_US:en', 03:17:32 INFO - 'LOGNAME': 'cltbld', 03:17:32 INFO - 'MAIL': '/var/mail/cltbld', 03:17:32 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:17:32 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:17:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:17:32 INFO - 'MOZ_NO_REMOTE': '1', 03:17:32 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:17:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:17:32 INFO - 'NO_EM_RESTART': '1', 03:17:32 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:17:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:17:32 INFO - 'PWD': '/builds/slave/test', 03:17:32 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:17:32 INFO - 'RUST_BACKTRACE': 'full', 03:17:32 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 03:17:32 INFO - 'SHELL': '/bin/bash', 03:17:32 INFO - 'SHLVL': '1', 03:17:32 INFO - 'STYLO_FORCE_ENABLED': '1', 03:17:32 INFO - 'TERM': 'linux', 03:17:32 INFO - 'TMOUT': '86400', 03:17:32 INFO - 'USER': 'cltbld', 03:17:32 INFO - 'XDG_SESSION_COOKIE': '199fb4c72b90437b990b419b000001c5-1504520177.184169-1287861291', 03:17:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:17:32 INFO - '_': '/tools/buildbot/bin/python'} 03:17:32 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ZH0GnAa4QqyzRlNalh5SXw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-072', '--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 03:17:32 INFO - mozversion application_buildid: 20170904091258 03:17:32 INFO - mozversion application_changeset: 632e42dca494ec3d90b70325d9c359f80cb3f38a 03:17:32 INFO - mozversion application_display_name: Nightly 03:17:32 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:17:32 INFO - mozversion application_name: Firefox 03:17:32 INFO - mozversion application_remotingname: firefox 03:17:32 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 03:17:32 INFO - mozversion application_vendor: Mozilla 03:17:32 INFO - mozversion application_version: 57.0a1 03:17:32 INFO - mozversion platform_buildid: 20170904091258 03:17:32 INFO - mozversion platform_changeset: 632e42dca494ec3d90b70325d9c359f80cb3f38a 03:17:32 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 03:17:32 INFO - mozversion platform_version: 57.0a1 03:17:32 INFO - using testdate: 1504520252 03:17:32 INFO - actual date: 1504520252 03:17:32 INFO - starting webserver on 'localhost:55912' 03:17:32 INFO - SUITE-START | Running 2 tests 03:17:32 INFO - TEST-START | dromaeo_css 03:17:32 INFO - Initialising browser for dromaeo_css test... 03:17:32 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:55912/getInfo.html -profile /tmp/tmpEMQ9fZ/profile 03:17:32 INFO - TEST-INFO | started process 3523 (/builds/slave/test/build/application/firefox/firefox http://localhost:55912/getInfo.html) 03:17:40 INFO - TEST-INFO | 3523: exit 0 03:17:40 INFO - Browser initialized. 03:17:40 INFO - Running cycle 1/1 for dromaeo_css test... 03:17:40 INFO - Using env: {'DISPLAY': ':0', 03:17:40 INFO - 'HOME': '/home/cltbld', 03:17:40 INFO - 'JSGC_DISABLE_POISONING': '1', 03:17:40 INFO - 'LANG': 'en_US.UTF-8', 03:17:40 INFO - 'LANGUAGE': 'en_US:en', 03:17:40 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 03:17:40 INFO - 'LOGNAME': 'cltbld', 03:17:40 INFO - 'MAIL': '/var/mail/cltbld', 03:17:40 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:17:40 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:17:40 INFO - 'MOZ_CRASHREPORTER': '1', 03:17:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:17:40 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 03:17:40 INFO - 'MOZ_NO_REMOTE': '1', 03:17:40 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:17:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:17:40 INFO - 'NO_EM_RESTART': '1', 03:17:40 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:17:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:17:40 INFO - 'PWD': '/builds/slave/test', 03:17:40 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:17:40 INFO - 'RUST_BACKTRACE': 'full', 03:17:40 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 03:17:40 INFO - 'SHELL': '/bin/bash', 03:17:40 INFO - 'SHLVL': '1', 03:17:40 INFO - 'STYLO_FORCE_ENABLED': '1', 03:17:40 INFO - 'TERM': 'linux', 03:17:40 INFO - 'TMOUT': '86400', 03:17:40 INFO - 'USER': 'cltbld', 03:17:40 INFO - 'XDG_SESSION_COOKIE': '199fb4c72b90437b990b419b000001c5-1504520177.184169-1287861291', 03:17:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:17:40 INFO - '_': '/tools/buildbot/bin/python'} 03:17:40 INFO - TEST-INFO | started process 3724 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpEMQ9fZ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 03:17:41 INFO - PID 3724 | 03:17:41 INFO - PID 3724 | (/builds/slave/test/build/application/firefox/firefox:3776): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 03:17:41 INFO - PID 3724 | 03:17:51 INFO - PID 3724 | RSS: Main: 170610688 03:17:51 INFO - PID 3724 | 03:20:50 INFO - PID 3724 | Cycle 1(1): loaded http://localhost:55912/tests/dromaeo/cssquery-dojo.html (next: http://localhost:55912/tests/dromaeo/cssquery-ext.html) 03:20:51 INFO - PID 3724 | RSS: Main: 173887488 03:20:51 INFO - PID 3724 | 03:23:53 INFO - PID 3724 | Cycle 1(1): loaded http://localhost:55912/tests/dromaeo/cssquery-ext.html (next: http://localhost:55912/tests/dromaeo/cssquery-jquery.html) 03:23:54 INFO - PID 3724 | RSS: Main: 170729472 03:23:54 INFO - PID 3724 | 03:26:55 INFO - PID 3724 | Cycle 1(1): loaded http://localhost:55912/tests/dromaeo/cssquery-jquery.html (next: http://localhost:55912/tests/dromaeo/cssquery-mootools.html) 03:26:56 INFO - PID 3724 | RSS: Main: 172875776 03:26:56 INFO - PID 3724 | 03:29:58 INFO - PID 3724 | Cycle 1(1): loaded http://localhost:55912/tests/dromaeo/cssquery-mootools.html (next: http://localhost:55912/tests/dromaeo/cssquery-prototype.html) 03:29:58 INFO - PID 3724 | RSS: Main: 171446272 03:29:58 INFO - PID 3724 | 03:33:02 INFO - PID 3724 | Cycle 1(1): loaded http://localhost:55912/tests/dromaeo/cssquery-prototype.html (next: http://localhost:55912/tests/dromaeo/cssquery-yui.html) 03:33:02 INFO - PID 3724 | RSS: Main: 173338624 03:33:02 INFO - PID 3724 | 03:36:04 INFO - PID 3724 | Cycle 1(1): loaded http://localhost:55912/tests/dromaeo/cssquery-yui.html (next: http://localhost:55912/tests/dromaeo/cssquery-dojo.html) 03:36:04 INFO - PID 3724 | RSS: Main: 173715456 03:36:04 INFO - PID 3724 | 03:36:04 INFO - PID 3724 | __start_tp_report 03:36:04 INFO - PID 3724 | _x_x_mozilla_page_load 03:36:04 INFO - PID 3724 | _x_x_mozilla_page_load_details 03:36:04 INFO - PID 3724 | |i|pagename|runs| 03:36:04 INFO - PID 3724 | |0;dojo.html;3332.2169290216793;3362.8654853805847;3403.115190050587;3426.4688973209163;3432.2963792422556;6445.03324501325;6476.805695829127;6479.384458476443;6499.870002599945;6569.178852643419;6314.273858506273;6384.457321127709;6394.808155755335;6450.161479007723;6665.600063996164;5932.911006334908;6032.27612686476;6038.6074905131245;6039.245094363205;6095.573309868311;949.8761402514956;5939.643621382709;5969.88060238795;6047.697615119248;6136.723847426854;5989.700514974277;6000.009998350267;6072.423119803611;6072.51419886408;6088;5967.970160149215;5993.160957465958;6071.726772295213;6088.330283668793;6095.268567771852;5963.582549221556;5982.611130276561;6025.457708806184;6052.12244224585;6055.212822333113;6509.8047058587845;6544.476441884685;6554.344565543455;6593.307702691196;6599.47204223661;6799.456043516507;6903.240643529208;6904.930950690479;6971.163460384735;7078.893816592756;6901.827454313632;6972.860542789166;6979.965100174467;6988.755393561201;7000.684969176399;6396.456301214403;6412.102305677209;6425.421712045938;6437.324080971524;6454.580452270634;7083.7520686775715;7085;7095.89356159658;7177;7259.41924646027;6864.24493305736;6931.168259808755;6936.826579335557;6956.65216739161;6975.267596902354;7001.894971575431;7022.719091236396;7043.788686339418;7113.608751518613;7134.571925684476;6764;6792;6944.4444444445335;6954.095967524189;7059.2940705928995;6933.54931929433;6978.337057979484;6995.825104372431;7000.3349681781165;7021.648917554204;5351.598630102758;5398.082326004589;5423.755930983037;5441.401445840955;5454.945450545523;4695.9530404695515;4722.905541889143;4749.3588365570895;4764.833230836974;4788.066327066189;5949.940500595025;6002.969985150134;6004.1294012368835;6061.030235162353;6080.361562036011;6982.546134501241;7003.5797852129035;7026.086608740933;7080.539764915264;7082.3979961703835;6002.159697642333;6066.090086487062;6154.353792851775;6154.446099851035;6161.168242287234;5764.279465066867;5832.562557808182;5870.090136028923;5925.288965324103;5940.70296485174;6782.728690852409;6790.864182716318;6792.422644075309;6801.115854938926;6809.285025072395;6874.072000279898;7014.403775679124;7034.331738484836;7046.718131274692;7060.752873649398;6717.731290748511;6727.495437842083;6736.225334086643;6816.420604248694;6833.043373927753;6619.172603424572;6776.457883369417;6793.354631310018;6881.208661004049;6886.586804791728;6939.687714052779;6953.826154345979;6955.513114081966;7016.298370163147;7078.8938165928585;6742.393184613408;6822.215445223863;6835.658217089225;6836.248012718696;6850.828729281808;6668.699908504032;6730.596164229966;6732.3940845322195;6822.249552549315;6827.249002609609;6494.740210391531;6531.216254049545;6585.604863708193;6606.6036037837885;6619.966900165468;6696.263411024686;6813.557118787263;6843.041974123527;6845;6873.140857392826;6586.50601204902;6629.204495460575;6685.164354455693;6696.966515167393;6715.1941766988275 03:36:04 INFO - PID 3724 | |1;ext.html;718.737660753825;766.195494730533;789.029493722721;1146.4840821630269;1174.9060075193986;29817.552736708938;40425.79787101064;41014.7949260254;41100.38349424754;41233.38149927754;41506.16987660245;42040.57959420405;42110.789446052804;42114;42126.36810447838;7468.402527797791;7620.123685776151;7637.121731000922;7652.540847549165;7658.617069146549;7577.621118944031;7690;7711.807204819868;7712.074551053854;7722.961385193066;7170.498065135443;7709.961450192769;7726.072871255457;7802.063752349698;7815.062192536903;7715.459917805756;7768.53388796674;7769;7776.688932442696;7777.72777952769;7584.051993500812;7665.961670191669;7676.846463070763;7677.500962437423;7681.57751323677;36767.81616091929;37247;37704.43443348352;37719;37728.24543509142;3517.120719820045;3584.354816133095;3596.1728802375337;3597.748157628967;3599.478075679012;30517.847410762803;30654.846725766227;30769.538456923165;30817.229569260722;30851.22871928197;5124.692518448867;5156.432792392834;5177.326947496801;5196.948030519684;5202.375714914197;185059;185074;187610;188114;188935;47362;47526.5247347529;47558.5244147561;47637.523624764;47801.69784719392;49194.75402622964;49455;49502.50497495051;49594.25608615873;49686.75156624194;15101.622459438602;15346.923265383824;15405.537833865003;15416.075035497906;15424.151671657943;12600.495980160875;12621.621351359474;12628.621141365773;12650.746985060247;12654.493820247271;13840;13923.30383480822;14030.438782448588;14062.648433789032;14181.503647372292;4927.113119638428;5916.112583112482;6055.091736239599;6063.8484037898525;6092.3603021682975;1000.9299349045642;1001.639409812467;1025.6974192613197;1026.861373714554;1030.7989941961246;22065.448363791034;22514.21200258016;22779;23103;23194.8840255801;1070.288258308232;1072.3297938788257;1086.9293495922739;1100.312304809494;1100.7303210713426;11973.700657483458;12075.637730868088;12202.267863928193;12272.32502212369;12272.509099635914;236916;239124;239649;240144;240342;50268.24597631038;50295.24557131646;50522.74738626357;50657;51041.48958510441;1446.6094154578416;1463.6340914771308;1469.9044562103643;1471.4923351443736;1484.161448781435;21690.24084157047;21961.780382195975;22364.552708946056;22604.886975565674;22606.09575616957;36217.27565448729;36219.818900905324;36285.81857090697;36326.636733632324;36342.09144771402;199309;200237;216654;220540;220667;36774;36986.260274793814;37061.25877482489;37123.25753484969;37187.81406092952;40263.19473610453;44301.33547996847;44802;44843;45373.54626453693;33118.834405827816;33413.331733364714;33425.83287083549;33454.83272583621;33759.66240337565;33187.17032074121;33325.50011749874;33432.83283583664;33698;33813;37159;37385.626143739304;37801;37896;37982.43026354552;32192.83903580467;32781.672183277864;33001.50497742488;33324;33435.66564334326;23740.169094081622;24791.380215494755;24882.875585621958;25139.371515712253;25531.617025745 03:36:04 INFO - PID 3724 | |2;jquery.html;577.5754820207144;577.6216578141316;581.148617275691;582.9883402331956;589.7287247865981;21835.563288734214;22418;22452;22621.773782262193;22623.208187713433;23623.291301260946;25416.872915635395;25547;25564.105256316034;25620;6591.110200122982;6723.697433615474;6732.158480189976;6736.730530778763;6737.831554211135;5926.170336152942;6417.6149431034055;6436;6576;6622;6407.295197528269;6418.422341989243;6424.325445828191;6432.903506447407;6437.356264373549;6422.068800024017;6423.64669943153;6429.646369449679;6431.099646049557;6431.196100487439;5976.9540330442;5982.700864956778;5983.910241346383;5987.850303742398;6004.789832355847;28624.284392890135;29292.267693307836;29736.70263297361;29772.70227297721;30133.246668833453;2030.472077259923;2069.627467055929;2108.515041540439;2108.599366120432;2108.768035516092;19464.318748843863;19961.301354452524;19967.101480433415;19979.50051248716;20031.79968200314;2358.364413496641;2411.626197939322;2440.1703420837002;2455.791257743094;2475.7276699563035;140113.2994335022;142243;144025;144087;144321;13438.865611343957;14235.793452014954;14312.57062288133;14372.35324410404;14413.207273600052;20837.79162208389;21277.148914043264;21317.68023479649;21741.565168696852;21765.891170544044;10081.848772268422;10132.24008199388;10193.592256309812;10207.438590877573;10245.180385569136;9341.392809467363;9376.5311734413;9387.765305867408;9501.809963800686;9553.14021738033;9124.81377420931;9321.254299656011;9374.765630859283;9498.90501094994;9508.952455237817;2742.0951086141527;2769.2938300733585;2773.154187972688;2798.8180768250404;2861.8855245789937;968.970930872075;970.2044323654677;972.3679608254691;997.8852431970271;1006.8389057750725;20246.69629955552;20360.89819550893;20379.388618341472;20442.08010639525;20803;1047.4602334568171;1055.298226679265;1062.458146345354;1064.627380416848;1065.9680209593726;11262.211645184758;11387.14596405273;11451.713707157387;11520.308781473139;11566.310785302618;434722;435664;437628;441664.9583760432;447145;110592;111938.44030779794;111997;112517.43741281405;113925.43037284927;1205.7166565857017;1208.250884451628;1227.2575092069349;1230.2556953043459;1236.2520674991501;17803.46589602314;17817.376391826223;17989.10054497296;18001;18048.45854624311;47543;50787.74606127093;55325.44674553203;57052;57592.71203643955;66034;66398.6680066616;66615.33384666091;66812.66593667;67038;13772.862271377158;13953.232572208626;13968;13988.300584970508;14004.579862604138;22991.6551251728;23096.653550197098;23114.075436983007;23126.190583329502;23243;18408.907955460138;18496.44510664682;18674.34639787601;18749.250029998646;18915.90542047281;15604.609884752972;16424.17879106016;16924;17291.91354043222;17508.387206447715;18916.432507024812;19139.712904306725;19156.904215478833;19161.52096197606;19235.90382048081;13816.654583635489;13890.236037018061;13895.652608684864;13898.652533686334;13929;18224;18288;18327.35854245095;18391.264349425874;18508.81491185071 03:36:04 INFO - PID 3724 | |3;mootools.html;1318.775808112621;1377.765779817431;1384.294010054872;1387.3548799808086;1388.2850332078974;14731.92634036831;15214.467493637725;15342.232888355566;15451.150186739726;15537.223138843055;15847.207639618002;16142.112183829884;16145.757813632805;16166.75749863753;16184.75722864155;2596.5845464725694;2646.907358242462;2653.4825708986755;2654.110872857595;2655.309619498925;2735.521283775341;2849.244950088228;2916.9854150729216;2917.7228163324557;2918.0953904289736;2504.7119581248207;2531.594944808831;2558.6801649793774;2558.7825034871967;2560.46230291639;2795.412963277714;2799.1322689966073;2808.9719102808913;2816.310004049011;2818.422223444199;2621.082621082606;2630.1057640402164;2630.658014458108;2630.8289961152464;2632;15096.849031509653;15426.537203883901;15437.691246175125;15445.613859653486;15448.304826282732;8155;8466.78833029173;8467.068622451525;8467.195616416493;8474.364422668325;5343.064963631349;5471.206675032098;5633.042382794934;5649.49154576086;5660.518855897212;1874.996876670988;1907.12272354717;1919.395390452003;1964.5383334916282;1967.9508012299664;26802.329941751417;26908.327291817863;26937.191884243504;26995.190144295506;27006.324841878915;6684.364985326483;6921.238663746984;6925.653717314114;6972.860542789116;7131.857362852714;16128;16132.354705811636;16170.59573510648;16195.838041619669;16247.268872900748;8130.065042520067;8135.308498777552;8144.633491492897;8145.755627331189;8159.428839981263;6559.770408035792;7404.74083407089;7515.511491753018;7614.657340419694;7622.047244094489;4884.169691152512;5350.545203657733;5383.138697808332;5488.341399032141;5494.148406996922;1586.698527279813;1586.88098392621;1589.6900104479516;1593.8645215156841;1596.7445208766542;897.3942588752566;920;921.6589861751195;922.6770630279483;929.5956259027345;8603.698870539502;8709.390342675952;8784.648614055493;8800.779980500538;8859.981102173204;981.7938232971013;985.8077674853478;986.3835103060587;989.7921436498272;995.7162208770466;4565.885852853705;4571.382863313476;5351.946480535223;5432.049391356497;5446.210299506629;21431.357059288246;22406;22552.21067262638;22553.32340029769;22573;21197.78802211989;21942.122315107536;21962.45093872634;22117.225897093846;22358.776412235995;11223.102151827998;11391.373474458818;11426.143039272089;11466.598669046543;11500.88499115015;8969.282457403262;9215.354925155174;9283;9374.625014999323;9496.667616633385;18703.251869925596;18973.43079707611;19035.143418545922;19060.1422935971;19525.218991240192;19172;19624;19735.40793776189;19784.109715063143;19960.20159193616;12277.324747138993;12464.314462704275;12576.937115314366;12583.182093163916;12583.811242831182;14338.283085845458;14372.137671739729;14462.783058254345;14482.275886205858;14496.492622758153;11567.710807229887;11608.767824643628;11616.303021818718;11618.186726929034;11893.345865977455;9630.32587718853;9635.325527213028;9664.516774161404;9711.5144242789;9826.950865245628;9901.653442129491;9907.801843962936;10031.548580313758;10128.898711013091;10181.949090254502;7329.780106596811;7341.559506429631;7346.33882950537;7369.299916507923;7403.777886663409;7494.962525187339;7507.436942229246;7590.848183036419;7617.466777325756;7671.846563068819 03:36:04 INFO - PID 3724 | |4;prototype.html;501.26314317952597;511.18721233239154;526.5655833937002;530.7478947499937;540.3920589336996;6330.113784070234;6367.522435817309;6371.649559274238;6448.5808422452565;6468.644224567659;6300.7794727184555;6320.431161195492;6334.461570766479;6352.17421735175;6395.680215989205;4876.2685597160535;5111.619862637086;5232.816851410202;5246.344206974129;5258.158694608864;5048.268001139832;5048.823291184809;5059;5094.414142373619;5109.463506331837;4969.130402179604;5004.149294619923;5014.949850501484;5027.2907211548245;5089.236614507817;5030.220315851012;5047.268146118829;5047.394312682465;5069.391672999227;5081.695098294095;4562.064776720797;4580.885477863047;4589.977050114761;4599.655025873039;4639.953600463986;6255.499560035186;6256.96871515644;6321.336259692712;6360.9045864312075;6381.096601046513;5701.315842098933;5723.627964182315;5759.329794359631;5794.26172148358;5813.883722325572;6539.771108011197;6583;6602.834929126762;6691.130153080117;6692.83267918301;4912.484189160123;4933.434614000736;4947.901041979177;4949.282354058641;4985.601151907875;6645.169353830771;6681.556764751386;6862.588244705334;6869.828254293633;6931.722731090801;4995.425526064477;5048.974755126274;5063.620228482879;5068.060524895552;5088.516590923857;5109.846704598868;5115.923261151086;5137.5376216139975;5149.150390185652;5199.896002079937;5634.661920284796;5716.370469583955;5740.971295143581;5784.797532086441;5812.384427007779;6610.702518386588;6734.393904548614;6751.662416879137;6752;6764.661766911635;1785.81248968859;1810.2487467700757;1812.6555954368891;1821.125859587379;1823.5258832703328;1178.2871362825538;1243.347242697591;1253.6991122130623;1256.2776403567984;1267.1066897837002;5958;5977.820665380045;5983.863066017443;6038.7282572283475;6064.120702498113;6875.3124687531845;6905.723771049203;6923.515353925177;6959.8956015659805;6994.5803251805055;5907.350191478934;5946.910796338058;5971.910421343683;6009.519238460913;6038.365971573009;3592.6766591006935;3613.2114603271025;3641.908952276214;3664.2671465706967;3699.445083237536;6415.871682566322;6548.639824809586;6581;6659.866802664016;6661;6852.5203235774015;6916.5992388144205;7013.614251216232;7037.4018208451835;7058.329458701415;6614.735410583523;6646.136002319668;6646.202455705346;6665.266820649627;6684.863573192569;6887.449004079562;6915.792526224222;6957.304269573002;6962.408195303456;6991.510594258353;6787;6809.897851532132;6838.503946059752;6881.965590172217;6917.792466226021;6570.704318305592;6574.769883054261;6633.872241718823;6692.631905245258;6734.865302693821;5035.974820126023;5065.13892638245;5105.6426050177615;5107;5133.101707201299;6606.339366063356;6732.596044237362;6839.384455399038;6963.721451141898;6975.581465112109;6536.346365363425;6553.148090748172;6611.140551728437;6613.702383392662;6642.136522252076;6423.550351475539;6547.508936829661;6557.245916719638;6578.901316480161;6623.966880165568;6437.195350581177;6462.935370646233;6533.901991470227;6570.277269500447;6606.61261135167;6591.538592298493;6615.834604134935;6637.6349300788925;6656.7670131545165;6719.7152967991115;6372.235331760218;6394.944834102321;6425.710843012169;6488.253850807028;6522.543421960607 03:36:04 INFO - PID 3724 | |5;yui.html;712.6971037309143;739.412167326975;775.3332134364446;845.1084106267884;916.972490825275;18982.90508547457;19486.512837179078;19499.317523886668;19552.608947821034;19565;17180.39868604599;18792.24831006758;18850.34023809167;18884.62230755384;18902.338418155367;983.46892677954;1005.3415013166388;1010.7422607235145;1011.0597144655504;1013.366645846346;959.8608201810733;998.6155330197889;1000.2698030437784;1001.1840350114634;1006.4011912911806;1066.184368957748;1067.4929408530963;1067.5089458848938;1067.7117178361837;1068.2201992545447;1063.9095676867437;1064.5156453813497;1065.0574241796028;1065.121274948163;1065.4566171252638;779.80894680803;782.7064850680995;782.9664842408024;783.0094929913664;783.3576467296818;18817.717734233996;18848;18933.90533047326;19223.32718354865;19261.229550817952;21266.787332126634;21376.144954201813;21536.892315538324;21708.240211592678;21717.45706357338;15140.621484462867;15244.466443674419;15279.236038198156;15345.386184552604;15406.691866162613;3638.472421498868;3654.5614526256754;3657.3233951719017;3657.908552286214;3662.9450558241383;120658;123930;124137;126019;129113;16526.256318465697;16770.664586708197;17605.911970440065;17715.114244287735;17732.822671773374;30330.393392132035;30437.543436848464;31102.53346199809;31202.53196202059;31240.531392029137;9019.729408117766;9082.86375704365;9094.590743416562;9096.636134554543;9111.498867562348;4307;4338.305871060616;4412.514623391425;4416.4037854889175;4421.226285400042;5140.126178549655;5263.36839579253;5267.341582302212;5282.049231138355;5286.418493965661;1040.2770074798093;1061.8194906865851;1076.56399158341;1083.6099004358425;1085.2403317677656;690.1959217511543;711.1501755402245;712.9536580122275;716.4053835316674;717.9641017949082;10931.01620854127;11006.284591501526;11360.318380897173;12334;12637.684057898443;632.3455223843392;633.7243299164878;635.7774778827373;644.735658380062;654.2933631677777;5097.566706829887;5338.572914166857;5365.436629153961;5443.646162999393;5604;113750.43124784323;113788;113962;115388.42305788417;119362;96506;96541;98688;99734;101322.4933875326;85400.99999999876;86589.13410865811;86979;89763.55118224367;90191;10372.11836993864;10455.424951627734;10588.788224235626;11685.76628467409;11874.762504749684;38718.6128138715;40072.59927400688;40216.59783402128;40447.393289101274;41806.37290440585;70708.64645676945;71018.64490677514;71428.64285678539;72715.63642181756;73239;16830.242639081025;16844.242009109374;16903;16935.830641693425;16966.406175783788;18779.34272300463;19152.425427237205;20177.39467815968;20368.79631203669;20385.79614203839;17687.38094166698;17709;18055.277788888296;18776.624467510846;18972.146253418905;16708.58228544296;17229.310827567257;17354.82645173532;17376.30494780245;17467.82532174713;17056.31774728997;17619.911900440417;17742.379016734354;17753.556161096076;17848.821511785234;11973.102017348909;12018.399080046138;12370.876291236973;12764.361781911053;13133.73732525325;13550;13748.381322840296;13830.446782129005;13831.377588008765;14049.297535122998 03:36:04 INFO - PID 3724 | __end_tp_report 03:36:04 INFO - PID 3724 | __start_cc_report 03:36:04 INFO - PID 3724 | _x_x_mozilla_cycle_collect,361 03:36:04 INFO - PID 3724 | __end_cc_report 03:36:04 INFO - PID 3724 | __startTimestamp1504521364834__endTimestamp 03:36:04 INFO - PID 3724 | 03:36:04 INFO - PID 3724 | ------- Summary: start ------- 03:36:04 INFO - PID 3724 | Number of tests: 6 03:36:04 INFO - PID 3724 | 03:36:04 INFO - PID 3724 | [#0] dojo.html Cycles:165 Average:6379.74 Median:6668.70 stddev:866.25 (13.0%) stddev-sans-first:835.27 03:36:04 INFO - PID 3724 | Values: 3332.2 3362.9 3403.1 3426.5 3432.3 6445.0 6476.8 6479.4 6499.9 6569.2 6314.3 6384.5 6394.8 6450.2 6665.6 5932.9 6032.3 6038.6 6039.2 6095.6 949.9 5939.6 5969.9 6047.7 6136.7 5989.7 6000.0 6072.4 6072.5 6088.0 5968.0 5993.2 6071.7 6088.3 6095.3 5963.6 5982.6 6025.5 6052.1 6055.2 6509.8 6544.5 6554.3 6593.3 6599.5 6799.5 6903.2 6904.9 6971.2 7078.9 6901.8 6972.9 6980.0 6988.8 7000.7 6396.5 6412.1 6425.4 6437.3 6454.6 7083.8 7085.0 7095.9 7177.0 7259.4 6864.2 6931.2 6936.8 6956.7 6975.3 7001.9 7022.7 7043.8 7113.6 7134.6 6764.0 6792.0 6944.4 6954.1 7059.3 6933.5 6978.3 6995.8 7000.3 7021.6 5351.6 5398.1 5423.8 5441.4 5454.9 4696.0 4722.9 4749.4 4764.8 4788.1 5949.9 6003.0 6004.1 6061.0 6080.4 6982.5 7003.6 7026.1 7080.5 7082.4 6002.2 6066.1 6154.4 6154.4 6161.2 5764.3 5832.6 5870.1 5925.3 5940.7 6782.7 6790.9 6792.4 6801.1 6809.3 6874.1 7014.4 7034.3 7046.7 7060.8 6717.7 6727.5 6736.2 6816.4 6833.0 6619.2 6776.5 6793.4 6881.2 6886.6 6939.7 6953.8 6955.5 7016.3 7078.9 6742.4 6822.2 6835.7 6836.2 6850.8 6668.7 6730.6 6732.4 6822.2 6827.2 6494.7 6531.2 6585.6 6606.6 6620.0 6696.3 6813.6 6843.0 6845.0 6873.1 6586.5 6629.2 6685.2 6697.0 6715.2 03:36:04 INFO - PID 3724 | 03:36:04 INFO - PID 3724 | [#1] ext.html Cycles:180 Average:38061.77 Median:3596.96 stddev:55394.19 (1540.0%) stddev-sans-first:55478.62 03:36:04 INFO - PID 3724 | Values: 718.7 766.2 789.0 1146.5 1174.9 29817.6 40425.8 41014.8 41100.4 41233.4 41506.2 42040.6 42110.8 42114.0 42126.4 7468.4 7620.1 7637.1 7652.5 7658.6 7577.6 7690.0 7711.8 7712.1 7723.0 7170.5 7710.0 7726.1 7802.1 7815.1 7715.5 7768.5 7769.0 7776.7 7777.7 7584.1 7666.0 7676.8 7677.5 7681.6 36767.8 37247.0 37704.4 37719.0 37728.2 3517.1 3584.4 3596.2 3597.7 3599.5 30517.8 30654.8 30769.5 30817.2 30851.2 5124.7 5156.4 5177.3 5196.9 5202.4 185059.0 185074.0 187610.0 188114.0 188935.0 47362.0 47526.5 47558.5 47637.5 47801.7 49194.8 49455.0 49502.5 49594.3 49686.8 15101.6 15346.9 15405.5 15416.1 15424.2 12600.5 12621.6 12628.6 12650.7 12654.5 13840.0 13923.3 14030.4 14062.6 14181.5 4927.1 5916.1 6055.1 6063.8 6092.4 1000.9 1001.6 1025.7 1026.9 1030.8 22065.4 22514.2 22779.0 23103.0 23194.9 1070.3 1072.3 1086.9 1100.3 1100.7 11973.7 12075.6 12202.3 12272.3 12272.5 236916.0 239124.0 239649.0 240144.0 240342.0 50268.2 50295.2 50522.7 50657.0 51041.5 1446.6 1463.6 1469.9 1471.5 1484.2 21690.2 21961.8 22364.6 22604.9 22606.1 36217.3 36219.8 36285.8 36326.6 36342.1 199309.0 200237.0 216654.0 220540.0 220667.0 36774.0 36986.3 37061.3 37123.3 37187.8 40263.2 44301.3 44802.0 44843.0 45373.5 33118.8 33413.3 33425.8 33454.8 33759.7 33187.2 33325.5 33432.8 33698.0 33813.0 37159.0 37385.6 37801.0 37896.0 37982.4 32192.8 32781.7 33001.5 33324.0 33435.7 23740.2 24791.4 24882.9 25139.4 25531.6 03:36:04 INFO - PID 3724 | 03:36:04 INFO - PID 3724 | [#2] jquery.html Cycles:180 Average:33136.56 Median:21800.73 stddev:74851.35 (343.3%) stddev-sans-first:75021.41 03:36:04 INFO - PID 3724 | Values: 577.6 577.6 581.1 583.0 589.7 21835.6 22418.0 22452.0 22621.8 22623.2 23623.3 25416.9 25547.0 25564.1 25620.0 6591.1 6723.7 6732.2 6736.7 6737.8 5926.2 6417.6 6436.0 6576.0 6622.0 6407.3 6418.4 6424.3 6432.9 6437.4 6422.1 6423.6 6429.6 6431.1 6431.2 5977.0 5982.7 5983.9 5987.9 6004.8 28624.3 29292.3 29736.7 29772.7 30133.2 2030.5 2069.6 2108.5 2108.6 2108.8 19464.3 19961.3 19967.1 19979.5 20031.8 2358.4 2411.6 2440.2 2455.8 2475.7 140113.3 142243.0 144025.0 144087.0 144321.0 13438.9 14235.8 14312.6 14372.4 14413.2 20837.8 21277.1 21317.7 21741.6 21765.9 10081.8 10132.2 10193.6 10207.4 10245.2 9341.4 9376.5 9387.8 9501.8 9553.1 9124.8 9321.3 9374.8 9498.9 9509.0 2742.1 2769.3 2773.2 2798.8 2861.9 969.0 970.2 972.4 997.9 1006.8 20246.7 20360.9 20379.4 20442.1 20803.0 1047.5 1055.3 1062.5 1064.6 1066.0 11262.2 11387.1 11451.7 11520.3 11566.3 434722.0 435664.0 437628.0 441665.0 447145.0 110592.0 111938.4 111997.0 112517.4 113925.4 1205.7 1208.3 1227.3 1230.3 1236.3 17803.5 17817.4 17989.1 18001.0 18048.5 47543.0 50787.7 55325.4 57052.0 57592.7 66034.0 66398.7 66615.3 66812.7 67038.0 13772.9 13953.2 13968.0 13988.3 14004.6 22991.7 23096.7 23114.1 23126.2 23243.0 18408.9 18496.4 18674.3 18749.3 18915.9 15604.6 16424.2 16924.0 17291.9 17508.4 18916.4 19139.7 19156.9 19161.5 19235.9 13816.7 13890.2 13895.7 13898.7 13929.0 18224.0 18288.0 18327.4 18391.3 18508.8 03:36:04 INFO - PID 3724 | 03:36:04 INFO - PID 3724 | [#3] mootools.html Cycles:180 Average:9513.28 Median:2653.80 stddev:6798.13 (256.2%) stddev-sans-first:6789.32 03:36:04 INFO - PID 3724 | Values: 1318.8 1377.8 1384.3 1387.4 1388.3 14731.9 15214.5 15342.2 15451.2 15537.2 15847.2 16142.1 16145.8 16166.8 16184.8 2596.6 2646.9 2653.5 2654.1 2655.3 2735.5 2849.2 2917.0 2917.7 2918.1 2504.7 2531.6 2558.7 2558.8 2560.5 2795.4 2799.1 2809.0 2816.3 2818.4 2621.1 2630.1 2630.7 2630.8 2632.0 15096.8 15426.5 15437.7 15445.6 15448.3 8155.0 8466.8 8467.1 8467.2 8474.4 5343.1 5471.2 5633.0 5649.5 5660.5 1875.0 1907.1 1919.4 1964.5 1968.0 26802.3 26908.3 26937.2 26995.2 27006.3 6684.4 6921.2 6925.7 6972.9 7131.9 16128.0 16132.4 16170.6 16195.8 16247.3 8130.1 8135.3 8144.6 8145.8 8159.4 6559.8 7404.7 7515.5 7614.7 7622.0 4884.2 5350.5 5383.1 5488.3 5494.1 1586.7 1586.9 1589.7 1593.9 1596.7 897.4 920.0 921.7 922.7 929.6 8603.7 8709.4 8784.6 8800.8 8860.0 981.8 985.8 986.4 989.8 995.7 4565.9 4571.4 5351.9 5432.0 5446.2 21431.4 22406.0 22552.2 22553.3 22573.0 21197.8 21942.1 21962.5 22117.2 22358.8 11223.1 11391.4 11426.1 11466.6 11500.9 8969.3 9215.4 9283.0 9374.6 9496.7 18703.3 18973.4 19035.1 19060.1 19525.2 19172.0 19624.0 19735.4 19784.1 19960.2 12277.3 12464.3 12576.9 12583.2 12583.8 14338.3 14372.1 14462.8 14482.3 14496.5 11567.7 11608.8 11616.3 11618.2 11893.3 9630.3 9635.3 9664.5 9711.5 9827.0 9901.7 9907.8 10031.5 10128.9 10181.9 7329.8 7341.6 7346.3 7369.3 7403.8 7495.0 7507.4 7590.8 7617.5 7671.8 03:36:04 INFO - PID 3724 | 03:36:04 INFO - PID 3724 | [#4] prototype.html Cycles:180 Average:5614.90 Median:6332.29 stddev:1571.41 (24.8%) stddev-sans-first:1528.23 03:36:04 INFO - PID 3724 | Values: 501.3 511.2 526.6 530.7 540.4 6330.1 6367.5 6371.6 6448.6 6468.6 6300.8 6320.4 6334.5 6352.2 6395.7 4876.3 5111.6 5232.8 5246.3 5258.2 5048.3 5048.8 5059.0 5094.4 5109.5 4969.1 5004.1 5014.9 5027.3 5089.2 5030.2 5047.3 5047.4 5069.4 5081.7 4562.1 4580.9 4590.0 4599.7 4640.0 6255.5 6257.0 6321.3 6360.9 6381.1 5701.3 5723.6 5759.3 5794.3 5813.9 6539.8 6583.0 6602.8 6691.1 6692.8 4912.5 4933.4 4947.9 4949.3 4985.6 6645.2 6681.6 6862.6 6869.8 6931.7 4995.4 5049.0 5063.6 5068.1 5088.5 5109.8 5115.9 5137.5 5149.2 5199.9 5634.7 5716.4 5741.0 5784.8 5812.4 6610.7 6734.4 6751.7 6752.0 6764.7 1785.8 1810.2 1812.7 1821.1 1823.5 1178.3 1243.3 1253.7 1256.3 1267.1 5958.0 5977.8 5983.9 6038.7 6064.1 6875.3 6905.7 6923.5 6959.9 6994.6 5907.4 5946.9 5971.9 6009.5 6038.4 3592.7 3613.2 3641.9 3664.3 3699.4 6415.9 6548.6 6581.0 6659.9 6661.0 6852.5 6916.6 7013.6 7037.4 7058.3 6614.7 6646.1 6646.2 6665.3 6684.9 6887.4 6915.8 6957.3 6962.4 6991.5 6787.0 6809.9 6838.5 6882.0 6917.8 6570.7 6574.8 6633.9 6692.6 6734.9 5036.0 5065.1 5105.6 5107.0 5133.1 6606.3 6732.6 6839.4 6963.7 6975.6 6536.3 6553.1 6611.1 6613.7 6642.1 6423.6 6547.5 6557.2 6578.9 6624.0 6437.2 6462.9 6533.9 6570.3 6606.6 6591.5 6615.8 6637.6 6656.8 6719.7 6372.2 6394.9 6425.7 6488.3 6522.5 03:36:04 INFO - PID 3724 | 03:36:04 INFO - PID 3724 | [#5] yui.html Cycles:180 Average:23745.50 Median:18953.03 stddev:32666.00 (172.4%) stddev-sans-first:32711.85 03:36:04 INFO - PID 3724 | Values: 712.7 739.4 775.3 845.1 917.0 18982.9 19486.5 19499.3 19552.6 19565.0 17180.4 18792.2 18850.3 18884.6 18902.3 983.5 1005.3 1010.7 1011.1 1013.4 959.9 998.6 1000.3 1001.2 1006.4 1066.2 1067.5 1067.5 1067.7 1068.2 1063.9 1064.5 1065.1 1065.1 1065.5 779.8 782.7 783.0 783.0 783.4 18817.7 18848.0 18933.9 19223.3 19261.2 21266.8 21376.1 21536.9 21708.2 21717.5 15140.6 15244.5 15279.2 15345.4 15406.7 3638.5 3654.6 3657.3 3657.9 3662.9 120658.0 123930.0 124137.0 126019.0 129113.0 16526.3 16770.7 17605.9 17715.1 17732.8 30330.4 30437.5 31102.5 31202.5 31240.5 9019.7 9082.9 9094.6 9096.6 9111.5 4307.0 4338.3 4412.5 4416.4 4421.2 5140.1 5263.4 5267.3 5282.0 5286.4 1040.3 1061.8 1076.6 1083.6 1085.2 690.2 711.2 713.0 716.4 718.0 10931.0 11006.3 11360.3 12334.0 12637.7 632.3 633.7 635.8 644.7 654.3 5097.6 5338.6 5365.4 5443.6 5604.0 113750.4 113788.0 113962.0 115388.4 119362.0 96506.0 96541.0 98688.0 99734.0 101322.5 85401.0 86589.1 86979.0 89763.6 90191.0 10372.1 10455.4 10588.8 11685.8 11874.8 38718.6 40072.6 40216.6 40447.4 41806.4 70708.6 71018.6 71428.6 72715.6 73239.0 16830.2 16844.2 16903.0 16935.8 16966.4 18779.3 19152.4 20177.4 20368.8 20385.8 17687.4 17709.0 18055.3 18776.6 18972.1 16708.6 17229.3 17354.8 17376.3 17467.8 17056.3 17619.9 17742.4 17753.6 17848.8 11973.1 12018.4 12370.9 12764.4 13133.7 13550.0 13748.4 13830.4 13831.4 14049.3 03:36:04 INFO - PID 3724 | -------- Summary: end -------- 03:36:04 INFO - PID 3724 | 03:36:05 INFO - TEST-INFO | 3724: exit 0 03:36:05 INFO - TEST-OK | dromaeo_css | took 1113008ms 03:36:05 INFO - TEST-START | kraken 03:36:05 INFO - Initialising browser for kraken test... 03:36:05 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:55912/getInfo.html -profile /tmp/tmpBIHDvx/profile 03:36:05 INFO - TEST-INFO | started process 4105 (/builds/slave/test/build/application/firefox/firefox http://localhost:55912/getInfo.html) 03:36:11 INFO - TEST-INFO | 4105: exit 0 03:36:11 INFO - Browser initialized. 03:36:11 INFO - Running cycle 1/1 for kraken test... 03:36:11 INFO - Using env: {'DISPLAY': ':0', 03:36:11 INFO - 'HOME': '/home/cltbld', 03:36:11 INFO - 'JSGC_DISABLE_POISONING': '1', 03:36:11 INFO - 'LANG': 'en_US.UTF-8', 03:36:11 INFO - 'LANGUAGE': 'en_US:en', 03:36:11 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 03:36:11 INFO - 'LOGNAME': 'cltbld', 03:36:11 INFO - 'MAIL': '/var/mail/cltbld', 03:36:12 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:36:12 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:36:12 INFO - 'MOZ_CRASHREPORTER': '1', 03:36:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:36:12 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 03:36:12 INFO - 'MOZ_NO_REMOTE': '1', 03:36:12 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:36:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:36:12 INFO - 'NO_EM_RESTART': '1', 03:36:12 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:36:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:36:12 INFO - 'PWD': '/builds/slave/test', 03:36:12 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:36:12 INFO - 'RUST_BACKTRACE': 'full', 03:36:12 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 03:36:12 INFO - 'SHELL': '/bin/bash', 03:36:12 INFO - 'SHLVL': '1', 03:36:12 INFO - 'STYLO_FORCE_ENABLED': '1', 03:36:12 INFO - 'TERM': 'linux', 03:36:12 INFO - 'TMOUT': '86400', 03:36:12 INFO - 'USER': 'cltbld', 03:36:12 INFO - 'XDG_SESSION_COOKIE': '199fb4c72b90437b990b419b000001c5-1504520177.184169-1287861291', 03:36:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:36:12 INFO - '_': '/tools/buildbot/bin/python'} 03:36:12 INFO - TEST-INFO | started process 4307 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpBIHDvx/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 03:36:12 INFO - PID 4307 | 03:36:12 INFO - PID 4307 | (/builds/slave/test/build/application/firefox/firefox:4359): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 03:36:12 INFO - PID 4307 | 03:36:23 INFO - PID 4307 | RSS: Main: 166871040 03:36:23 INFO - PID 4307 | 03:36:25 INFO - PID 4307 | Cycle 1(1): loaded http://localhost:55912/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:55912/tests/kraken/driver.html?testName=audio-beat-detection) 03:36:26 INFO - PID 4307 | RSS: Main: 172990464 03:36:26 INFO - PID 4307 | 03:36:29 INFO - PID 4307 | Cycle 1(1): loaded http://localhost:55912/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:55912/tests/kraken/driver.html?testName=audio-dft) 03:36:30 INFO - PID 4307 | RSS: Main: 173281280 03:36:30 INFO - PID 4307 | 03:36:33 INFO - PID 4307 | Cycle 1(1): loaded http://localhost:55912/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:55912/tests/kraken/driver.html?testName=audio-fft) 03:36:33 INFO - PID 4307 | RSS: Main: 166465536 03:36:33 INFO - PID 4307 | 03:36:35 INFO - PID 4307 | Cycle 1(1): loaded http://localhost:55912/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:55912/tests/kraken/driver.html?testName=audio-oscillator) 03:36:36 INFO - PID 4307 | RSS: Main: 167669760 03:36:36 INFO - PID 4307 | 03:36:38 INFO - PID 4307 | Cycle 1(1): loaded http://localhost:55912/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:55912/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:36:39 INFO - PID 4307 | RSS: Main: 167632896 03:36:39 INFO - PID 4307 | 03:36:42 INFO - PID 4307 | Cycle 1(1): loaded http://localhost:55912/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:55912/tests/kraken/driver.html?testName=imaging-darkroom) 03:36:43 INFO - PID 4307 | RSS: Main: 170598400 03:36:43 INFO - PID 4307 | 03:36:46 INFO - PID 4307 | Cycle 1(1): loaded http://localhost:55912/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:55912/tests/kraken/driver.html?testName=imaging-desaturate) 03:36:47 INFO - PID 4307 | RSS: Main: 170479616 03:36:47 INFO - PID 4307 | 03:36:50 INFO - PID 4307 | Cycle 1(1): loaded http://localhost:55912/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:55912/tests/kraken/driver.html?testName=json-parse-financial) 03:36:50 INFO - PID 4307 | RSS: Main: 171057152 03:36:50 INFO - PID 4307 | 03:36:52 INFO - PID 4307 | Cycle 1(1): loaded http://localhost:55912/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:55912/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:36:52 INFO - PID 4307 | RSS: Main: 171380736 03:36:52 INFO - PID 4307 | 03:36:54 INFO - PID 4307 | Cycle 1(1): loaded http://localhost:55912/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:55912/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:36:55 INFO - PID 4307 | RSS: Main: 170758144 03:36:55 INFO - PID 4307 | 03:36:57 INFO - PID 4307 | Cycle 1(1): loaded http://localhost:55912/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:55912/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:36:57 INFO - PID 4307 | RSS: Main: 170868736 03:36:57 INFO - PID 4307 | 03:37:00 INFO - PID 4307 | Cycle 1(1): loaded http://localhost:55912/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:55912/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:37:01 INFO - PID 4307 | RSS: Main: 171122688 03:37:01 INFO - PID 4307 | 03:37:04 INFO - PID 4307 | Cycle 1(1): loaded http://localhost:55912/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:55912/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:37:04 INFO - PID 4307 | RSS: Main: 171266048 03:37:04 INFO - PID 4307 | 03:37:06 INFO - PID 4307 | Cycle 1(1): loaded http://localhost:55912/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:55912/tests/kraken/driver.html?testName=ai-astar) 03:37:06 INFO - PID 4307 | RSS: Main: 172945408 03:37:06 INFO - PID 4307 | 03:37:06 INFO - PID 4307 | __start_tp_report 03:37:06 INFO - PID 4307 | _x_x_mozilla_page_load 03:37:06 INFO - PID 4307 | _x_x_mozilla_page_load_details 03:37:06 INFO - PID 4307 | |i|pagename|runs| 03:37:06 INFO - PID 4307 | |0;ai-astar;101;107;94;106;109;110;111;106;107;107 03:37:06 INFO - PID 4307 | |1;audio-beat-detection;144;115;142;168;118;147;143;117;176;115 03:37:06 INFO - PID 4307 | |2;audio-dft;163;163;163;162;162;162;167;165;162;169 03:37:06 INFO - PID 4307 | |3;audio-fft;73;73;74;170;75;76;74;76;166;75 03:37:06 INFO - PID 4307 | |4;audio-oscillator;79;80;129;80;79;83;129;78;80;136 03:37:06 INFO - PID 4307 | |5;imaging-gaussian-blur;114;113;115;114;115;114;114;113;113;114 03:37:06 INFO - PID 4307 | |6;imaging-darkroom;148;148;147;148;149;148;147;148;148;151 03:37:06 INFO - PID 4307 | |7;imaging-desaturate;94;93;95;94;94;94;94;94;93;94 03:37:06 INFO - PID 4307 | |8;json-parse-financial;53;53;53;52;52;52;52;53;53;58 03:37:06 INFO - PID 4307 | |9;json-stringify-tinderbox;38;39;38;38;38;37;39;38;38;39 03:37:06 INFO - PID 4307 | |10;stanford-crypto-aes;76;81;74;75;74;75;76;74;74;79 03:37:06 INFO - PID 4307 | |11;stanford-crypto-ccm;129;121;127;119;117;127;116;125;116;153 03:37:06 INFO - PID 4307 | |12;stanford-crypto-pbkdf2;180;170;173;170;171;168;170;169;169;165 03:37:06 INFO - PID 4307 | |13;stanford-crypto-sha256-iterative;71;69;64;66;64;66;64;63;63;62 03:37:06 INFO - PID 4307 | __end_tp_report 03:37:06 INFO - PID 4307 | __start_cc_report 03:37:06 INFO - PID 4307 | _x_x_mozilla_cycle_collect,935 03:37:06 INFO - PID 4307 | __end_cc_report 03:37:06 INFO - PID 4307 | __startTimestamp1504521426932__endTimestamp 03:37:06 INFO - PID 4307 | 03:37:06 INFO - PID 4307 | ------- Summary: start ------- 03:37:06 INFO - PID 4307 | Number of tests: 14 03:37:06 INFO - PID 4307 | 03:37:06 INFO - PID 4307 | [#0] ai-astar Cycles:10 Average:105.80 Median:108.00 stddev:4.96 (4.6%) stddev-sans-first:4.95 03:37:06 INFO - PID 4307 | Values: 101.0 107.0 94.0 106.0 109.0 110.0 111.0 106.0 107.0 107.0 03:37:06 INFO - PID 4307 | 03:37:06 INFO - PID 4307 | [#1] audio-beat-detection Cycles:10 Average:138.50 Median:143.50 stddev:22.11 (15.4%) stddev-sans-first:23.36 03:37:06 INFO - PID 4307 | Values: 144.0 115.0 142.0 168.0 118.0 147.0 143.0 117.0 176.0 115.0 03:37:06 INFO - PID 4307 | 03:37:06 INFO - PID 4307 | [#2] audio-dft Cycles:10 Average:163.80 Median:163.00 stddev:2.44 (1.5%) stddev-sans-first:2.57 03:37:06 INFO - PID 4307 | Values: 163.0 163.0 163.0 162.0 162.0 162.0 167.0 165.0 162.0 169.0 03:37:06 INFO - PID 4307 | 03:37:06 INFO - PID 4307 | [#3] audio-fft Cycles:10 Average:93.20 Median:74.50 stddev:39.45 (53.0%) stddev-sans-first:41.16 03:37:06 INFO - PID 4307 | Values: 73.0 73.0 74.0 170.0 75.0 76.0 74.0 76.0 166.0 75.0 03:37:06 INFO - PID 4307 | 03:37:06 INFO - PID 4307 | [#4] audio-oscillator Cycles:10 Average:95.30 Median:79.50 stddev:24.97 (31.4%) stddev-sans-first:25.78 03:37:06 INFO - PID 4307 | Values: 79.0 80.0 129.0 80.0 79.0 83.0 129.0 78.0 80.0 136.0 03:37:06 INFO - PID 4307 | 03:37:06 INFO - PID 4307 | [#5] imaging-gaussian-blur Cycles:10 Average:113.90 Median:114.00 stddev:0.74 (0.6%) stddev-sans-first:0.78 03:37:06 INFO - PID 4307 | Values: 114.0 113.0 115.0 114.0 115.0 114.0 114.0 113.0 113.0 114.0 03:37:06 INFO - PID 4307 | 03:37:06 INFO - PID 4307 | [#6] imaging-darkroom Cycles:10 Average:148.20 Median:148.00 stddev:1.14 (0.8%) stddev-sans-first:1.20 03:37:06 INFO - PID 4307 | Values: 148.0 148.0 147.0 148.0 149.0 148.0 147.0 148.0 148.0 151.0 03:37:06 INFO - PID 4307 | 03:37:06 INFO - PID 4307 | [#7] imaging-desaturate Cycles:10 Average:93.90 Median:94.00 stddev:0.57 (0.6%) stddev-sans-first:0.60 03:37:06 INFO - PID 4307 | Values: 94.0 93.0 95.0 94.0 94.0 94.0 94.0 94.0 93.0 94.0 03:37:06 INFO - PID 4307 | 03:37:06 INFO - PID 4307 | [#8] json-parse-financial Cycles:10 Average:53.10 Median:53.00 stddev:1.79 (3.4%) stddev-sans-first:1.90 03:37:06 INFO - PID 4307 | Values: 53.0 53.0 53.0 52.0 52.0 52.0 52.0 53.0 53.0 58.0 03:37:06 INFO - PID 4307 | 03:37:06 INFO - PID 4307 | [#9] json-stringify-tinderbox Cycles:10 Average:38.20 Median:38.00 stddev:0.63 (1.7%) stddev-sans-first:0.67 03:37:06 INFO - PID 4307 | Values: 38.0 39.0 38.0 38.0 38.0 37.0 39.0 38.0 38.0 39.0 03:37:06 INFO - PID 4307 | 03:37:06 INFO - PID 4307 | [#10] stanford-crypto-aes Cycles:10 Average:75.80 Median:75.50 stddev:2.39 (3.2%) stddev-sans-first:2.54 03:37:06 INFO - PID 4307 | Values: 76.0 81.0 74.0 75.0 74.0 75.0 76.0 74.0 74.0 79.0 03:37:06 INFO - PID 4307 | 03:37:06 INFO - PID 4307 | [#11] stanford-crypto-ccm Cycles:10 Average:125.00 Median:126.00 stddev:10.98 (8.7%) stddev-sans-first:11.56 03:37:06 INFO - PID 4307 | Values: 129.0 121.0 127.0 119.0 117.0 127.0 116.0 125.0 116.0 153.0 03:37:06 INFO - PID 4307 | 03:37:06 INFO - PID 4307 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:170.50 Median:170.00 stddev:3.92 (2.3%) stddev-sans-first:2.19 03:37:06 INFO - PID 4307 | Values: 180.0 170.0 173.0 170.0 171.0 168.0 170.0 169.0 169.0 165.0 03:37:06 INFO - PID 4307 | 03:37:06 INFO - PID 4307 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.20 Median:65.00 stddev:2.86 (4.4%) stddev-sans-first:2.13 03:37:06 INFO - PID 4307 | Values: 71.0 69.0 64.0 66.0 64.0 66.0 64.0 63.0 63.0 62.0 03:37:06 INFO - PID 4307 | -------- Summary: end -------- 03:37:06 INFO - PID 4307 | 03:37:07 INFO - PID 4307 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 03:37:07 INFO - TEST-INFO | 4307: exit 0 03:37:07 INFO - TEST-OK | kraken | took 62158ms 03:37:07 INFO - SUITE-END | took 1175s 03:37:08 INFO - Completed test suite (00:19:35) 03:37:08 INFO - javascript benchmark 03:37:08 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8614.310554751914, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3332.2169290216793, 3362.8654853805847, 3403.115190050587, 3426.4688973209163, 3432.2963792422556, 6445.03324501325, 6476.805695829127, 6479.384458476443, 6499.870002599945, 6569.178852643419, 6314.273858506273, 6384.457321127709, 6394.808155755335, 6450.161479007723, 6665.600063996164, 5932.911006334908, 6032.27612686476, 6038.6074905131245, 6039.245094363205, 6095.573309868311, 949.8761402514956, 5939.643621382709, 5969.88060238795, 6047.697615119248, 6136.723847426854, 5989.700514974277, 6000.009998350267, 6072.423119803611, 6072.51419886408, 6088.0, 5967.970160149215, 5993.160957465958, 6071.726772295213, 6088.330283668793, 6095.268567771852, 5963.582549221556, 5982.611130276561, 6025.457708806184, 6052.12244224585, 6055.212822333113, 6509.8047058587845, 6544.476441884685, 6554.344565543455, 6593.307702691196, 6599.47204223661, 6799.456043516507, 6903.240643529208, 6904.930950690479, 6971.163460384735, 7078.893816592756, 6901.827454313632, 6972.860542789166, 6979.965100174467, 6988.755393561201, 7000.684969176399, 6396.456301214403, 6412.102305677209, 6425.421712045938, 6437.324080971524, 6454.580452270634, 7083.7520686775715, 7085.0, 7095.89356159658, 7177.0, 7259.41924646027, 6864.24493305736, 6931.168259808755, 6936.826579335557, 6956.65216739161, 6975.267596902354, 7001.894971575431, 7022.719091236396, 7043.788686339418, 7113.608751518613, 7134.571925684476, 6764.0, 6792.0, 6944.4444444445335, 6954.095967524189, 7059.2940705928995, 6933.54931929433, 6978.337057979484, 6995.825104372431, 7000.3349681781165, 7021.648917554204, 5351.598630102758, 5398.082326004589, 5423.755930983037, 5441.401445840955, 5454.945450545523, 4695.9530404695515, 4722.905541889143, 4749.3588365570895, 4764.833230836974, 4788.066327066189, 5949.940500595025, 6002.969985150134, 6004.1294012368835, 6061.030235162353, 6080.361562036011, 6982.546134501241, 7003.5797852129035, 7026.086608740933, 7080.539764915264, 7082.3979961703835, 6002.159697642333, 6066.090086487062, 6154.353792851775, 6154.446099851035, 6161.168242287234, 5764.279465066867, 5832.562557808182, 5870.090136028923, 5925.288965324103, 5940.70296485174, 6782.728690852409, 6790.864182716318, 6792.422644075309, 6801.115854938926, 6809.285025072395, 6874.072000279898, 7014.403775679124, 7034.331738484836, 7046.718131274692, 7060.752873649398, 6717.731290748511, 6727.495437842083, 6736.225334086643, 6816.420604248694, 6833.043373927753, 6619.172603424572, 6776.457883369417, 6793.354631310018, 6881.208661004049, 6886.586804791728, 6939.687714052779, 6953.826154345979, 6955.513114081966, 7016.298370163147, 7078.8938165928585, 6742.393184613408, 6822.215445223863, 6835.658217089225, 6836.248012718696, 6850.828729281808, 6668.699908504032, 6730.596164229966, 6732.3940845322195, 6822.249552549315, 6827.249002609609, 6494.740210391531, 6531.216254049545, 6585.604863708193, 6606.6036037837885, 6619.966900165468, 6696.263411024686, 6813.557118787263, 6843.041974123527, 6845.0, 6873.140857392826, 6586.50601204902, 6629.204495460575, 6685.164354455693, 6696.966515167393, 6715.1941766988275], "value": 6319.434963733581, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [718.737660753825, 766.195494730533, 789.029493722721, 1146.4840821630269, 1174.9060075193986, 29817.552736708938, 40425.79787101064, 41014.7949260254, 41100.38349424754, 41233.38149927754, 41506.16987660245, 42040.57959420405, 42110.789446052804, 42114.0, 42126.36810447838, 7468.402527797791, 7620.123685776151, 7637.121731000922, 7652.540847549165, 7658.617069146549, 7577.621118944031, 7690.0, 7711.807204819868, 7712.074551053854, 7722.961385193066, 7170.498065135443, 7709.961450192769, 7726.072871255457, 7802.063752349698, 7815.062192536903, 7715.459917805756, 7768.53388796674, 7769.0, 7776.688932442696, 7777.72777952769, 7584.051993500812, 7665.961670191669, 7676.846463070763, 7677.500962437423, 7681.57751323677, 36767.81616091929, 37247.0, 37704.43443348352, 37719.0, 37728.24543509142, 3517.120719820045, 3584.354816133095, 3596.1728802375337, 3597.748157628967, 3599.478075679012, 30517.847410762803, 30654.846725766227, 30769.538456923165, 30817.229569260722, 30851.22871928197, 5124.692518448867, 5156.432792392834, 5177.326947496801, 5196.948030519684, 5202.375714914197, 185059.0, 185074.0, 187610.0, 188114.0, 188935.0, 47362.0, 47526.5247347529, 47558.5244147561, 47637.523624764, 47801.69784719392, 49194.75402622964, 49455.0, 49502.50497495051, 49594.25608615873, 49686.75156624194, 15101.622459438602, 15346.923265383824, 15405.537833865003, 15416.075035497906, 15424.151671657943, 12600.495980160875, 12621.621351359474, 12628.621141365773, 12650.746985060247, 12654.493820247271, 13840.0, 13923.30383480822, 14030.438782448588, 14062.648433789032, 14181.503647372292, 4927.113119638428, 5916.112583112482, 6055.091736239599, 6063.8484037898525, 6092.3603021682975, 1000.9299349045642, 1001.639409812467, 1025.6974192613197, 1026.861373714554, 1030.7989941961246, 22065.448363791034, 22514.21200258016, 22779.0, 23103.0, 23194.8840255801, 1070.288258308232, 1072.3297938788257, 1086.9293495922739, 1100.312304809494, 1100.7303210713426, 11973.700657483458, 12075.637730868088, 12202.267863928193, 12272.32502212369, 12272.509099635914, 236916.0, 239124.0, 239649.0, 240144.0, 240342.0, 50268.24597631038, 50295.24557131646, 50522.74738626357, 50657.0, 51041.48958510441, 1446.6094154578416, 1463.6340914771308, 1469.9044562103643, 1471.4923351443736, 1484.161448781435, 21690.24084157047, 21961.780382195975, 22364.552708946056, 22604.886975565674, 22606.09575616957, 36217.27565448729, 36219.818900905324, 36285.81857090697, 36326.636733632324, 36342.09144771402, 199309.0, 200237.0, 216654.0, 220540.0, 220667.0, 36774.0, 36986.260274793814, 37061.25877482489, 37123.25753484969, 37187.81406092952, 40263.19473610453, 44301.33547996847, 44802.0, 44843.0, 45373.54626453693, 33118.834405827816, 33413.331733364714, 33425.83287083549, 33454.83272583621, 33759.66240337565, 33187.17032074121, 33325.50011749874, 33432.83283583664, 33698.0, 33813.0, 37159.0, 37385.626143739304, 37801.0, 37896.0, 37982.43026354552, 32192.83903580467, 32781.672183277864, 33001.50497742488, 33324.0, 33435.66564334326, 23740.169094081622, 24791.380215494755, 24882.875585621958, 25139.371515712253, 25531.617025745], "value": 17205.69628932985, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [577.5754820207144, 577.6216578141316, 581.148617275691, 582.9883402331956, 589.7287247865981, 21835.563288734214, 22418.0, 22452.0, 22621.773782262193, 22623.208187713433, 23623.291301260946, 25416.872915635395, 25547.0, 25564.105256316034, 25620.0, 6591.110200122982, 6723.697433615474, 6732.158480189976, 6736.730530778763, 6737.831554211135, 5926.170336152942, 6417.6149431034055, 6436.0, 6576.0, 6622.0, 6407.295197528269, 6418.422341989243, 6424.325445828191, 6432.903506447407, 6437.356264373549, 6422.068800024017, 6423.64669943153, 6429.646369449679, 6431.099646049557, 6431.196100487439, 5976.9540330442, 5982.700864956778, 5983.910241346383, 5987.850303742398, 6004.789832355847, 28624.284392890135, 29292.267693307836, 29736.70263297361, 29772.70227297721, 30133.246668833453, 2030.472077259923, 2069.627467055929, 2108.515041540439, 2108.599366120432, 2108.768035516092, 19464.318748843863, 19961.301354452524, 19967.101480433415, 19979.50051248716, 20031.79968200314, 2358.364413496641, 2411.626197939322, 2440.1703420837002, 2455.791257743094, 2475.7276699563035, 140113.2994335022, 142243.0, 144025.0, 144087.0, 144321.0, 13438.865611343957, 14235.793452014954, 14312.57062288133, 14372.35324410404, 14413.207273600052, 20837.79162208389, 21277.148914043264, 21317.68023479649, 21741.565168696852, 21765.891170544044, 10081.848772268422, 10132.24008199388, 10193.592256309812, 10207.438590877573, 10245.180385569136, 9341.392809467363, 9376.5311734413, 9387.765305867408, 9501.809963800686, 9553.14021738033, 9124.81377420931, 9321.254299656011, 9374.765630859283, 9498.90501094994, 9508.952455237817, 2742.0951086141527, 2769.2938300733585, 2773.154187972688, 2798.8180768250404, 2861.8855245789937, 968.970930872075, 970.2044323654677, 972.3679608254691, 997.8852431970271, 1006.8389057750725, 20246.69629955552, 20360.89819550893, 20379.388618341472, 20442.08010639525, 20803.0, 1047.4602334568171, 1055.298226679265, 1062.458146345354, 1064.627380416848, 1065.9680209593726, 11262.211645184758, 11387.14596405273, 11451.713707157387, 11520.308781473139, 11566.310785302618, 434722.0, 435664.0, 437628.0, 441664.9583760432, 447145.0, 110592.0, 111938.44030779794, 111997.0, 112517.43741281405, 113925.43037284927, 1205.7166565857017, 1208.250884451628, 1227.2575092069349, 1230.2556953043459, 1236.2520674991501, 17803.46589602314, 17817.376391826223, 17989.10054497296, 18001.0, 18048.45854624311, 47543.0, 50787.74606127093, 55325.44674553203, 57052.0, 57592.71203643955, 66034.0, 66398.6680066616, 66615.33384666091, 66812.66593667, 67038.0, 13772.862271377158, 13953.232572208626, 13968.0, 13988.300584970508, 14004.579862604138, 22991.6551251728, 23096.653550197098, 23114.075436983007, 23126.190583329502, 23243.0, 18408.907955460138, 18496.44510664682, 18674.34639787601, 18749.250029998646, 18915.90542047281, 15604.609884752972, 16424.17879106016, 16924.0, 17291.91354043222, 17508.387206447715, 18916.432507024812, 19139.712904306725, 19156.904215478833, 19161.52096197606, 19235.90382048081, 13816.654583635489, 13890.236037018061, 13895.652608684864, 13898.652533686334, 13929.0, 18224.0, 18288.0, 18327.35854245095, 18391.264349425874, 18508.81491185071], "value": 11895.940153673315, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1318.775808112621, 1377.765779817431, 1384.294010054872, 1387.3548799808086, 1388.2850332078974, 14731.92634036831, 15214.467493637725, 15342.232888355566, 15451.150186739726, 15537.223138843055, 15847.207639618002, 16142.112183829884, 16145.757813632805, 16166.75749863753, 16184.75722864155, 2596.5845464725694, 2646.907358242462, 2653.4825708986755, 2654.110872857595, 2655.309619498925, 2735.521283775341, 2849.244950088228, 2916.9854150729216, 2917.7228163324557, 2918.0953904289736, 2504.7119581248207, 2531.594944808831, 2558.6801649793774, 2558.7825034871967, 2560.46230291639, 2795.412963277714, 2799.1322689966073, 2808.9719102808913, 2816.310004049011, 2818.422223444199, 2621.082621082606, 2630.1057640402164, 2630.658014458108, 2630.8289961152464, 2632.0, 15096.849031509653, 15426.537203883901, 15437.691246175125, 15445.613859653486, 15448.304826282732, 8155.0, 8466.78833029173, 8467.068622451525, 8467.195616416493, 8474.364422668325, 5343.064963631349, 5471.206675032098, 5633.042382794934, 5649.49154576086, 5660.518855897212, 1874.996876670988, 1907.12272354717, 1919.395390452003, 1964.5383334916282, 1967.9508012299664, 26802.329941751417, 26908.327291817863, 26937.191884243504, 26995.190144295506, 27006.324841878915, 6684.364985326483, 6921.238663746984, 6925.653717314114, 6972.860542789116, 7131.857362852714, 16128.0, 16132.354705811636, 16170.59573510648, 16195.838041619669, 16247.268872900748, 8130.065042520067, 8135.308498777552, 8144.633491492897, 8145.755627331189, 8159.428839981263, 6559.770408035792, 7404.74083407089, 7515.511491753018, 7614.657340419694, 7622.047244094489, 4884.169691152512, 5350.545203657733, 5383.138697808332, 5488.341399032141, 5494.148406996922, 1586.698527279813, 1586.88098392621, 1589.6900104479516, 1593.8645215156841, 1596.7445208766542, 897.3942588752566, 920.0, 921.6589861751195, 922.6770630279483, 929.5956259027345, 8603.698870539502, 8709.390342675952, 8784.648614055493, 8800.779980500538, 8859.981102173204, 981.7938232971013, 985.8077674853478, 986.3835103060587, 989.7921436498272, 995.7162208770466, 4565.885852853705, 4571.382863313476, 5351.946480535223, 5432.049391356497, 5446.210299506629, 21431.357059288246, 22406.0, 22552.21067262638, 22553.32340029769, 22573.0, 21197.78802211989, 21942.122315107536, 21962.45093872634, 22117.225897093846, 22358.776412235995, 11223.102151827998, 11391.373474458818, 11426.143039272089, 11466.598669046543, 11500.88499115015, 8969.282457403262, 9215.354925155174, 9283.0, 9374.625014999323, 9496.667616633385, 18703.251869925596, 18973.43079707611, 19035.143418545922, 19060.1422935971, 19525.218991240192, 19172.0, 19624.0, 19735.40793776189, 19784.109715063143, 19960.20159193616, 12277.324747138993, 12464.314462704275, 12576.937115314366, 12583.182093163916, 12583.811242831182, 14338.283085845458, 14372.137671739729, 14462.783058254345, 14482.275886205858, 14496.492622758153, 11567.710807229887, 11608.767824643628, 11616.303021818718, 11618.186726929034, 11893.345865977455, 9630.32587718853, 9635.325527213028, 9664.516774161404, 9711.5144242789, 9826.950865245628, 9901.653442129491, 9907.801843962936, 10031.548580313758, 10128.898711013091, 10181.949090254502, 7329.780106596811, 7341.559506429631, 7346.33882950537, 7369.299916507923, 7403.777886663409, 7494.962525187339, 7507.436942229246, 7590.848183036419, 7617.466777325756, 7671.846563068819], "value": 6793.078920278115, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [501.26314317952597, 511.18721233239154, 526.5655833937002, 530.7478947499937, 540.3920589336996, 6330.113784070234, 6367.522435817309, 6371.649559274238, 6448.5808422452565, 6468.644224567659, 6300.7794727184555, 6320.431161195492, 6334.461570766479, 6352.17421735175, 6395.680215989205, 4876.2685597160535, 5111.619862637086, 5232.816851410202, 5246.344206974129, 5258.158694608864, 5048.268001139832, 5048.823291184809, 5059.0, 5094.414142373619, 5109.463506331837, 4969.130402179604, 5004.149294619923, 5014.949850501484, 5027.2907211548245, 5089.236614507817, 5030.220315851012, 5047.268146118829, 5047.394312682465, 5069.391672999227, 5081.695098294095, 4562.064776720797, 4580.885477863047, 4589.977050114761, 4599.655025873039, 4639.953600463986, 6255.499560035186, 6256.96871515644, 6321.336259692712, 6360.9045864312075, 6381.096601046513, 5701.315842098933, 5723.627964182315, 5759.329794359631, 5794.26172148358, 5813.883722325572, 6539.771108011197, 6583.0, 6602.834929126762, 6691.130153080117, 6692.83267918301, 4912.484189160123, 4933.434614000736, 4947.901041979177, 4949.282354058641, 4985.601151907875, 6645.169353830771, 6681.556764751386, 6862.588244705334, 6869.828254293633, 6931.722731090801, 4995.425526064477, 5048.974755126274, 5063.620228482879, 5068.060524895552, 5088.516590923857, 5109.846704598868, 5115.923261151086, 5137.5376216139975, 5149.150390185652, 5199.896002079937, 5634.661920284796, 5716.370469583955, 5740.971295143581, 5784.797532086441, 5812.384427007779, 6610.702518386588, 6734.393904548614, 6751.662416879137, 6752.0, 6764.661766911635, 1785.81248968859, 1810.2487467700757, 1812.6555954368891, 1821.125859587379, 1823.5258832703328, 1178.2871362825538, 1243.347242697591, 1253.6991122130623, 1256.2776403567984, 1267.1066897837002, 5958.0, 5977.820665380045, 5983.863066017443, 6038.7282572283475, 6064.120702498113, 6875.3124687531845, 6905.723771049203, 6923.515353925177, 6959.8956015659805, 6994.5803251805055, 5907.350191478934, 5946.910796338058, 5971.910421343683, 6009.519238460913, 6038.365971573009, 3592.6766591006935, 3613.2114603271025, 3641.908952276214, 3664.2671465706967, 3699.445083237536, 6415.871682566322, 6548.639824809586, 6581.0, 6659.866802664016, 6661.0, 6852.5203235774015, 6916.5992388144205, 7013.614251216232, 7037.4018208451835, 7058.329458701415, 6614.735410583523, 6646.136002319668, 6646.202455705346, 6665.266820649627, 6684.863573192569, 6887.449004079562, 6915.792526224222, 6957.304269573002, 6962.408195303456, 6991.510594258353, 6787.0, 6809.897851532132, 6838.503946059752, 6881.965590172217, 6917.792466226021, 6570.704318305592, 6574.769883054261, 6633.872241718823, 6692.631905245258, 6734.865302693821, 5035.974820126023, 5065.13892638245, 5105.6426050177615, 5107.0, 5133.101707201299, 6606.339366063356, 6732.596044237362, 6839.384455399038, 6963.721451141898, 6975.581465112109, 6536.346365363425, 6553.148090748172, 6611.140551728437, 6613.702383392662, 6642.136522252076, 6423.550351475539, 6547.508936829661, 6557.245916719638, 6578.901316480161, 6623.966880165568, 6437.195350581177, 6462.935370646233, 6533.901991470227, 6570.277269500447, 6606.61261135167, 6591.538592298493, 6615.834604134935, 6637.6349300788925, 6656.7670131545165, 6719.7152967991115, 6372.235331760218, 6394.944834102321, 6425.710843012169, 6488.253850807028, 6522.543421960607], "value": 5152.446306374037, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [712.6971037309143, 739.412167326975, 775.3332134364446, 845.1084106267884, 916.972490825275, 18982.90508547457, 19486.512837179078, 19499.317523886668, 19552.608947821034, 19565.0, 17180.39868604599, 18792.24831006758, 18850.34023809167, 18884.62230755384, 18902.338418155367, 983.46892677954, 1005.3415013166388, 1010.7422607235145, 1011.0597144655504, 1013.366645846346, 959.8608201810733, 998.6155330197889, 1000.2698030437784, 1001.1840350114634, 1006.4011912911806, 1066.184368957748, 1067.4929408530963, 1067.5089458848938, 1067.7117178361837, 1068.2201992545447, 1063.9095676867437, 1064.5156453813497, 1065.0574241796028, 1065.121274948163, 1065.4566171252638, 779.80894680803, 782.7064850680995, 782.9664842408024, 783.0094929913664, 783.3576467296818, 18817.717734233996, 18848.0, 18933.90533047326, 19223.32718354865, 19261.229550817952, 21266.787332126634, 21376.144954201813, 21536.892315538324, 21708.240211592678, 21717.45706357338, 15140.621484462867, 15244.466443674419, 15279.236038198156, 15345.386184552604, 15406.691866162613, 3638.472421498868, 3654.5614526256754, 3657.3233951719017, 3657.908552286214, 3662.9450558241383, 120658.0, 123930.0, 124137.0, 126019.0, 129113.0, 16526.256318465697, 16770.664586708197, 17605.911970440065, 17715.114244287735, 17732.822671773374, 30330.393392132035, 30437.543436848464, 31102.53346199809, 31202.53196202059, 31240.531392029137, 9019.729408117766, 9082.86375704365, 9094.590743416562, 9096.636134554543, 9111.498867562348, 4307.0, 4338.305871060616, 4412.514623391425, 4416.4037854889175, 4421.226285400042, 5140.126178549655, 5263.36839579253, 5267.341582302212, 5282.049231138355, 5286.418493965661, 1040.2770074798093, 1061.8194906865851, 1076.56399158341, 1083.6099004358425, 1085.2403317677656, 690.1959217511543, 711.1501755402245, 712.9536580122275, 716.4053835316674, 717.9641017949082, 10931.01620854127, 11006.284591501526, 11360.318380897173, 12334.0, 12637.684057898443, 632.3455223843392, 633.7243299164878, 635.7774778827373, 644.735658380062, 654.2933631677777, 5097.566706829887, 5338.572914166857, 5365.436629153961, 5443.646162999393, 5604.0, 113750.43124784323, 113788.0, 113962.0, 115388.42305788417, 119362.0, 96506.0, 96541.0, 98688.0, 99734.0, 101322.4933875326, 85400.99999999876, 86589.13410865811, 86979.0, 89763.55118224367, 90191.0, 10372.11836993864, 10455.424951627734, 10588.788224235626, 11685.76628467409, 11874.762504749684, 38718.6128138715, 40072.59927400688, 40216.59783402128, 40447.393289101274, 41806.37290440585, 70708.64645676945, 71018.64490677514, 71428.64285678539, 72715.63642181756, 73239.0, 16830.242639081025, 16844.242009109374, 16903.0, 16935.830641693425, 16966.406175783788, 18779.34272300463, 19152.425427237205, 20177.39467815968, 20368.79631203669, 20385.79614203839, 17687.38094166698, 17709.0, 18055.277788888296, 18776.624467510846, 18972.146253418905, 16708.58228544296, 17229.310827567257, 17354.82645173532, 17376.30494780245, 17467.82532174713, 17056.31774728997, 17619.911900440417, 17742.379016734354, 17753.556161096076, 17848.821511785234, 11973.102017348909, 12018.399080046138, 12370.876291236973, 12764.361781911053, 13133.73732525325, 13550.0, 13748.381322840296, 13830.446782129005, 13831.377588008765, 14049.297535122998], "value": 9025.461669484459, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1480.4, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 107.0, 94.0, 106.0, 109.0, 110.0, 111.0, 106.0, 107.0, 107.0], "value": 105.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 115.0, 142.0, 168.0, 118.0, 147.0, 143.0, 117.0, 176.0, 115.0], "value": 138.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 163.0, 163.0, 162.0, 162.0, 162.0, 167.0, 165.0, 162.0, 169.0], "value": 163.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 73.0, 74.0, 170.0, 75.0, 76.0, 74.0, 76.0, 166.0, 75.0], "value": 93.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 80.0, 129.0, 80.0, 79.0, 83.0, 129.0, 78.0, 80.0, 136.0], "value": 95.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 113.0, 115.0, 114.0, 115.0, 114.0, 114.0, 113.0, 113.0, 114.0], "value": 113.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 148.0, 147.0, 148.0, 149.0, 148.0, 147.0, 148.0, 148.0, 151.0], "value": 148.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 93.0, 95.0, 94.0, 94.0, 94.0, 94.0, 94.0, 93.0, 94.0], "value": 93.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 53.0, 53.0, 52.0, 52.0, 52.0, 52.0, 53.0, 53.0, 58.0], "value": 53.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [38.0, 39.0, 38.0, 38.0, 38.0, 37.0, 39.0, 38.0, 38.0, 39.0], "value": 38.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 81.0, 74.0, 75.0, 74.0, 75.0, 76.0, 74.0, 74.0, 79.0], "value": 75.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [129.0, 121.0, 127.0, 119.0, 117.0, 127.0, 116.0, 125.0, 116.0, 153.0], "value": 125.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [180.0, 170.0, 173.0, 170.0, 171.0, 168.0, 170.0, 169.0, 169.0, 165.0], "value": 170.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 69.0, 64.0, 66.0, 64.0, 66.0, 64.0, 63.0, 63.0, 62.0], "value": 65.2, "unit": "score"}]}]} 03:37:08 INFO - Return code: 0 03:37:08 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:37:08 INFO - # TBPL SUCCESS # 03:37:08 INFO - Running post-action listener: _package_coverage_data 03:37:08 INFO - Running post-action listener: _resource_record_post_action 03:37:08 INFO - [mozharness: 2017-09-04 10:37:08.239985Z] Finished run-tests step (success) 03:37:08 INFO - Running post-run listener: _resource_record_post_run 03:37:08 INFO - Total resource usage - Wall time: 1193s; CPU: 13.0%; Read bytes: 5201920; Write bytes: 402411520; Read time: 1788; Write time: 279864 03:37:08 INFO - TinderboxPrint: CPU usage
13.1% 03:37:08 INFO - TinderboxPrint: I/O read bytes / time
5,201,920 / 1,788 03:37:08 INFO - TinderboxPrint: I/O write bytes / time
402,411,520 / 279,864 03:37:08 INFO - TinderboxPrint: CPU idle
8,262.7 (86.9%) 03:37:08 INFO - TinderboxPrint: CPU user
1,193.0 (12.5%) 03:37:08 INFO - TinderboxPrint: Swap in / out
0 / 0 03:37:08 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 120000512; Read time: 0; Write time: 106428 03:37:08 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 03:37:08 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 3457024; Write bytes: 282411008; Read time: 1748; Write time: 173436 03:37:08 INFO - Running post-run listener: _upload_blobber_files 03:37:08 INFO - Blob upload gear active. 03:37:08 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:37:08 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 03:37:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 03:37:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 03:37:09 INFO - (blobuploader) - INFO - Open directory for files ... 03:37:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:37:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:37:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:37:16 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:37:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:37:16 INFO - (blobuploader) - INFO - Done attempting. 03:37:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 03:37:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:37:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:37:17 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 03:37:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:37:17 INFO - (blobuploader) - INFO - Done attempting. 03:37:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 03:37:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:37:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:37:18 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 03:37:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:37:18 INFO - (blobuploader) - INFO - Done attempting. 03:37:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 03:37:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:37:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:37:19 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 03:37:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:37:19 INFO - (blobuploader) - INFO - Done attempting. 03:37:19 INFO - (blobuploader) - INFO - Iteration through files over. 03:37:19 INFO - Return code: 0 03:37:19 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:37:19 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:37:19 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f028e605627e22cb328b30b28c8db9bad1655f9ef0e3035e58a8de95c594cd05ce2ded27815663579b1188dce816921eae3499093ccb60613b397ba8bedb0c19", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ccff30f374cf294008b845b9fc6b61bbc4996561b2b7e6b2177c607410c03c2ac510ed55b1860d679502bb6373adefd25ec0c08ed74a5693f81d56e07a005d27", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9b7406c871abbc13ca761c63c4681dee4bd8a7ee5c2fe849dae3d437af45bae3df052796138a2dfe380240d1aeeff4330a2ad680c81e715276a2b806bbaa3253", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/74a9f525d976e664cb399d6d0ae7cdbd24bbcfaa2ff30e4dfefc595647d8ee44a4a3d0e888fce3bc39d5cf2766794111174307595be80db2d79b3fa06aa9d23e"} 03:37:19 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:37:19 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:37:19 INFO - Contents: 03:37:19 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f028e605627e22cb328b30b28c8db9bad1655f9ef0e3035e58a8de95c594cd05ce2ded27815663579b1188dce816921eae3499093ccb60613b397ba8bedb0c19", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ccff30f374cf294008b845b9fc6b61bbc4996561b2b7e6b2177c607410c03c2ac510ed55b1860d679502bb6373adefd25ec0c08ed74a5693f81d56e07a005d27", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9b7406c871abbc13ca761c63c4681dee4bd8a7ee5c2fe849dae3d437af45bae3df052796138a2dfe380240d1aeeff4330a2ad680c81e715276a2b806bbaa3253", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/74a9f525d976e664cb399d6d0ae7cdbd24bbcfaa2ff30e4dfefc595647d8ee44a4a3d0e888fce3bc39d5cf2766794111174307595be80db2d79b3fa06aa9d23e"} 03:37:19 INFO - Running post-run listener: copy_logs_to_upload_dir 03:37:19 INFO - Copying logs to upload dir... 03:37:19 INFO - mkdir: /builds/slave/test/build/upload/logs 03:37:19 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1258.579157 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 58 secs) (at 2017-09-04 03:37:19.155847) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-04 03:37:19.159247) ========= 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=199fb4c72b90437b990b419b000001c5-1504520177.184169-1287861291 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/sha512/f028e605627e22cb328b30b28c8db9bad1655f9ef0e3035e58a8de95c594cd05ce2ded27815663579b1188dce816921eae3499093ccb60613b397ba8bedb0c19", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ccff30f374cf294008b845b9fc6b61bbc4996561b2b7e6b2177c607410c03c2ac510ed55b1860d679502bb6373adefd25ec0c08ed74a5693f81d56e07a005d27", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9b7406c871abbc13ca761c63c4681dee4bd8a7ee5c2fe849dae3d437af45bae3df052796138a2dfe380240d1aeeff4330a2ad680c81e715276a2b806bbaa3253", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/74a9f525d976e664cb399d6d0ae7cdbd24bbcfaa2ff30e4dfefc595647d8ee44a4a3d0e888fce3bc39d5cf2766794111174307595be80db2d79b3fa06aa9d23e"} build_url:https://queue.taskcluster.net/v1/task/ZH0GnAa4QqyzRlNalh5SXw/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011256 build_url: 'https://queue.taskcluster.net/v1/task/ZH0GnAa4QqyzRlNalh5SXw/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f028e605627e22cb328b30b28c8db9bad1655f9ef0e3035e58a8de95c594cd05ce2ded27815663579b1188dce816921eae3499093ccb60613b397ba8bedb0c19", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ccff30f374cf294008b845b9fc6b61bbc4996561b2b7e6b2177c607410c03c2ac510ed55b1860d679502bb6373adefd25ec0c08ed74a5693f81d56e07a005d27", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9b7406c871abbc13ca761c63c4681dee4bd8a7ee5c2fe849dae3d437af45bae3df052796138a2dfe380240d1aeeff4330a2ad680c81e715276a2b806bbaa3253", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/74a9f525d976e664cb399d6d0ae7cdbd24bbcfaa2ff30e4dfefc595647d8ee44a4a3d0e888fce3bc39d5cf2766794111174307595be80db2d79b3fa06aa9d23e"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-04 03:37:19.187226) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-04 03:37:19.187550) ========= 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=199fb4c72b90437b990b419b000001c5-1504520177.184169-1287861291 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004484 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-04 03:37:19.269869) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-04 03:37:19.270173) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-04 03:37:19.270464) ========= ========= Total master_lag: 0.17 =========