builder: mozilla-central_ubuntu64_hw_qr_test-dromaeojs-e10s slave: talos-linux64-ix-010 starttime: 1503883044.77 results: success (0) revision: d10c97627b51a226e19d0fa801201897fe1932f6 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-08-27 18:17:24.773110) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-08-27 18:17:24.773551) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-27 18:17:24.773854) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-27 18:17:24.951058) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-27 18:17:24.951444) ========= 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=eca2ab57546de7b41f256b19000001ef-1503859188.360365-1270311827 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-08-27 18:17:24-- 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% 25.9M=0s 2017-08-27 18:17:25 (25.9 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.077993 ========= master_lag: 0.06 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-27 18:17:25.092845) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-27 18:17:25.093143) ========= 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=eca2ab57546de7b41f256b19000001ef-1503859188.360365-1270311827 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015811 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-27 18:17:25.142801) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-27 18:17:25.143124) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev d10c97627b51a226e19d0fa801201897fe1932f6 --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 d10c97627b51a226e19d0fa801201897fe1932f6 --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=eca2ab57546de7b41f256b19000001ef-1503859188.360365-1270311827 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-08-27 18:17:25,231 truncating revision to first 12 chars 2017-08-27 18:17:25,231 Setting DEBUG logging. 2017-08-27 18:17:25,232 attempt 1/10 2017-08-27 18:17:25,232 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/d10c97627b51?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-08-27 18:17:25,538 unpacking tar archive at: mozilla-central-d10c97627b51/testing/mozharness/ program finished with exit code 0 elapsedTime=0.619649 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-27 18:17:25.801994) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-27 18:17:25.802294) ========= script_repo_revision: d10c97627b51a226e19d0fa801201897fe1932f6 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-27 18:17:25.802674) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-27 18:17:25.802961) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-27 18:17:25.845338) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 3 secs) (at 2017-08-27 18:17:25.845613) ========= /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=eca2ab57546de7b41f256b19000001ef-1503859188.360365-1270311827 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 18:17:26 INFO - MultiFileLogger online at 20170827 18:17:26 in /builds/slave/test 18:17:26 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 18:17:26 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:17:26 INFO - {'append_to_log': False, 18:17:26 INFO - 'base_work_dir': '/builds/slave/test', 18:17:26 INFO - 'blob_upload_branch': 'Firefox', 18:17:26 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:17:26 INFO - 'branch': 'Firefox', 18:17:26 INFO - 'buildbot_json_path': 'buildprops.json', 18:17:26 INFO - 'code_coverage': False, 18:17:26 INFO - 'config_files': ('talos/linux_config.py',), 18:17:26 INFO - 'default_actions': ('clobber', 18:17:26 INFO - 'read-buildbot-config', 18:17:26 INFO - 'download-and-extract', 18:17:26 INFO - 'populate-webroot', 18:17:26 INFO - 'create-virtualenv', 18:17:26 INFO - 'install', 18:17:26 INFO - 'setup-mitmproxy', 18:17:26 INFO - 'run-tests'), 18:17:26 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:17:26 INFO - 'disable_ccov_upload': False, 18:17:26 INFO - 'download_minidump_stackwalk': True, 18:17:26 INFO - 'download_symbols': 'ondemand', 18:17:26 INFO - 'enable_stylo': False, 18:17:26 INFO - 'enable_webrender': False, 18:17:26 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 18:17:26 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:17:26 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:17:26 INFO - 'gecko_profile': False, 18:17:26 INFO - 'gecko_profile_interval': 0, 18:17:26 INFO - 'installer_path': 'installer.exe', 18:17:26 INFO - 'log_level': 'info', 18:17:26 INFO - 'log_name': 'talos', 18:17:26 INFO - 'log_to_console': True, 18:17:26 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 18:17:26 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 18:17:26 INFO - 'opt_config_files': (), 18:17:26 INFO - 'pip_index': False, 18:17:26 INFO - 'suite': 'dromaeojs-e10s', 18:17:26 INFO - 'system_bits': '32', 18:17:26 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:17:26 INFO - 'title': 'talos-linux64-ix-010', 18:17:26 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:17:26 INFO - 'use_talos_json': True, 18:17:26 INFO - 'verify': 'False', 18:17:26 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:17:26 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:17:26 INFO - 'work_dir': 'build'} 18:17:26 INFO - [mozharness: 2017-08-28 01:17:26.054274Z] Running clobber step. 18:17:26 INFO - Running pre-action listener: _resource_record_pre_action 18:17:26 INFO - Running main action method: clobber 18:17:26 INFO - rmtree: /builds/slave/test/build 18:17:26 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:17:27 INFO - Running post-action listener: _resource_record_post_action 18:17:27 INFO - [mozharness: 2017-08-28 01:17:27.085280Z] Finished clobber step (success) 18:17:27 INFO - [mozharness: 2017-08-28 01:17:27.085379Z] Running read-buildbot-config step. 18:17:27 INFO - Running pre-action listener: _resource_record_pre_action 18:17:27 INFO - Running main action method: read_buildbot_config 18:17:27 INFO - Using buildbot properties: 18:17:27 INFO - { 18:17:27 INFO - "project": "", 18:17:27 INFO - "product": "firefox", 18:17:27 INFO - "who": "philringnalda@gmail.com", 18:17:27 INFO - "installer_path": "public/build/target.tar.bz2", 18:17:27 INFO - "builddir": "mozilla-central_ubuntu64_hw_qr_test-dromaeojs-e10s", 18:17:27 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 18:17:27 INFO - "buildername": "Ubuntu HW 12.04 x64 qr mozilla-central talos dromaeojs-e10s", 18:17:27 INFO - "stage_platform": "linux64-qr", 18:17:27 INFO - "basedir": "/builds/slave/test", 18:17:27 INFO - "buildnumber": 20, 18:17:27 INFO - "platform": "ubuntu64_hw_qr", 18:17:27 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 18:17:27 INFO - "slavebuilddir": "test", 18:17:27 INFO - "taskId": "MYRDxLSmTP2Il-sLc1cm8Q", 18:17:27 INFO - "branch": "mozilla-central", 18:17:27 INFO - "script_repo_revision": "production", 18:17:27 INFO - "revision": "d10c97627b51a226e19d0fa801201897fe1932f6", 18:17:27 INFO - "slavename": "talos-linux64-ix-010", 18:17:27 INFO - "repo_path": "mozilla-central" 18:17:27 INFO - } 18:17:27 INFO - Finding installer, test and symbols from parent task. 18:17:27 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MYRDxLSmTP2Il-sLc1cm8Q'}, attempt #1 18:17:27 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MYRDxLSmTP2Il-sLc1cm8Q'}, attempt #1 18:17:27 INFO - Task dependencies: FgF7GK1MTSiueBqY-_8-1Q 18:17:27 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/FgF7GK1MTSiueBqY-_8-1Q'}, attempt #1 18:17:28 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/FgF7GK1MTSiueBqY-_8-1Q/artifacts/public/build/target.tar.bz2 18:17:28 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/FgF7GK1MTSiueBqY-_8-1Q/artifacts/public/build/target.test_packages.json 18:17:28 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/FgF7GK1MTSiueBqY-_8-1Q/artifacts/public/build/target.crashreporter-symbols.zip 18:17:28 INFO - Running post-action listener: _resource_record_post_action 18:17:28 INFO - [mozharness: 2017-08-28 01:17:28.449180Z] Finished read-buildbot-config step (success) 18:17:28 INFO - [mozharness: 2017-08-28 01:17:28.449403Z] Running download-and-extract step. 18:17:28 INFO - Running pre-action listener: _resource_record_pre_action 18:17:28 INFO - Running main action method: download_and_extract 18:17:28 INFO - mkdir: /builds/slave/test/build/tests 18:17:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:17:28 INFO - trying https://queue.taskcluster.net/v1/task/FgF7GK1MTSiueBqY-_8-1Q/artifacts/public/build/target.test_packages.json 18:17:28 INFO - Downloading https://queue.taskcluster.net/v1/task/FgF7GK1MTSiueBqY-_8-1Q/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 18:17:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/FgF7GK1MTSiueBqY-_8-1Q/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 18:17:29 INFO - Downloaded 989 bytes. 18:17:29 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 18:17:29 INFO - Using the following test package requirements: 18:17:29 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 18:17:29 INFO - u'common': [u'target.common.tests.zip'], 18:17:29 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 18:17:29 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 18:17:29 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 18:17:29 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 18:17:29 INFO - u'mozbase': [u'target.common.tests.zip'], 18:17:29 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 18:17:29 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 18:17:29 INFO - u'web-platform': [u'target.common.tests.zip', 18:17:29 INFO - u'target.web-platform.tests.tar.gz'], 18:17:29 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 18:17:29 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 18:17:29 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/FgF7GK1MTSiueBqY-_8-1Q/artifacts/public/build/target.common.tests.zip 18:17:29 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FgF7GK1MTSiueBqY-_8-1Q/artifacts/public/build/target.common.tests.zip'}, attempt #1 18:17:29 INFO - Fetch https://queue.taskcluster.net/v1/task/FgF7GK1MTSiueBqY-_8-1Q/artifacts/public/build/target.common.tests.zip into memory 18:17:32 INFO - Content-Length response header: 54978035 18:17:32 INFO - Bytes received: 54978035 18:17:43 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 18:17:43 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/FgF7GK1MTSiueBqY-_8-1Q/artifacts/public/build/target.common.tests.zip 18:17:43 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FgF7GK1MTSiueBqY-_8-1Q/artifacts/public/build/target.common.tests.zip'}, attempt #1 18:17:43 INFO - Fetch https://queue.taskcluster.net/v1/task/FgF7GK1MTSiueBqY-_8-1Q/artifacts/public/build/target.common.tests.zip into memory 18:17:45 INFO - Content-Length response header: 54978035 18:17:45 INFO - Bytes received: 54978035 18:17:57 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/FgF7GK1MTSiueBqY-_8-1Q/artifacts/public/build/target.talos.tests.zip 18:17:57 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FgF7GK1MTSiueBqY-_8-1Q/artifacts/public/build/target.talos.tests.zip'}, attempt #1 18:17:57 INFO - Fetch https://queue.taskcluster.net/v1/task/FgF7GK1MTSiueBqY-_8-1Q/artifacts/public/build/target.talos.tests.zip into memory 18:17:58 INFO - Content-Length response header: 14496611 18:17:58 INFO - Bytes received: 14496611 18:17:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:17:58 INFO - trying https://queue.taskcluster.net/v1/task/FgF7GK1MTSiueBqY-_8-1Q/artifacts/public/build/target.tar.bz2 18:17:58 INFO - Downloading https://queue.taskcluster.net/v1/task/FgF7GK1MTSiueBqY-_8-1Q/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 18:17:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/FgF7GK1MTSiueBqY-_8-1Q/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 18:18:02 INFO - Downloaded 61245636 bytes. 18:18:02 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/FgF7GK1MTSiueBqY-_8-1Q/artifacts/public/build/target.tar.bz2 18:18:02 INFO - mkdir: /builds/slave/test/properties 18:18:02 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:18:02 INFO - Writing to file /builds/slave/test/properties/build_url 18:18:02 INFO - Contents: 18:18:02 INFO - build_url:https://queue.taskcluster.net/v1/task/FgF7GK1MTSiueBqY-_8-1Q/artifacts/public/build/target.tar.bz2 18:18:02 INFO - Running post-action listener: _resource_record_post_action 18:18:02 INFO - Running post-action listener: find_tests_for_verification 18:18:02 INFO - Running post-action listener: set_extra_try_arguments 18:18:02 INFO - [mozharness: 2017-08-28 01:18:02.348402Z] Finished download-and-extract step (success) 18:18:02 INFO - [mozharness: 2017-08-28 01:18:02.348628Z] Running populate-webroot step. 18:18:02 INFO - Running pre-action listener: _resource_record_pre_action 18:18:02 INFO - Running main action method: populate_webroot 18:18:02 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:18:02 INFO - 'chromez-stylo-e10s': {'talos_options': ['--stylo'], 18:18:02 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:18:02 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:18:02 INFO - 'dromaeojs-stylo-e10s': {'talos_options': ['--stylo'], 18:18:02 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:18:02 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 18:18:02 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:18:02 INFO - 'g1-stylo-e10s': {'pagesets_name': 'tp5n.zip', 18:18:02 INFO - 'talos_options': ['--stylo'], 18:18:02 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:18:02 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 18:18:02 INFO - 'tests': ['damp', 'tps']}, 18:18:02 INFO - 'g2-stylo-e10s': {'pagesets_name': 'tp5n.zip', 18:18:02 INFO - 'talos_options': ['--stylo'], 18:18:02 INFO - 'tests': ['damp', 'tps']}, 18:18:02 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:18:02 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 18:18:02 INFO - 'g4-stylo-e10s': {'talos_options': ['--stylo'], 18:18:02 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 18:18:02 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 18:18:02 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 18:18:02 INFO - 'g5-stylo-e10s': {'pagesets_name': 'tp5n.zip', 18:18:02 INFO - 'talos_options': ['--stylo'], 18:18:02 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 18:18:02 INFO - 'other-e10s': {'tests': ['a11yr', 18:18:02 INFO - 'ts_paint', 18:18:02 INFO - 'tpaint', 18:18:02 INFO - 'sessionrestore', 18:18:02 INFO - 'sessionrestore_many_windows', 18:18:02 INFO - 'sessionrestore_no_auto_restore', 18:18:02 INFO - 'tabpaint']}, 18:18:02 INFO - 'other-stylo-e10s': {'talos_options': ['--stylo'], 18:18:02 INFO - 'tests': ['a11yr', 18:18:02 INFO - 'ts_paint', 18:18:02 INFO - 'tpaint', 18:18:02 INFO - 'sessionrestore', 18:18:02 INFO - 'sessionrestore_many_windows', 18:18:02 INFO - 'sessionrestore_no_auto_restore', 18:18:02 INFO - 'tabpaint']}, 18:18:02 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 18:18:02 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 18:18:02 INFO - 'perf-reftest-singletons-stylo-e10s': {'talos_options': ['--stylo'], 18:18:02 INFO - 'tests': ['bloom_basic_singleton']}, 18:18:02 INFO - 'perf-reftest-stylo-e10s': {'talos_options': ['--stylo'], 18:18:02 INFO - 'tests': ['bloom_basic']}, 18:18:02 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:18:02 INFO - 'tsvgr_opacity', 18:18:02 INFO - 'tart', 18:18:02 INFO - 'tscrollx', 18:18:02 INFO - 'tsvg_static']}, 18:18:02 INFO - 'svgr-stylo-e10s': {'talos_options': ['--stylo'], 18:18:02 INFO - 'tests': ['tsvgx', 18:18:02 INFO - 'tsvgr_opacity', 18:18:02 INFO - 'tart', 18:18:02 INFO - 'tscrollx', 18:18:02 INFO - 'tsvg_static']}, 18:18:02 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 18:18:02 INFO - 'tp5o-stylo-e10s': {'pagesets_name': 'tp5n.zip', 18:18:02 INFO - 'talos_options': ['--stylo'], 18:18:02 INFO - 'tests': ['tp5o']}, 18:18:02 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 18:18:02 INFO - 'talos_options': ['--mitmproxy', 18:18:02 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 18:18:02 INFO - '--firstNonBlankPaint'], 18:18:02 INFO - 'tests': ['tp6_google', 18:18:02 INFO - 'tp6_youtube', 18:18:02 INFO - 'tp6_amazon', 18:18:02 INFO - 'tp6_facebook']}, 18:18:02 INFO - 'tp6-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 18:18:02 INFO - 'talos_options': ['--stylo', 18:18:02 INFO - '--mitmproxy', 18:18:02 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 18:18:02 INFO - '--firstNonBlankPaint'], 18:18:02 INFO - 'tests': ['tp6_google', 18:18:02 INFO - 'tp6_youtube', 18:18:02 INFO - 'tp6_amazon', 18:18:02 INFO - 'tp6_facebook']}, 18:18:02 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 18:18:02 INFO - 'talos_options': ['--stylo', 18:18:02 INFO - '--stylo-threads=1', 18:18:02 INFO - '--mitmproxy', 18:18:02 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 18:18:02 INFO - '--firstNonBlankPaint'], 18:18:02 INFO - 'tests': ['tp6_google', 18:18:02 INFO - 'tp6_youtube', 18:18:02 INFO - 'tp6_amazon', 18:18:02 INFO - 'tp6_facebook']}, 18:18:02 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 18:18:02 INFO - 'talos_options': ['--xperf_path', 18:18:02 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:18:02 INFO - 'tests': ['tp5n']}, 18:18:02 INFO - 'xperf-stylo-e10s': {'pagesets_name': 'tp5n.zip', 18:18:02 INFO - 'talos_options': ['--stylo', 18:18:02 INFO - '--xperf_path', 18:18:02 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:18:02 INFO - 'tests': ['tp5n']}}} 18:18:02 INFO - Running post-action listener: _resource_record_post_action 18:18:02 INFO - [mozharness: 2017-08-28 01:18:02.370606Z] Finished populate-webroot step (success) 18:18:02 INFO - [mozharness: 2017-08-28 01:18:02.370695Z] Running create-virtualenv step. 18:18:02 INFO - Running pre-action listener: _resource_record_pre_action 18:18:02 INFO - Running main action method: create_virtualenv 18:18:02 INFO - Creating virtualenv /builds/slave/test/build/venv 18:18:02 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 18:18:02 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 18:18:02 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 18:18:02 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:18:02 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:18:03 INFO - Installing distribute.............................................................................................................................................................................................done. 18:18:03 INFO - Installing pip...............done. 18:18:03 INFO - Return code: 0 18:18:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 18:18:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 18:18:03 INFO - Reading from file tmpfile_stdout 18:18:03 INFO - Output received: 18:18:03 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 18:18:03 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:18:03 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')]} 18:18:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:18:03 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')]} 18:18:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:18:03 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 0x175f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x192aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1acdf90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1acb130>, '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 0x1acc030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1acc4c0>, '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': 'eca2ab57546de7b41f256b19000001ef-1503859188.360365-1270311827', '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 18:18:03 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 18:18:03 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 18:18:03 INFO - Using env: {'DISPLAY': ':0', 18:18:03 INFO - 'HOME': '/home/cltbld', 18:18:03 INFO - 'LANG': 'en_US.UTF-8', 18:18:03 INFO - 'LANGUAGE': 'en_US:en', 18:18:03 INFO - 'LOGNAME': 'cltbld', 18:18:03 INFO - 'MAIL': '/var/mail/cltbld', 18:18:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:18:03 INFO - 'MOZ_NO_REMOTE': '1', 18:18:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:18:03 INFO - 'NO_EM_RESTART': '1', 18:18:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:18:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:18:03 INFO - 'PWD': '/builds/slave/test', 18:18:03 INFO - 'SHELL': '/bin/bash', 18:18:03 INFO - 'SHLVL': '1', 18:18:03 INFO - 'TERM': 'linux', 18:18:03 INFO - 'TMOUT': '86400', 18:18:03 INFO - 'USER': 'cltbld', 18:18:03 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1503859188.360365-1270311827', 18:18:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:18:03 INFO - '_': '/tools/buildbot/bin/python'} 18:18:04 INFO - Ignoring indexes: http://pypi.python.org/simple/ 18:18:04 INFO - Downloading/unpacking pip>=1.5 18:18:04 INFO - Running setup.py egg_info for package pip 18:18:04 INFO - warning: no files found matching 'pip/cacert.pem' 18:18:04 INFO - warning: no files found matching '*.html' under directory 'docs' 18:18:04 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 18:18:04 INFO - no previously-included directories found matching 'docs/_build/_sources' 18:18:04 INFO - Installing collected packages: pip 18:18:04 INFO - Found existing installation: pip 1.1 18:18:04 INFO - Uninstalling pip: 18:18:04 INFO - Successfully uninstalled pip 18:18:04 INFO - Running setup.py install for pip 18:18:04 INFO - warning: no files found matching 'pip/cacert.pem' 18:18:04 INFO - warning: no files found matching '*.html' under directory 'docs' 18:18:04 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 18:18:04 INFO - no previously-included directories found matching 'docs/_build/_sources' 18:18:04 INFO - Installing pip script to /builds/slave/test/build/venv/bin 18:18:04 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 18:18:04 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 18:18:04 INFO - Successfully installed pip 18:18:04 INFO - Cleaning up... 18:18:04 INFO - Return code: 0 18:18:04 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:18:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:18:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:18:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:18:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:18:04 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 0x175f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x192aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1acdf90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1acb130>, '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 0x1acc030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1acc4c0>, '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': 'eca2ab57546de7b41f256b19000001ef-1503859188.360365-1270311827', '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 18:18:04 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 18:18:04 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 18:18:04 INFO - Using env: {'DISPLAY': ':0', 18:18:04 INFO - 'HOME': '/home/cltbld', 18:18:04 INFO - 'LANG': 'en_US.UTF-8', 18:18:04 INFO - 'LANGUAGE': 'en_US:en', 18:18:04 INFO - 'LOGNAME': 'cltbld', 18:18:04 INFO - 'MAIL': '/var/mail/cltbld', 18:18:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:18:04 INFO - 'MOZ_NO_REMOTE': '1', 18:18:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:18:04 INFO - 'NO_EM_RESTART': '1', 18:18:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:18:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:18:04 INFO - 'PWD': '/builds/slave/test', 18:18:04 INFO - 'SHELL': '/bin/bash', 18:18:04 INFO - 'SHLVL': '1', 18:18:04 INFO - 'TERM': 'linux', 18:18:04 INFO - 'TMOUT': '86400', 18:18:04 INFO - 'USER': 'cltbld', 18:18:04 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1503859188.360365-1270311827', 18:18:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:18:04 INFO - '_': '/tools/buildbot/bin/python'} 18:18:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:18:04 INFO - Downloading/unpacking psutil>=3.1.1 18:18: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 18:18: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 18:18: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 18:18: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 18:18:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:18:06 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:18:06 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:18:06 INFO - Installing collected packages: psutil 18:18:06 INFO - Running setup.py install for psutil 18:18:06 INFO - building 'psutil._psutil_linux' extension 18:18:06 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 18:18:06 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 18:18:06 INFO - building 'psutil._psutil_posix' extension 18:18:06 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 18:18:06 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 18:18:06 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:18:06 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:18:06 INFO - Successfully installed psutil 18:18:06 INFO - Cleaning up... 18:18:06 INFO - Return code: 0 18:18:06 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:18:06 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')]} 18:18:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:18:06 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')]} 18:18:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:18:06 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 0x175f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x192aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1acdf90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1acb130>, '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 0x1acc030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1acc4c0>, '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': 'eca2ab57546de7b41f256b19000001ef-1503859188.360365-1270311827', '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 18:18:06 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 18:18:06 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 18:18:06 INFO - Using env: {'DISPLAY': ':0', 18:18:06 INFO - 'HOME': '/home/cltbld', 18:18:06 INFO - 'LANG': 'en_US.UTF-8', 18:18:06 INFO - 'LANGUAGE': 'en_US:en', 18:18:06 INFO - 'LOGNAME': 'cltbld', 18:18:06 INFO - 'MAIL': '/var/mail/cltbld', 18:18:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:18:06 INFO - 'MOZ_NO_REMOTE': '1', 18:18:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:18:06 INFO - 'NO_EM_RESTART': '1', 18:18:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:18:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:18:06 INFO - 'PWD': '/builds/slave/test', 18:18:06 INFO - 'SHELL': '/bin/bash', 18:18:06 INFO - 'SHLVL': '1', 18:18:06 INFO - 'TERM': 'linux', 18:18:06 INFO - 'TMOUT': '86400', 18:18:06 INFO - 'USER': 'cltbld', 18:18:06 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1503859188.360365-1270311827', 18:18:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:18:06 INFO - '_': '/tools/buildbot/bin/python'} 18:18:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:18:06 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:18:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:18:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:18:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:18:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:18:07 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:18:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:18:07 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:18:07 INFO - Installing collected packages: mozsystemmonitor 18:18:07 INFO - Running setup.py install for mozsystemmonitor 18:18:08 INFO - Successfully installed mozsystemmonitor 18:18:08 INFO - Cleaning up... 18:18:08 INFO - Return code: 0 18:18:08 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:18: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')]} 18:18:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:18: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')]} 18:18:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:18:08 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 0x175f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x192aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1acdf90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1acb130>, '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 0x1acc030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1acc4c0>, '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': 'eca2ab57546de7b41f256b19000001ef-1503859188.360365-1270311827', '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 18:18:08 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 18:18:08 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 18:18:08 INFO - Using env: {'DISPLAY': ':0', 18:18:08 INFO - 'HOME': '/home/cltbld', 18:18:08 INFO - 'LANG': 'en_US.UTF-8', 18:18:08 INFO - 'LANGUAGE': 'en_US:en', 18:18:08 INFO - 'LOGNAME': 'cltbld', 18:18:08 INFO - 'MAIL': '/var/mail/cltbld', 18:18:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:18:08 INFO - 'MOZ_NO_REMOTE': '1', 18:18:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:18:08 INFO - 'NO_EM_RESTART': '1', 18:18:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:18:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:18:08 INFO - 'PWD': '/builds/slave/test', 18:18:08 INFO - 'SHELL': '/bin/bash', 18:18:08 INFO - 'SHLVL': '1', 18:18:08 INFO - 'TERM': 'linux', 18:18:08 INFO - 'TMOUT': '86400', 18:18:08 INFO - 'USER': 'cltbld', 18:18:08 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1503859188.360365-1270311827', 18:18:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:18:08 INFO - '_': '/tools/buildbot/bin/python'} 18:18:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:18:08 INFO - Downloading/unpacking jsonschema==2.5.1 18:18:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:18:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:18:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:18:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:18:09 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:18:09 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:18: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 18:18: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 18:18: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 18:18: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 18:18:09 INFO - Downloading functools32-3.2.3-2.tar.gz 18:18:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:18:09 INFO - warning: no files found matching '*.txt' 18:18:09 INFO - no previously-included directories found matching 'build' 18:18:09 INFO - no previously-included directories found matching 'dist' 18:18:09 INFO - no previously-included directories found matching '.git*' 18:18:09 INFO - Installing collected packages: jsonschema, functools32 18:18:09 INFO - Running setup.py install for functools32 18:18:09 INFO - warning: no files found matching '*.txt' 18:18:09 INFO - no previously-included directories found matching 'build' 18:18:09 INFO - no previously-included directories found matching 'dist' 18:18:09 INFO - no previously-included directories found matching '.git*' 18:18:09 INFO - Successfully installed jsonschema functools32 18:18:09 INFO - Cleaning up... 18:18:09 INFO - Return code: 0 18:18:09 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:18:09 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')]} 18:18:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:18:09 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')]} 18:18:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:18:09 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 0x175f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x192aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1acdf90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1acb130>, '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 0x1acc030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1acc4c0>, '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': 'eca2ab57546de7b41f256b19000001ef-1503859188.360365-1270311827', '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 18:18:09 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 18:18:09 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 18:18:09 INFO - Using env: {'DISPLAY': ':0', 18:18:09 INFO - 'HOME': '/home/cltbld', 18:18:09 INFO - 'LANG': 'en_US.UTF-8', 18:18:09 INFO - 'LANGUAGE': 'en_US:en', 18:18:09 INFO - 'LOGNAME': 'cltbld', 18:18:09 INFO - 'MAIL': '/var/mail/cltbld', 18:18:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:18:09 INFO - 'MOZ_NO_REMOTE': '1', 18:18:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:18:09 INFO - 'NO_EM_RESTART': '1', 18:18:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:18:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:18:09 INFO - 'PWD': '/builds/slave/test', 18:18:09 INFO - 'SHELL': '/bin/bash', 18:18:09 INFO - 'SHLVL': '1', 18:18:09 INFO - 'TERM': 'linux', 18:18:09 INFO - 'TMOUT': '86400', 18:18:09 INFO - 'USER': 'cltbld', 18:18:09 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1503859188.360365-1270311827', 18:18:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:18:09 INFO - '_': '/tools/buildbot/bin/python'} 18:18:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:18:10 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:18:10 INFO - Cleaning up... 18:18:10 INFO - Return code: 0 18:18:10 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:18:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:18:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:18:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:18:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:18:10 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x175f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x192aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1acdf90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1acb130>, '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 0x1acc030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1acc4c0>, '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': 'eca2ab57546de7b41f256b19000001ef-1503859188.360365-1270311827', '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 18:18:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 18:18:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 18:18:10 INFO - Using env: {'DISPLAY': ':0', 18:18:10 INFO - 'HOME': '/home/cltbld', 18:18:10 INFO - 'LANG': 'en_US.UTF-8', 18:18:10 INFO - 'LANGUAGE': 'en_US:en', 18:18:10 INFO - 'LOGNAME': 'cltbld', 18:18:10 INFO - 'MAIL': '/var/mail/cltbld', 18:18:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:18:10 INFO - 'MOZ_NO_REMOTE': '1', 18:18:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:18:10 INFO - 'NO_EM_RESTART': '1', 18:18:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:18:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:18:10 INFO - 'PWD': '/builds/slave/test', 18:18:10 INFO - 'SHELL': '/bin/bash', 18:18:10 INFO - 'SHLVL': '1', 18:18:10 INFO - 'TERM': 'linux', 18:18:10 INFO - 'TMOUT': '86400', 18:18:10 INFO - 'USER': 'cltbld', 18:18:10 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1503859188.360365-1270311827', 18:18:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:18:10 INFO - '_': '/tools/buildbot/bin/python'} 18:18:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:18:10 INFO - Downloading/unpacking blobuploader==1.2.4 18:18:10 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 18:18:10 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 18:18:10 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 18:18:10 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 18:18:11 INFO - Downloading blobuploader-1.2.4.tar.gz 18:18:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:18:11 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:18: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 18:18: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 18:18: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 18:18: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 18:18:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:18:11 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:18: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 18:18: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 18:18: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 18:18: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 18:18:11 INFO - Downloading docopt-0.6.1.tar.gz 18:18:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:18:12 INFO - Installing collected packages: blobuploader, requests, docopt 18:18:12 INFO - Running setup.py install for blobuploader 18:18:12 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:18:12 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:18:12 INFO - Running setup.py install for requests 18:18:12 INFO - Running setup.py install for docopt 18:18:12 INFO - Successfully installed blobuploader requests docopt 18:18:12 INFO - Cleaning up... 18:18:12 INFO - Return code: 0 18:18:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:18:12 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')]} 18:18:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:18:12 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')]} 18:18:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:18:12 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 0x175f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x192aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1acdf90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1acb130>, '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 0x1acc030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1acc4c0>, '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': 'eca2ab57546de7b41f256b19000001ef-1503859188.360365-1270311827', '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 18:18:12 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 18:18:12 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 18:18:12 INFO - Using env: {'DISPLAY': ':0', 18:18:12 INFO - 'HOME': '/home/cltbld', 18:18:12 INFO - 'LANG': 'en_US.UTF-8', 18:18:12 INFO - 'LANGUAGE': 'en_US:en', 18:18:12 INFO - 'LOGNAME': 'cltbld', 18:18:12 INFO - 'MAIL': '/var/mail/cltbld', 18:18:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:18:12 INFO - 'MOZ_NO_REMOTE': '1', 18:18:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:18:12 INFO - 'NO_EM_RESTART': '1', 18:18:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:18:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:18:12 INFO - 'PWD': '/builds/slave/test', 18:18:12 INFO - 'SHELL': '/bin/bash', 18:18:12 INFO - 'SHLVL': '1', 18:18:12 INFO - 'TERM': 'linux', 18:18:12 INFO - 'TMOUT': '86400', 18:18:12 INFO - 'USER': 'cltbld', 18:18:12 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1503859188.360365-1270311827', 18:18:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:18:12 INFO - '_': '/tools/buildbot/bin/python'} 18:18:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:18:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:18:12 INFO - Running setup.py (path:/tmp/pip-TEpq4y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:18:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:18:12 INFO - Running setup.py (path:/tmp/pip-7ixsvD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:18:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:18:12 INFO - Running setup.py (path:/tmp/pip-KXR1qH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:18:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:18:13 INFO - Running setup.py (path:/tmp/pip-ZBHNXQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:18:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:18:13 INFO - Running setup.py (path:/tmp/pip-1FGYtd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:18:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:18:13 INFO - Running setup.py (path:/tmp/pip-Qz6cDU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:18:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:18:13 INFO - Running setup.py (path:/tmp/pip-LoqLcu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:18:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:18:13 INFO - Running setup.py (path:/tmp/pip-EAFdpt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:18:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:18:13 INFO - Running setup.py (path:/tmp/pip-0D0U_S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:18:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:18:13 INFO - Running setup.py (path:/tmp/pip-KEVJPj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:18:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:18:13 INFO - Running setup.py (path:/tmp/pip-fvzf0g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:18:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:18:13 INFO - Running setup.py (path:/tmp/pip-jiH457-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:18:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:18:13 INFO - Running setup.py (path:/tmp/pip-IrLUdQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:18:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:18:13 INFO - Running setup.py (path:/tmp/pip-S6qCFK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:18:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:18:13 INFO - Running setup.py (path:/tmp/pip-YrC1XE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:18:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:18:13 INFO - Running setup.py (path:/tmp/pip-2QS4mk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:18:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:18:13 INFO - Running setup.py (path:/tmp/pip-29Ok03-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:18:14 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:18:14 INFO - Running setup.py install for manifestparser 18:18:14 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:18:14 INFO - Running setup.py install for mozcrash 18:18:14 INFO - Running setup.py install for mozdebug 18:18:14 INFO - Running setup.py install for mozdevice 18:18:14 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:18:14 INFO - Running setup.py install for mozfile 18:18:14 INFO - Running setup.py install for mozhttpd 18:18:14 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:18:14 INFO - Running setup.py install for mozinfo 18:18:14 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:18:14 INFO - Running setup.py install for mozInstall 18:18:14 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:18:14 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:18:14 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:18:14 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:18:14 INFO - Running setup.py install for mozleak 18:18:14 INFO - Running setup.py install for mozlog 18:18:15 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:18:15 INFO - Running setup.py install for moznetwork 18:18:15 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:18:15 INFO - Running setup.py install for mozprocess 18:18:15 INFO - Running setup.py install for mozprofile 18:18:15 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:18:15 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:18:15 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:18:15 INFO - Running setup.py install for mozrunner 18:18:15 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:18:15 INFO - Running setup.py install for mozscreenshot 18:18:15 INFO - Running setup.py install for moztest 18:18:15 INFO - Running setup.py install for mozversion 18:18:15 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:18:15 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:18:15 INFO - Cleaning up... 18:18:15 INFO - Return code: 0 18:18:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:18:15 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')]} 18:18:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:18:15 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')]} 18:18:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:18:15 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 0x175f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x192aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1acdf90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1acb130>, '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 0x1acc030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1acc4c0>, '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': 'eca2ab57546de7b41f256b19000001ef-1503859188.360365-1270311827', '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 18:18:15 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 18:18:15 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 18:18:15 INFO - Using env: {'DISPLAY': ':0', 18:18:15 INFO - 'HOME': '/home/cltbld', 18:18:15 INFO - 'LANG': 'en_US.UTF-8', 18:18:15 INFO - 'LANGUAGE': 'en_US:en', 18:18:15 INFO - 'LOGNAME': 'cltbld', 18:18:15 INFO - 'MAIL': '/var/mail/cltbld', 18:18:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:18:15 INFO - 'MOZ_NO_REMOTE': '1', 18:18:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:18:15 INFO - 'NO_EM_RESTART': '1', 18:18:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:18:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:18:15 INFO - 'PWD': '/builds/slave/test', 18:18:15 INFO - 'SHELL': '/bin/bash', 18:18:15 INFO - 'SHLVL': '1', 18:18:15 INFO - 'TERM': 'linux', 18:18:15 INFO - 'TMOUT': '86400', 18:18:15 INFO - 'USER': 'cltbld', 18:18:15 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1503859188.360365-1270311827', 18:18:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:18:15 INFO - '_': '/tools/buildbot/bin/python'} 18:18:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:18:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:18:15 INFO - Running setup.py (path:/tmp/pip-ehir5P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:18:16 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)) 18:18:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:18:16 INFO - Running setup.py (path:/tmp/pip-EI9TO9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:18:16 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)) 18:18:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:18:16 INFO - Running setup.py (path:/tmp/pip-JhHvNy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:18:16 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)) 18:18:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:18:16 INFO - Running setup.py (path:/tmp/pip-TqGMfO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:18:16 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)) 18:18:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:18:16 INFO - Running setup.py (path:/tmp/pip-zS8Hbs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:18:16 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)) 18:18:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:18:16 INFO - Running setup.py (path:/tmp/pip-wi0S6K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:18:16 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)) 18:18:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:18:16 INFO - Running setup.py (path:/tmp/pip-zTFg9l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:18:16 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)) 18:18:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:18:16 INFO - Running setup.py (path:/tmp/pip-QC0nEJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:18:16 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)) 18:18:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:18:16 INFO - Running setup.py (path:/tmp/pip-NJGwGU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:18:16 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)) 18:18:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:18:16 INFO - Running setup.py (path:/tmp/pip-lSdUO8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:18:16 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)) 18:18:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:18:16 INFO - Running setup.py (path:/tmp/pip-7UbAGo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:18:16 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)) 18:18:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:18:16 INFO - Running setup.py (path:/tmp/pip-4V4vyA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:18:16 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)) 18:18:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:18:16 INFO - Running setup.py (path:/tmp/pip-UzK5mm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:18:16 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)) 18:18:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:18:16 INFO - Running setup.py (path:/tmp/pip-SHuEXj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:18:16 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)) 18:18:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:18:17 INFO - Running setup.py (path:/tmp/pip-gaJrej-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:18:17 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)) 18:18:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:18:17 INFO - Running setup.py (path:/tmp/pip-eeOXnl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:18:17 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)) 18:18:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:18:17 INFO - Running setup.py (path:/tmp/pip-RWVDcx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:18:17 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)) 18:18:17 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)) 18:18:17 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)) 18:18:17 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)) 18:18:17 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)) 18:18:17 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)) 18:18:17 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)) 18:18:17 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:18:17 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 18:18:17 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 18:18:17 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 18:18:17 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 18:18:18 INFO - Downloading blessings-1.6.tar.gz 18:18:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:18:18 INFO - Installing collected packages: blessings 18:18:18 INFO - Running setup.py install for blessings 18:18:18 INFO - Successfully installed blessings 18:18:18 INFO - Cleaning up... 18:18:18 INFO - Return code: 0 18:18:18 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:18:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:18:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:18:18 INFO - Reading from file tmpfile_stdout 18:18:18 INFO - Current package versions: 18:18:18 INFO - argparse == 1.2.1 18:18:18 INFO - blessings == 1.6 18:18:18 INFO - blobuploader == 1.2.4 18:18:18 INFO - docopt == 0.6.1 18:18:18 INFO - functools32 == 3.2.3-2 18:18:18 INFO - jsonschema == 2.5.1 18:18:18 INFO - manifestparser == 1.1 18:18:18 INFO - mozInstall == 1.13 18:18:18 INFO - mozcrash == 1.0 18:18:18 INFO - mozdebug == 0.1 18:18:18 INFO - mozdevice == 0.51 18:18:18 INFO - mozfile == 1.2 18:18:18 INFO - mozhttpd == 0.7 18:18:18 INFO - mozinfo == 0.10 18:18:18 INFO - mozleak == 0.1 18:18:18 INFO - mozlog == 3.5 18:18:18 INFO - moznetwork == 0.27 18:18:18 INFO - mozprocess == 0.25 18:18:18 INFO - mozprofile == 0.28 18:18:18 INFO - mozrunner == 6.13 18:18:18 INFO - mozscreenshot == 0.1 18:18:18 INFO - mozsystemmonitor == 0.3 18:18:18 INFO - moztest == 0.8 18:18:18 INFO - mozversion == 1.4 18:18:18 INFO - psutil == 3.1.1 18:18:18 INFO - requests == 1.2.3 18:18:18 INFO - wsgiref == 0.1.2 18:18:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:18:18 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')]} 18:18:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:18:18 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')]} 18:18:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:18:18 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 0x175f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x192aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1acdf90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1acb130>, '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 0x1acc030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1acc4c0>, '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': 'eca2ab57546de7b41f256b19000001ef-1503859188.360365-1270311827', '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 18:18:18 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 18:18:18 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 18:18:18 INFO - Using env: {'DISPLAY': ':0', 18:18:18 INFO - 'HOME': '/home/cltbld', 18:18:18 INFO - 'LANG': 'en_US.UTF-8', 18:18:18 INFO - 'LANGUAGE': 'en_US:en', 18:18:18 INFO - 'LOGNAME': 'cltbld', 18:18:18 INFO - 'MAIL': '/var/mail/cltbld', 18:18:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:18:18 INFO - 'MOZ_NO_REMOTE': '1', 18:18:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:18:18 INFO - 'NO_EM_RESTART': '1', 18:18:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:18:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:18:18 INFO - 'PWD': '/builds/slave/test', 18:18:18 INFO - 'SHELL': '/bin/bash', 18:18:18 INFO - 'SHLVL': '1', 18:18:18 INFO - 'TERM': 'linux', 18:18:18 INFO - 'TMOUT': '86400', 18:18:18 INFO - 'USER': 'cltbld', 18:18:18 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1503859188.360365-1270311827', 18:18:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:18:18 INFO - '_': '/tools/buildbot/bin/python'} 18:18:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:18:18 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)) 18:18:18 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)) 18:18:18 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)) 18:18:18 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)) 18:18:18 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)) 18:18:18 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)) 18:18:18 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)) 18:18:18 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)) 18:18:18 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)) 18:18:18 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 18:18:18 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 18:18:18 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 18:18:18 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 18:18:18 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 18:18:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 18:18:20 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)) 18:18:20 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)) 18:18:20 INFO - Installing collected packages: simplejson 18:18:20 INFO - Running setup.py install for simplejson 18:18:20 INFO - building 'simplejson._speedups' extension 18:18:20 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 18:18:21 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 18:18:21 INFO - Successfully installed simplejson 18:18:21 INFO - Cleaning up... 18:18:21 INFO - Return code: 0 18:18:21 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:18:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:18:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:18:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:18:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:18:21 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 0x175f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x192aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1acdf90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1acb130>, '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 0x1acc030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1acc4c0>, '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': 'eca2ab57546de7b41f256b19000001ef-1503859188.360365-1270311827', '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 18:18:21 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 18:18:21 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 18:18:21 INFO - Using env: {'DISPLAY': ':0', 18:18:21 INFO - 'HOME': '/home/cltbld', 18:18:21 INFO - 'LANG': 'en_US.UTF-8', 18:18:21 INFO - 'LANGUAGE': 'en_US:en', 18:18:21 INFO - 'LOGNAME': 'cltbld', 18:18:21 INFO - 'MAIL': '/var/mail/cltbld', 18:18:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:18:21 INFO - 'MOZ_NO_REMOTE': '1', 18:18:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:18:21 INFO - 'NO_EM_RESTART': '1', 18:18:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:18:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:18:21 INFO - 'PWD': '/builds/slave/test', 18:18:21 INFO - 'SHELL': '/bin/bash', 18:18:21 INFO - 'SHLVL': '1', 18:18:21 INFO - 'TERM': 'linux', 18:18:21 INFO - 'TMOUT': '86400', 18:18:21 INFO - 'USER': 'cltbld', 18:18:21 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1503859188.360365-1270311827', 18:18:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:18:21 INFO - '_': '/tools/buildbot/bin/python'} 18:18:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:18:21 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:18:21 INFO - Cleaning up... 18:18:21 INFO - Return code: 0 18:18:21 INFO - Running post-action listener: _resource_record_post_action 18:18:21 INFO - Running post-action listener: _start_resource_monitoring 18:18:21 INFO - Starting resource monitoring. 18:18:21 INFO - [mozharness: 2017-08-28 01:18:21.460822Z] Finished create-virtualenv step (success) 18:18:21 INFO - [mozharness: 2017-08-28 01:18:21.461348Z] Running install step. 18:18:21 INFO - Running pre-action listener: _resource_record_pre_action 18:18:21 INFO - Running main action method: install 18:18:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:18:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:18:21 INFO - Reading from file tmpfile_stdout 18:18:21 INFO - Detecting whether we're running mozinstall >=1.0... 18:18:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:18:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:18:21 INFO - Reading from file tmpfile_stdout 18:18:21 INFO - Output received: 18:18:21 INFO - Usage: mozinstall [options] installer 18:18:21 INFO - Options: 18:18:21 INFO - -h, --help show this help message and exit 18:18:21 INFO - -d DEST, --destination=DEST 18:18:21 INFO - Directory to install application into. [default: 18:18:21 INFO - "/builds/slave/test"] 18:18:21 INFO - --app=APP Application being installed. [default: firefox] 18:18:21 INFO - mkdir: /builds/slave/test/build/application 18:18:21 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'] 18:18:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 18:18:39 INFO - Reading from file tmpfile_stdout 18:18:39 INFO - Output received: 18:18:39 INFO - /builds/slave/test/build/application/firefox/firefox 18:18:39 INFO - Running post-action listener: _resource_record_post_action 18:18:39 INFO - [mozharness: 2017-08-28 01:18:39.462164Z] Finished install step (success) 18:18:39 INFO - [mozharness: 2017-08-28 01:18:39.462509Z] Running setup-mitmproxy step. 18:18:39 INFO - Running pre-action listener: _resource_record_pre_action 18:18:39 INFO - Running main action method: setup_mitmproxy 18:18:39 INFO - Skipping: mitmproxy is not required 18:18:39 INFO - Running post-action listener: _resource_record_post_action 18:18:39 INFO - [mozharness: 2017-08-28 01:18:39.464118Z] Finished setup-mitmproxy step (success) 18:18:39 INFO - [mozharness: 2017-08-28 01:18:39.464430Z] Running run-tests step. 18:18:39 INFO - Running pre-action listener: _resource_record_pre_action 18:18:39 INFO - Running pre-action listener: _set_gcov_prefix 18:18:39 INFO - Running main action method: run_tests 18:18:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:18:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:18:39 INFO - Python 2.7.3 18:18:39 INFO - Return code: 0 18:18:39 INFO - grabbing minidump binary from tooltool 18:18:39 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')]} 18:18:39 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 0x1acb130>, '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 0x1acc030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1acc4c0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:18:39 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 18:18:39 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 18:18:39 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 18:18:39 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:18:39 INFO - Return code: 0 18:18:39 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 18:18:39 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:18:39 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:18:39 INFO - ENV: RUST_BACKTRACE is now full 18:18:39 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 18:18:39 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:18:39 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/FgF7GK1MTSiueBqY-_8-1Q/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-010', '--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 18:18:39 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/FgF7GK1MTSiueBqY-_8-1Q/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-010 --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 18:18:39 INFO - Using env: {'DISPLAY': ':0', 18:18:39 INFO - 'HOME': '/home/cltbld', 18:18:39 INFO - 'LANG': 'en_US.UTF-8', 18:18:39 INFO - 'LANGUAGE': 'en_US:en', 18:18:39 INFO - 'LOGNAME': 'cltbld', 18:18:39 INFO - 'MAIL': '/var/mail/cltbld', 18:18:39 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:18:39 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:18:39 INFO - 'MOZ_ACCELERATED': '1', 18:18:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:18:39 INFO - 'MOZ_NO_REMOTE': '1', 18:18:39 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:18:39 INFO - 'MOZ_WEBRENDER': '1', 18:18:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:18:39 INFO - 'NO_EM_RESTART': '1', 18:18:39 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:18:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:18:39 INFO - 'PWD': '/builds/slave/test', 18:18:39 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:18:39 INFO - 'RUST_BACKTRACE': 'full', 18:18:39 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 18:18:39 INFO - 'SHELL': '/bin/bash', 18:18:39 INFO - 'SHLVL': '1', 18:18:39 INFO - 'TERM': 'linux', 18:18:39 INFO - 'TMOUT': '86400', 18:18:39 INFO - 'USER': 'cltbld', 18:18:39 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1503859188.360365-1270311827', 18:18:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:18:39 INFO - '_': '/tools/buildbot/bin/python'} 18:18:39 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/FgF7GK1MTSiueBqY-_8-1Q/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-010', '--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 18:18:39 INFO - mozversion application_buildid: 20170828003210 18:18:39 INFO - mozversion application_changeset: d10c97627b51a226e19d0fa801201897fe1932f6 18:18:39 INFO - mozversion application_display_name: Nightly 18:18:39 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:18:39 INFO - mozversion application_name: Firefox 18:18:39 INFO - mozversion application_remotingname: firefox 18:18:39 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 18:18:39 INFO - mozversion application_vendor: Mozilla 18:18:39 INFO - mozversion application_version: 57.0a1 18:18:39 INFO - mozversion platform_buildid: 20170828003210 18:18:39 INFO - mozversion platform_changeset: d10c97627b51a226e19d0fa801201897fe1932f6 18:18:39 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 18:18:39 INFO - mozversion platform_version: 57.0a1 18:18:39 INFO - using testdate: 1503883119 18:18:39 INFO - actual date: 1503883119 18:18:39 INFO - starting webserver on 'localhost:35627' 18:18:39 INFO - SUITE-START | Running 2 tests 18:18:39 INFO - TEST-START | dromaeo_css 18:18:39 INFO - Initialising browser for dromaeo_css test... 18:18:39 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:35627/getInfo.html -profile /tmp/tmpqaEvZp/profile 18:18:39 INFO - TEST-INFO | started process 29971 (/builds/slave/test/build/application/firefox/firefox http://localhost:35627/getInfo.html) 18:18:46 INFO - TEST-INFO | 29971: exit 0 18:18:46 INFO - Browser initialized. 18:18:46 INFO - Running cycle 1/1 for dromaeo_css test... 18:18:46 INFO - Using env: {'DISPLAY': ':0', 18:18:46 INFO - 'HOME': '/home/cltbld', 18:18:46 INFO - 'JSGC_DISABLE_POISONING': '1', 18:18:46 INFO - 'LANG': 'en_US.UTF-8', 18:18:46 INFO - 'LANGUAGE': 'en_US:en', 18:18:46 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 18:18:46 INFO - 'LOGNAME': 'cltbld', 18:18:46 INFO - 'MAIL': '/var/mail/cltbld', 18:18:46 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:18:46 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:18:46 INFO - 'MOZ_ACCELERATED': '1', 18:18:46 INFO - 'MOZ_CRASHREPORTER': '1', 18:18:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:18:46 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 18:18:46 INFO - 'MOZ_NO_REMOTE': '1', 18:18:46 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:18:46 INFO - 'MOZ_WEBRENDER': '1', 18:18:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:18:46 INFO - 'NO_EM_RESTART': '1', 18:18:46 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:18:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:18:46 INFO - 'PWD': '/builds/slave/test', 18:18:46 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:18:46 INFO - 'RUST_BACKTRACE': 'full', 18:18:46 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 18:18:46 INFO - 'SHELL': '/bin/bash', 18:18:46 INFO - 'SHLVL': '1', 18:18:46 INFO - 'TERM': 'linux', 18:18:46 INFO - 'TMOUT': '86400', 18:18:46 INFO - 'USER': 'cltbld', 18:18:46 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1503859188.360365-1270311827', 18:18:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:18:46 INFO - '_': '/tools/buildbot/bin/python'} 18:18:46 INFO - TEST-INFO | started process 30192 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpqaEvZp/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 18:18:47 INFO - PID 30192 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 18:18:47 INFO - PID 30192 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 18:18:47 INFO - PID 30192 | 18:18:47 INFO - PID 30192 | (/builds/slave/test/build/application/firefox/firefox:30251): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 18:18:47 INFO - PID 30192 | 18:18:48 INFO - PID 30192 | 18:18:48 INFO - PID 30192 | (/builds/slave/test/build/application/firefox/firefox:30309): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 18:18:48 INFO - PID 30192 | 18:18:48 INFO - PID 30192 | __metrics Screen width/height:1600/1200 18:18:48 INFO - PID 30192 | colorDepth:24 18:18:48 INFO - PID 30192 | Browser inner width/height: 1024/768 18:18:48 INFO - PID 30192 | __metrics 18:18:58 INFO - PID 30192 | RSS: Main: 203399168 18:18:58 INFO - PID 30192 | 18:19:01 INFO - PID 30192 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 18:21:57 INFO - PID 30192 | Cycle 1(1): loaded http://localhost:35627/tests/dromaeo/cssquery-dojo.html (next: http://localhost:35627/tests/dromaeo/cssquery-ext.html) 18:21:58 INFO - PID 30192 | RSS: Main: 218435584 18:21:58 INFO - PID 30192 | 18:25:00 INFO - PID 30192 | Cycle 1(1): loaded http://localhost:35627/tests/dromaeo/cssquery-ext.html (next: http://localhost:35627/tests/dromaeo/cssquery-jquery.html) 18:25:01 INFO - PID 30192 | RSS: Main: 211869696 18:25:01 INFO - PID 30192 | 18:28:03 INFO - PID 30192 | Cycle 1(1): loaded http://localhost:35627/tests/dromaeo/cssquery-jquery.html (next: http://localhost:35627/tests/dromaeo/cssquery-mootools.html) 18:28:04 INFO - PID 30192 | RSS: Main: 213954560 18:28:04 INFO - PID 30192 | 18:31:06 INFO - PID 30192 | Cycle 1(1): loaded http://localhost:35627/tests/dromaeo/cssquery-mootools.html (next: http://localhost:35627/tests/dromaeo/cssquery-prototype.html) 18:31:06 INFO - PID 30192 | RSS: Main: 212918272 18:31:06 INFO - PID 30192 | 18:34:10 INFO - PID 30192 | Cycle 1(1): loaded http://localhost:35627/tests/dromaeo/cssquery-prototype.html (next: http://localhost:35627/tests/dromaeo/cssquery-yui.html) 18:34:11 INFO - PID 30192 | RSS: Main: 211263488 18:34:11 INFO - PID 30192 | 18:37:13 INFO - PID 30192 | Cycle 1(1): loaded http://localhost:35627/tests/dromaeo/cssquery-yui.html (next: http://localhost:35627/tests/dromaeo/cssquery-dojo.html) 18:37:13 INFO - PID 30192 | RSS: Main: 216592384 18:37:13 INFO - PID 30192 | 18:37:13 INFO - PID 30192 | __start_tp_report 18:37:13 INFO - PID 30192 | _x_x_mozilla_page_load 18:37:13 INFO - PID 30192 | _x_x_mozilla_page_load_details 18:37:13 INFO - PID 30192 | |i|pagename|runs| 18:37:13 INFO - PID 30192 | |0;dojo.html;3235.3529294141176;3269.4278501262274;3269.8855540056097;3284.6551112133225;3294.1435226841013;6236.189295391604;6287.1198032275515;6307.117003619491;6322.272938612061;6382.170317858678;6323.30443651198;6390.840228994278;6401.0398440233985;6409.198850143732;6411.070394792752;5791.536677065825;5806.332271788756;5833.125031245305;5849.619774714652;5890.64656120632;5842.737076831553;5884.6763428011445;5932.525397968152;5979.491743201833;5997.37027612101;5843.737031833578;5902.114682797572;5929.377415371388;5935.525157987372;5938.198343223674;5648.887022259573;5794.362620111784;5875.7062146892695;5967.4629283364275;5971.581989260753;5306.151015837486;5709.886572118438;5781.971090144522;5806.216160818278;5810.796622118248;6289.245290565162;6351.301356850743;6406.4554512866;6424.518161137933;6446.194225721785;6691.397774200345;6725.865482690368;6731;6801.863962720768;6854.383105520527;6903.206131294915;6908.0674108995645;6913.827154321132;6920.30796920309;6929.133858267716;6291.1192433059405;6321.431071203614;6412.39082287182;6412.39082287182;6418.422341989243;6887.827804304932;6984.161900571964;7032.507724459341;7065.187503437068;7066.3993560546905;6533.150690410216;6639.738449694639;6656.667166641649;6704.96647516769;6742.089817874622;6704.99425086241;6844.555103918229;6869.244383117853;6870.27862074475;6896.137982752156;6769.42459890905;6829.419499342513;6866.793996180123;6871;6883.690233939485;6751.156105486814;6758.729650814011;6773.322667733188;6808.148981377328;6814.352636499525;5299.549538289211;5344.251804747339;5346;5373.462653734595;5373.892522149613;4604.516525764813;4645.372874661945;4648.163330600457;4648.86053418398;4663.906721865544;5944.524438044946;5959.404059594006;6008.789692360747;6035;6038.667873266924;6864.725410983605;6872.175338959257;6876.037354770337;6901.068355772007;6910.101686780787;5937.346891841894;5975.940240597625;5995.280566332068;6049.8487537811025;6058.212432383762;5755.539556835443;5871.765129394862;5902.468777810018;5926.940730592725;5938.910916336258;6412.23053233615;6446.967765161145;6543.869122617521;6550.868982620321;6557.245916719543;6825.180978282638;6856.485763567852;6856.90289553669;6877.037214789833;6877.449804015567;6394.840128996626;6506.284308725942;6579.703913323816;6585.473162147113;6586;6733.7306507741105;6737.562058466184;6748.797536073926;6827.863442731217;6844.110265665432;6685.498587606046;6749.730010799513;6772.390484856386;6843.692033858389;6848.246692863681;6614.867702646016;6622.106015688013;6708.42978346846;6711.865762684816;6712.9664351679885;6628.602283862983;6650.068990341453;6673.866522669617;6702.396784289437;6713.295104014008;6540.018997150465;6565.638889861103;6638.402543771083;6661.533692641662;6672.266050734318;6586.308437613981;6715.899261510984;6756.29058948823;6811.659417029228;6820.147481564804;6323.1463752394675;6429.58207716497;6516.283208846929;6518.478521718346;6526.44525215343 18:37:13 INFO - PID 30192 | |1;ext.html;767.1944458318764;770.3875419041862;789.5144486141022;814.2142832166958;818.0428898189118;40220;40573.79713101438;41046.384304235464;41148.17703645925;41223.58776412235;37190.62809371905;37372.43941340882;37532;37555.62444375562;37658.246835063284;6815.352541508573;6892.379685828275;6943.7569685061035;6961.860762784741;6964.338387853146;6859.725610975555;6895.310468953115;6908.689108990107;6930.653467326639;6945.201301850276;6875.7937261882225;6922.619255940921;7634.312911837934;7842.137364889858;7871.9212807872045;7794.922050779504;7833.843323133506;7856.096548896893;7857.960710196441;7864.449488535776;7686.654100565488;7688.6155692215725;7733;7746.225377462265;7785.532868027936;36205.27589448223;36575;36656;36733;36761.26477470436;3389.4746314321073;3430.7255419566377;3450.741194410429;3456.7580269381156;3474.1488335358;30549.389012219854;30703.539446908315;30870.845645771853;30943.845280773676;30985;4985.875353116165;5018.64869459138;5074.67014644047;5090.312807770941;5106.463821298785;180556;181550;182214;183611;191837.9999999986;48917.755411222715;49038.26442603364;49329;49532;49593.752031240336;49639.25541116886;49672.751636241584;49677.26129585447;50133.498665012885;50280.24579631309;14498.275086245645;14527.346269417902;14608;14631.122132672073;14667.413303467742;11955.342456164994;11958.402079896145;11978.580749673894;11981.820272695917;11987.760244795056;13878.375473103735;13924;13936.372863221179;14091.154530728189;14326.355314010894;5682.147677848273;5683.943160568424;5711.400302968129;5716.5140963017775;5842;985.8521221816785;986.146982859821;993.215359865698;999.6601155607128;1006.0442579549457;27744;28335.71664283372;28406;28855.42289154205;29536.113916582537;1028.881678606955;1052.494802494807;1065.0521036277719;1074.1782536359692;1080.7514271717391;11947.654656696139;11949.581764638197;12142.939285303693;12210.26738395699;12213.206141600767;238986;244911;245290;245591;245977;57818;58167.4183258162;58455;58539.12191317134;58608.41391586029;1432;1447.6597999470328;1464.4801095610906;1465.6775509387917;1465.6848777512887;27372;27531.83928811386;27628.171154865388;27805;28149.859250703616;37930.241395172496;38235.61764382321;38238;38296.808515957244;38307.808460957516;149595.2520237392;149657;150301.24849375684;150363;150806;29479.55780663249;31119;31223.219419514695;31240.531392029592;35061;42756;42789;43482.782586088135;43577;43627;33414.33171336511;34284.48573271453;34330;34351.48472772961;34477.82761086179;33077.338453231285;33569.66430335665;33593;33963;34321.82839085789;37630.62369376271;37755.81122094372;37835.243295134496;38060;38796.41805372978;32740;32838.83580582082;32881.67118328882;32897.83551082229;33832;23912.163074292315;24052.39869003219;24154.03383864696;24532.877335613208;24984.750152498244 18:37:13 INFO - PID 30192 | |2;jquery.html;555.6471640508279;556.2990631803929;561.3516388571202;570.9257796486456;572.201778518966;22216.222432214876;22359.552808943812;23136.190233341837;23219.419514512145;23284.65073023904;25029.74970250297;25402.618960715605;25528.872355638196;25543.872280638618;25580.360490987736;6561.835954101138;6708.631025293607;6730.158730158731;6734.225564060122;6740.6629668516625;5792.507636850873;6366.299707032223;6670.032845237438;6756.054152418666;6764.898526522106;6304.747810087591;6325.968370158166;6326.841828954267;6332.746690132389;6371.8407039823915;6332.620042797447;6349.71426285816;6377.8405539861415;6381.712822922957;6388.8083357499345;5907.379725128842;5923.614965027303;5928.7628494860155;5943.31651860033;5966.3138739045135;27035.324116897038;28428.71571284302;28697.139085827457;29505.70494295051;29566.556501652278;2004.0480771633358;2021.7674967378794;2047.8566500344982;2051.7435320584927;2059.5468996820678;18479.353222637143;19107.426777196706;19196.136173872208;19245.903770481058;19246.4226073218;2338.4972230970425;2364.0780095762666;2431.598786200275;2444.4866578018637;2453.2027091195255;127543.36228318892;134906;135142.32428837792;135229;135815;13917.86082139186;14203.573892783026;14410.351534180778;14455.710885782226;14978.47575334858;22646.094156233896;22653.320400387685;22677.433064173198;22694.546109077724;22742.886285568467;9508.144267015863;9512.28657850664;9551.331406801597;9816;9848.50757462124;8763.912360876437;8855.64577416909;8975.596098175598;9060.864087038699;9221.769455763659;8387.412881098386;8964.14840590143;9170.954145229365;9312.8603070954;9562.95218523903;2745.203890871625;2786.818856774303;2792.5339196921373;2844.3031457293096;2867.641544806899;923.6490133749132;964.8118616869643;973.464594473037;973.6056896956744;975.6195083917278;19413;19476.51308717265;19593.706094408597;19653.50866228327;19745;1023.8057261344652;1024.2267088348249;1032.6385764982197;1040.6201736366172;1045.038564520643;10835.51240194193;10839.512221949873;10967.780644387227;11030.944845275882;11058.557657693764;481997;484512;493060;513870;515653;119437.40281298711;122704.38647806883;123077.38461307636;125591;126056;1202.3327053485225;1224.9571265005682;1238.2137342787396;1240.231056744824;1251.5369313354117;20754.481137971066;20784.58430831405;21054.68417973701;21233.78766212318;21255.149794008066;59778;60723.69638151958;60806.39193608007;61081.69459152676;63298;73883;74413;74794;75214.62392688001;76197.61901190458;14281.571552853431;14701.485448009269;14813.111213327235;14822.40710371573;14932.954693171374;23143.305700829;23243.651345230173;23253.18613848507;23405.648915266625;23447.17934872271;18547.814521855147;18550.350737724115;18573;18580.814191858448;18582.907085464485;15665.765013525035;15978;16385.754213686567;16733.497995060166;16764.413245536347;18881.71677424867;19007.524811879815;19433.931133787777;19570.412887613395;19713.60572788565;13864.792028119788;14616.123032618078;14622.341994610055;14649.340779664728;14662.780058299348;17916.104194789947;18646;19064.809351906857;19107.713384299524;19179.712304315726 18:37:13 INFO - PID 30192 | |3;mootools.html;1307.5031488034545;1360.0139898573536;1363.7791036741282;1366.952156674516;1367.9521216757412;12517.812232816515;14271.357788899499;14756.188409637465;14874.925625371885;14888.553343399702;12506.81239781404;14586.197759123244;14915.477958271462;14925.701485970301;14967.700645987074;2660.2285337252224;2691.946161076787;2695.986520067397;2699.85150816705;2707.6344693466426;2504.5992641177413;2548.49030193961;2607.530644483992;2651.5227259093353;2665.4002849358935;2649.403884126066;2659.6542449481635;2685.7582817546418;2692.057779777082;2693.151657227967;2440.4386990991993;2470.8270421070533;2472;2653.6284920111198;2717.7825773938234;2608.0350270399886;2656.5882288245352;2660.188642464048;2670.9065182718514;2685.5434576122107;14753.631159220999;14785.556433307018;14790.70418591633;14860.256987150708;14874.405023799036;7587.506812057199;7861.685532578747;7862.331701805353;7868.213178682143;7881.133075361706;5350.464953504657;5354.464553544653;5460.972695136499;5562.193481945095;5617.578681598856;1905.7046157845557;1936.0900376822872;1980.99009504953;1985.265451782835;2299.1493147535352;25036.374090647696;25063.49873002548;25152;25201.62197567038;25442.236732897858;6550.853600619873;6561.80314590563;6641.734330626818;6750.122484077038;6802.727890884408;16363.100029498253;16365.59086022835;16368.181590920645;16380.754288685679;16396.26216820246;7204.53170543913;7285.854282914418;7287.963560182166;7311.524750891175;7321.707131714672;6649.700763465656;6666.533342665967;6738.59568425896;6753.49348798842;6798.150231221098;5150.4592017837585;5254.737263136828;5282.867928301823;5424.511793938565;5515.61390702647;1505.3000354835053;1537.7001484710374;1539.3457780443266;1557.6105973506624;1561.921903904823;841.1882533355341;848.245061894914;881.7795551112222;887;887.5695287785419;7146.821329466805;7256.238094999949;7261.818454538678;7289.271072892774;7303.817404564822;637.327619361578;648.9805305840832;650.4276236911583;653.5359894474976;654.5254690349553;4423.049044455457;4486.641068714495;5355.437679043722;5488.506034456838;5565.499105080481;19270.421887343404;20994.685079723815;21061.684074738892;21323.36029919105;21381.144754209967;19784.604307914047;20394.69407958882;20417.2853950114;20430.591388172154;20450.488737781674;8962.193402593668;10426.322289051188;10428.530716117793;10716;11041.668749937515;8127.512349259064;8309.584520774059;8378.748637540884;8382.916170838214;8392.28665563434;17545.385911493035;17793.288268469634;17962.730559041363;18300.90849545744;18689.065546722883;18407.263709451472;18510.352137675116;18774.249030038645;18840.905795470935;18893.811061889206;11143.777124457627;11287.20989530725;11390.487428065591;11527.827082593936;11566.479508421973;12957.093003490043;13039.282839443922;13041.217526948414;13223.404946777226;13251.536196233086;10726.624568140078;10818.891811082103;10955.890441095487;10969.451527423438;11074.280171788809;10077.949610251902;10241.129503991946;10268.58925642966;10421.895781042092;10541.367517948947;7954.284114429729;8550.273226775545;8716.78208044804;8727.607257673293;8948.284137269133;6533.216014078341;6558.57369271015;6588.374104460068;6592.274849766618;6620.337966203341;6465.579737317247;6629.370209830058;6747.898781518183;6857.451403887577;6877.140357455318 18:37:13 INFO - PID 30192 | |4;prototype.html;498.2001439884807;504.6408042401772;512.1626141259043;513.307035502072;515.8271978887072;6371.585846919947;6417.518686098544;6427.485801135904;6434.839129021777;6449.51628627853;6152.138700581922;6214.316425193237;6222.906656400158;6243.313235544098;6349.7142628581705;5241.5020573045695;5277.947220527793;5282.366116066059;5283.788648454057;5298.311219541454;5203.479652034785;5213.4525874782985;5245.4754524547625;5250.31745873036;5345.973270133644;4897.302536584873;5013.974930125344;5054.898902021957;5067.417247016585;5087.9745601272125;5020.347354843847;5031.798728050873;5071.0365030644425;5075.898482030376;5107.489251074881;4471.910561788779;4527.388802511652;4551.726896386227;4552.271636538171;4559;6225.097360882647;6256.812295631093;6280.434760871543;6292.968535157341;6293.811185664437;5808.448197421239;5829.300483941955;5844.503217226541;5864.882702345929;5875.148103524965;6610.768623098168;6663.13379260693;6667.133062823599;6707.6981535831;6717.731290748364;4808.158572249842;4847;4859.1496488114435;4892.706437613754;4902;6968.651567421609;7038.577685338845;7156.284371562802;7162.283771622848;7170.533915295489;4895.681780684244;4910.803567857317;4949.505049495022;4984.651074424828;4995.275685025651;5011.824586139468;5017.448080711119;5027.271045698353;5046;5060.873478163076;5681.233033540502;5688.658680479184;5688.9759843227785;5714.657055592016;5730.656160630376;6681.532292739559;6701.597904125768;6712.194536655535;6715.832104197337;6830.795076147723;1676.6311411489453;1730.4030109612415;1770.663573920951;1781.376518218639;1793;1214.9817752733716;1219.451246938881;1221.9633410997685;1224.6570960131178;1246.9127161098636;5866.4160676617075;5908.172855800191;5941.435563621449;5951.583389162717;6027.186329845415;6546.18172728409;6778.22050464193;6832.795016149524;6854.657267136723;6859.279775623488;5887.646741195542;5907.459792289357;5973.522118230618;6038.365971573009;6061.393860613904;3669.761465504787;3675.298655488214;3677;3701.6668499835146;3721.9627803721614;6558.311377305409;6565.934340656628;6572.507061970371;6603.7688680895935;6611.735530578723;7018.33325834045;7041.718331266795;7086;7102.041224434738;7129.465290103263;6507.739690412331;6570.536048981632;6624.8012559623285;6640.767573134821;6653.800385988428;6889.448844092561;6936.653167341714;6959.738085122254;6989.650517474004;7016.789496315118;6828.9317106830285;6847.390863147057;6864.5881247125335;6890.517663763489;6895.5173137879865;6508.284088750139;6540.280569137486;6604.405603495708;6617.966910165418;6637.435817955527;5035.672681275852;5040.974795126001;5073.416557095981;5073.670211436245;5075.137226671549;6812.965935170292;6835.521513494008;6867.931320686929;6924.238333783381;6934;6603.834904127435;6607.9669601651685;6642.966785166043;6661;6723;6632.568883022588;6637.818174550002;6642.269350371359;6653.434458070925;6712.49656275791;6340.239171299474;6565.179352580927;6648.268690444144;6692.631905245258;6743.966280168568;6052;6468.838279043061;6517.543771936109;6518.282988871315;6755.2231493378895;6419.175562752374;6490.8052758417325;6503.187101612299;6523.347665233438;6529.967350163409 18:37:13 INFO - PID 30192 | |5;yui.html;852.4373913217278;886.7206829848598;892.8928528576572;895.6148855991925;902.1745103230544;16309.918450407746;17351.826481735177;17353.56616084597;17414.82585174148;17415.390461333824;17112.572185695364;18626.348077817278;18698.532536686555;18780.342688005923;18827.52931176721;925.9120383563568;939.3659279986016;942.3356533643764;944.0748066894448;944.0795224655974;868.8088620503479;950.2255661635745;951.8001219743827;953.5232383808096;953.6090203016764;950.7908260182784;955.2071780422268;963.3160456076195;965.5413678502725;984.3503287830033;956.0248546482584;956.5886668732443;957.5738796235678;958.5638534466835;981.6711401680445;704.7815177295055;704.9330313620197;705.1995984557564;715.6350261366688;728.8177955511122;16777.748333775;16878.91560542202;16982.32070717182;17008.829911700974;17013.659726805396;21150.894245528827;21303.467413314636;21464.141434342608;21671.45821354479;21831.672524912137;16175.757363639555;16368.427105051265;16424.917875410545;16449.753253701205;16464.588385290463;3672.210474747915;3720.8511659533588;3735;3740.6446387593137;3743.0829446785438;124874;128195.35902320522;130203;131353.99999999904;131434.3428282862;16157.596060098356;16265.349386024664;16526.6694666106;16939.915300423418;16962.406315778888;29484.262893427407;29548.261293467407;29600.851995739882;29652.258693532833;29680.554791678143;8132.349412047022;8177.713780017671;8210.712625058095;8240.670373185005;8309.169083091643;4054.391841223781;4122.793860306972;4125.422440858282;4127.071408932966;4140.37894315849;4948.777305021283;5064.316317297192;5085.0338435697095;5087.643864929493;5087.745612719349;884.6638277454655;901.6078006067381;916.4272329793879;917.1928702741678;918.7886786039248;661.163628010567;684.4011489946297;684.7055766020559;689.1489011071322;702.8980797784293;10688.893111068945;10766.407847568458;11081.279716818543;12122.878771212176;12371.319577423328;655.3479288108363;664.8437617159964;666.643345809999;676.7022510095542;680.9421199198025;5164.715940623302;5360.892782144413;5507.697076660742;5823.213866128103;5874.853128671817;106270;109916.4504177474;110420.44789776161;113289.4335528317;115001.4249928745;96024.51987740156;97392.51303743436;97545;100353.49823250837;102007;87748.5612571933;87774;89862;90086.54956725174;90871.99999999868;10478.161747060065;10611.2041596879;10768.838467422838;10780.029797318279;10830.620928267474;39020.6097939017;40055.79972100121;40782;40806.591934081465;41703;51519.74240128775;51783.74108129435;51835;52468;53060.4693953071;14551.708965820835;14619.780703289247;14717.632059198606;14786;15079.170645614158;15979.440719574759;16226;16266.918665406598;16323.428679996143;16554.089525076237;13976.021678482408;16818.915905420396;18003.459896203134;18796.872187668785;18847.6230475387;16611.252493638058;16836;17301.653966920843;17458.650826983136;17532.82467175312;15953.361865525249;17128.14359282006;17180.570485737957;17643.117844108;17945.192466338784;11766.58816941403;11804.350760708243;11863.762724745284;12342.13605047638;12504.937475312565;13509.324533773075;13540.187588744708;13720.794188086988;13732.313384330544;13777.931110344383 18:37:13 INFO - PID 30192 | __end_tp_report 18:37:13 INFO - PID 30192 | __start_cc_report 18:37:13 INFO - PID 30192 | _x_x_mozilla_cycle_collect,354 18:37:13 INFO - PID 30192 | __end_cc_report 18:37:13 INFO - PID 30192 | __startTimestamp1503884233654__endTimestamp 18:37:13 INFO - PID 30192 | 18:37:13 INFO - PID 30192 | ------- Summary: start ------- 18:37:13 INFO - PID 30192 | Number of tests: 6 18:37:13 INFO - PID 30192 | 18:37:13 INFO - PID 30192 | [#0] dojo.html Cycles:165 Average:6275.57 Median:6518.48 stddev:745.88 (11.4%) stddev-sans-first:709.01 18:37:13 INFO - PID 30192 | Values: 3235.4 3269.4 3269.9 3284.7 3294.1 6236.2 6287.1 6307.1 6322.3 6382.2 6323.3 6390.8 6401.0 6409.2 6411.1 5791.5 5806.3 5833.1 5849.6 5890.6 5842.7 5884.7 5932.5 5979.5 5997.4 5843.7 5902.1 5929.4 5935.5 5938.2 5648.9 5794.4 5875.7 5967.5 5971.6 5306.2 5709.9 5782.0 5806.2 5810.8 6289.2 6351.3 6406.5 6424.5 6446.2 6691.4 6725.9 6731.0 6801.9 6854.4 6903.2 6908.1 6913.8 6920.3 6929.1 6291.1 6321.4 6412.4 6412.4 6418.4 6887.8 6984.2 7032.5 7065.2 7066.4 6533.2 6639.7 6656.7 6705.0 6742.1 6705.0 6844.6 6869.2 6870.3 6896.1 6769.4 6829.4 6866.8 6871.0 6883.7 6751.2 6758.7 6773.3 6808.1 6814.4 5299.5 5344.3 5346.0 5373.5 5373.9 4604.5 4645.4 4648.2 4648.9 4663.9 5944.5 5959.4 6008.8 6035.0 6038.7 6864.7 6872.2 6876.0 6901.1 6910.1 5937.3 5975.9 5995.3 6049.8 6058.2 5755.5 5871.8 5902.5 5926.9 5938.9 6412.2 6447.0 6543.9 6550.9 6557.2 6825.2 6856.5 6856.9 6877.0 6877.4 6394.8 6506.3 6579.7 6585.5 6586.0 6733.7 6737.6 6748.8 6827.9 6844.1 6685.5 6749.7 6772.4 6843.7 6848.2 6614.9 6622.1 6708.4 6711.9 6713.0 6628.6 6650.1 6673.9 6702.4 6713.3 6540.0 6565.6 6638.4 6661.5 6672.3 6586.3 6715.9 6756.3 6811.7 6820.1 6323.1 6429.6 6516.3 6518.5 6526.4 18:37:13 INFO - PID 30192 | 18:37:13 INFO - PID 30192 | [#1] ext.html Cycles:180 Average:36723.97 Median:35633.14 stddev:51115.68 (143.4%) stddev-sans-first:51187.77 18:37:13 INFO - PID 30192 | Values: 767.2 770.4 789.5 814.2 818.0 40220.0 40573.8 41046.4 41148.2 41223.6 37190.6 37372.4 37532.0 37555.6 37658.2 6815.4 6892.4 6943.8 6961.9 6964.3 6859.7 6895.3 6908.7 6930.7 6945.2 6875.8 6922.6 7634.3 7842.1 7871.9 7794.9 7833.8 7856.1 7858.0 7864.4 7686.7 7688.6 7733.0 7746.2 7785.5 36205.3 36575.0 36656.0 36733.0 36761.3 3389.5 3430.7 3450.7 3456.8 3474.1 30549.4 30703.5 30870.8 30943.8 30985.0 4985.9 5018.6 5074.7 5090.3 5106.5 180556.0 181550.0 182214.0 183611.0 191838.0 48917.8 49038.3 49329.0 49532.0 49593.8 49639.3 49672.8 49677.3 50133.5 50280.2 14498.3 14527.3 14608.0 14631.1 14667.4 11955.3 11958.4 11978.6 11981.8 11987.8 13878.4 13924.0 13936.4 14091.2 14326.4 5682.1 5683.9 5711.4 5716.5 5842.0 985.9 986.1 993.2 999.7 1006.0 27744.0 28335.7 28406.0 28855.4 29536.1 1028.9 1052.5 1065.1 1074.2 1080.8 11947.7 11949.6 12142.9 12210.3 12213.2 238986.0 244911.0 245290.0 245591.0 245977.0 57818.0 58167.4 58455.0 58539.1 58608.4 1432.0 1447.7 1464.5 1465.7 1465.7 27372.0 27531.8 27628.2 27805.0 28149.9 37930.2 38235.6 38238.0 38296.8 38307.8 149595.3 149657.0 150301.2 150363.0 150806.0 29479.6 31119.0 31223.2 31240.5 35061.0 42756.0 42789.0 43482.8 43577.0 43627.0 33414.3 34284.5 34330.0 34351.5 34477.8 33077.3 33569.7 33593.0 33963.0 34321.8 37630.6 37755.8 37835.2 38060.0 38796.4 32740.0 32838.8 32881.7 32897.8 33832.0 23912.2 24052.4 24154.0 24532.9 24984.8 18:37:13 INFO - PID 30192 | 18:37:13 INFO - PID 30192 | [#2] jquery.html Cycles:180 Average:35320.66 Median:23248.42 stddev:83964.59 (361.2%) stddev-sans-first:84159.56 18:37:13 INFO - PID 30192 | Values: 555.6 556.3 561.4 570.9 572.2 22216.2 22359.6 23136.2 23219.4 23284.7 25029.7 25402.6 25528.9 25543.9 25580.4 6561.8 6708.6 6730.2 6734.2 6740.7 5792.5 6366.3 6670.0 6756.1 6764.9 6304.7 6326.0 6326.8 6332.7 6371.8 6332.6 6349.7 6377.8 6381.7 6388.8 5907.4 5923.6 5928.8 5943.3 5966.3 27035.3 28428.7 28697.1 29505.7 29566.6 2004.0 2021.8 2047.9 2051.7 2059.5 18479.4 19107.4 19196.1 19245.9 19246.4 2338.5 2364.1 2431.6 2444.5 2453.2 127543.4 134906.0 135142.3 135229.0 135815.0 13917.9 14203.6 14410.4 14455.7 14978.5 22646.1 22653.3 22677.4 22694.5 22742.9 9508.1 9512.3 9551.3 9816.0 9848.5 8763.9 8855.6 8975.6 9060.9 9221.8 8387.4 8964.1 9171.0 9312.9 9563.0 2745.2 2786.8 2792.5 2844.3 2867.6 923.6 964.8 973.5 973.6 975.6 19413.0 19476.5 19593.7 19653.5 19745.0 1023.8 1024.2 1032.6 1040.6 1045.0 10835.5 10839.5 10967.8 11030.9 11058.6 481997.0 484512.0 493060.0 513870.0 515653.0 119437.4 122704.4 123077.4 125591.0 126056.0 1202.3 1225.0 1238.2 1240.2 1251.5 20754.5 20784.6 21054.7 21233.8 21255.1 59778.0 60723.7 60806.4 61081.7 63298.0 73883.0 74413.0 74794.0 75214.6 76197.6 14281.6 14701.5 14813.1 14822.4 14933.0 23143.3 23243.7 23253.2 23405.6 23447.2 18547.8 18550.4 18573.0 18580.8 18582.9 15665.8 15978.0 16385.8 16733.5 16764.4 18881.7 19007.5 19433.9 19570.4 19713.6 13864.8 14616.1 14622.3 14649.3 14662.8 17916.1 18646.0 19064.8 19107.7 19179.7 18:37:13 INFO - PID 30192 | 18:37:13 INFO - PID 30192 | [#3] mootools.html Cycles:180 Average:8896.01 Median:2628.72 stddev:6374.91 (242.5%) stddev-sans-first:6367.29 18:37:13 INFO - PID 30192 | Values: 1307.5 1360.0 1363.8 1367.0 1368.0 12517.8 14271.4 14756.2 14874.9 14888.6 12506.8 14586.2 14915.5 14925.7 14967.7 2660.2 2691.9 2696.0 2699.9 2707.6 2504.6 2548.5 2607.5 2651.5 2665.4 2649.4 2659.7 2685.8 2692.1 2693.2 2440.4 2470.8 2472.0 2653.6 2717.8 2608.0 2656.6 2660.2 2670.9 2685.5 14753.6 14785.6 14790.7 14860.3 14874.4 7587.5 7861.7 7862.3 7868.2 7881.1 5350.5 5354.5 5461.0 5562.2 5617.6 1905.7 1936.1 1981.0 1985.3 2299.1 25036.4 25063.5 25152.0 25201.6 25442.2 6550.9 6561.8 6641.7 6750.1 6802.7 16363.1 16365.6 16368.2 16380.8 16396.3 7204.5 7285.9 7288.0 7311.5 7321.7 6649.7 6666.5 6738.6 6753.5 6798.2 5150.5 5254.7 5282.9 5424.5 5515.6 1505.3 1537.7 1539.3 1557.6 1561.9 841.2 848.2 881.8 887.0 887.6 7146.8 7256.2 7261.8 7289.3 7303.8 637.3 649.0 650.4 653.5 654.5 4423.0 4486.6 5355.4 5488.5 5565.5 19270.4 20994.7 21061.7 21323.4 21381.1 19784.6 20394.7 20417.3 20430.6 20450.5 8962.2 10426.3 10428.5 10716.0 11041.7 8127.5 8309.6 8378.7 8382.9 8392.3 17545.4 17793.3 17962.7 18300.9 18689.1 18407.3 18510.4 18774.2 18840.9 18893.8 11143.8 11287.2 11390.5 11527.8 11566.5 12957.1 13039.3 13041.2 13223.4 13251.5 10726.6 10818.9 10955.9 10969.5 11074.3 10077.9 10241.1 10268.6 10421.9 10541.4 7954.3 8550.3 8716.8 8727.6 8948.3 6533.2 6558.6 6588.4 6592.3 6620.3 6465.6 6629.4 6747.9 6857.5 6877.1 18:37:13 INFO - PID 30192 | 18:37:13 INFO - PID 30192 | [#4] prototype.html Cycles:180 Average:5618.80 Median:6250.06 stddev:1586.06 (25.4%) stddev-sans-first:1543.24 18:37:13 INFO - PID 30192 | Values: 498.2 504.6 512.2 513.3 515.8 6371.6 6417.5 6427.5 6434.8 6449.5 6152.1 6214.3 6222.9 6243.3 6349.7 5241.5 5277.9 5282.4 5283.8 5298.3 5203.5 5213.5 5245.5 5250.3 5346.0 4897.3 5014.0 5054.9 5067.4 5088.0 5020.3 5031.8 5071.0 5075.9 5107.5 4471.9 4527.4 4551.7 4552.3 4559.0 6225.1 6256.8 6280.4 6293.0 6293.8 5808.4 5829.3 5844.5 5864.9 5875.1 6610.8 6663.1 6667.1 6707.7 6717.7 4808.2 4847.0 4859.1 4892.7 4902.0 6968.7 7038.6 7156.3 7162.3 7170.5 4895.7 4910.8 4949.5 4984.7 4995.3 5011.8 5017.4 5027.3 5046.0 5060.9 5681.2 5688.7 5689.0 5714.7 5730.7 6681.5 6701.6 6712.2 6715.8 6830.8 1676.6 1730.4 1770.7 1781.4 1793.0 1215.0 1219.5 1222.0 1224.7 1246.9 5866.4 5908.2 5941.4 5951.6 6027.2 6546.2 6778.2 6832.8 6854.7 6859.3 5887.6 5907.5 5973.5 6038.4 6061.4 3669.8 3675.3 3677.0 3701.7 3722.0 6558.3 6565.9 6572.5 6603.8 6611.7 7018.3 7041.7 7086.0 7102.0 7129.5 6507.7 6570.5 6624.8 6640.8 6653.8 6889.4 6936.7 6959.7 6989.7 7016.8 6828.9 6847.4 6864.6 6890.5 6895.5 6508.3 6540.3 6604.4 6618.0 6637.4 5035.7 5041.0 5073.4 5073.7 5075.1 6813.0 6835.5 6867.9 6924.2 6934.0 6603.8 6608.0 6643.0 6661.0 6723.0 6632.6 6637.8 6642.3 6653.4 6712.5 6340.2 6565.2 6648.3 6692.6 6744.0 6052.0 6468.8 6517.5 6518.3 6755.2 6419.2 6490.8 6503.2 6523.3 6530.0 18:37:13 INFO - PID 30192 | 18:37:13 INFO - PID 30192 | [#5] yui.html Cycles:180 Average:22833.83 Median:29666.41 stddev:32251.68 (108.7%) stddev-sans-first:32299.92 18:37:13 INFO - PID 30192 | Values: 852.4 886.7 892.9 895.6 902.2 16309.9 17351.8 17353.6 17414.8 17415.4 17112.6 18626.3 18698.5 18780.3 18827.5 925.9 939.4 942.3 944.1 944.1 868.8 950.2 951.8 953.5 953.6 950.8 955.2 963.3 965.5 984.4 956.0 956.6 957.6 958.6 981.7 704.8 704.9 705.2 715.6 728.8 16777.7 16878.9 16982.3 17008.8 17013.7 21150.9 21303.5 21464.1 21671.5 21831.7 16175.8 16368.4 16424.9 16449.8 16464.6 3672.2 3720.9 3735.0 3740.6 3743.1 124874.0 128195.4 130203.0 131354.0 131434.3 16157.6 16265.3 16526.7 16939.9 16962.4 29484.3 29548.3 29600.9 29652.3 29680.6 8132.3 8177.7 8210.7 8240.7 8309.2 4054.4 4122.8 4125.4 4127.1 4140.4 4948.8 5064.3 5085.0 5087.6 5087.7 884.7 901.6 916.4 917.2 918.8 661.2 684.4 684.7 689.1 702.9 10688.9 10766.4 11081.3 12122.9 12371.3 655.3 664.8 666.6 676.7 680.9 5164.7 5360.9 5507.7 5823.2 5874.9 106270.0 109916.5 110420.4 113289.4 115001.4 96024.5 97392.5 97545.0 100353.5 102007.0 87748.6 87774.0 89862.0 90086.5 90872.0 10478.2 10611.2 10768.8 10780.0 10830.6 39020.6 40055.8 40782.0 40806.6 41703.0 51519.7 51783.7 51835.0 52468.0 53060.5 14551.7 14619.8 14717.6 14786.0 15079.2 15979.4 16226.0 16266.9 16323.4 16554.1 13976.0 16818.9 18003.5 18796.9 18847.6 16611.3 16836.0 17301.7 17458.7 17532.8 15953.4 17128.1 17180.6 17643.1 17945.2 11766.6 11804.4 11863.8 12342.1 12504.9 13509.3 13540.2 13720.8 13732.3 13777.9 18:37:13 INFO - PID 30192 | -------- Summary: end -------- 18:37:13 INFO - PID 30192 | 18:37:14 INFO - TEST-INFO | 30192: exit 0 18:37:14 INFO - TEST-OK | dromaeo_css | took 1114826ms 18:37:14 INFO - TEST-START | kraken 18:37:14 INFO - Initialising browser for kraken test... 18:37:14 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:35627/getInfo.html -profile /tmp/tmpEtsVRY/profile 18:37:14 INFO - TEST-INFO | started process 30616 (/builds/slave/test/build/application/firefox/firefox http://localhost:35627/getInfo.html) 18:37:21 INFO - TEST-INFO | 30616: exit 0 18:37:21 INFO - Browser initialized. 18:37:21 INFO - Running cycle 1/1 for kraken test... 18:37:21 INFO - Using env: {'DISPLAY': ':0', 18:37:21 INFO - 'HOME': '/home/cltbld', 18:37:21 INFO - 'JSGC_DISABLE_POISONING': '1', 18:37:21 INFO - 'LANG': 'en_US.UTF-8', 18:37:21 INFO - 'LANGUAGE': 'en_US:en', 18:37:21 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 18:37:21 INFO - 'LOGNAME': 'cltbld', 18:37:21 INFO - 'MAIL': '/var/mail/cltbld', 18:37:21 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:37:21 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:37:21 INFO - 'MOZ_ACCELERATED': '1', 18:37:21 INFO - 'MOZ_CRASHREPORTER': '1', 18:37:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:37:21 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 18:37:21 INFO - 'MOZ_NO_REMOTE': '1', 18:37:21 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:37:21 INFO - 'MOZ_WEBRENDER': '1', 18:37:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:37:21 INFO - 'NO_EM_RESTART': '1', 18:37:21 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:37:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:37:21 INFO - 'PWD': '/builds/slave/test', 18:37:21 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:37:21 INFO - 'RUST_BACKTRACE': 'full', 18:37:21 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 18:37:21 INFO - 'SHELL': '/bin/bash', 18:37:21 INFO - 'SHLVL': '1', 18:37:21 INFO - 'TERM': 'linux', 18:37:21 INFO - 'TMOUT': '86400', 18:37:21 INFO - 'USER': 'cltbld', 18:37:21 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1503859188.360365-1270311827', 18:37:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:37:21 INFO - '_': '/tools/buildbot/bin/python'} 18:37:21 INFO - TEST-INFO | started process 30797 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpEtsVRY/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 18:37:21 INFO - PID 30797 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 18:37:22 INFO - PID 30797 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 18:37:22 INFO - PID 30797 | 18:37:22 INFO - PID 30797 | (/builds/slave/test/build/application/firefox/firefox:30857): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 18:37:22 INFO - PID 30797 | 18:37:23 INFO - PID 30797 | 18:37:23 INFO - PID 30797 | (/builds/slave/test/build/application/firefox/firefox:30912): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 18:37:23 INFO - PID 30797 | 18:37:23 INFO - PID 30797 | __metrics Screen width/height:1600/1200 18:37:23 INFO - PID 30797 | colorDepth:24 18:37:23 INFO - PID 30797 | Browser inner width/height: 1024/768 18:37:23 INFO - PID 30797 | __metrics 18:37:32 INFO - PID 30797 | RSS: Main: 207519744 18:37:32 INFO - PID 30797 | 18:37:35 INFO - PID 30797 | Cycle 1(1): loaded http://localhost:35627/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:35627/tests/kraken/driver.html?testName=audio-beat-detection) 18:37:36 INFO - PID 30797 | RSS: Main: 213520384 18:37:36 INFO - PID 30797 | 18:37:39 INFO - PID 30797 | Cycle 1(1): loaded http://localhost:35627/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:35627/tests/kraken/driver.html?testName=audio-dft) 18:37:39 INFO - PID 30797 | RSS: Main: 213303296 18:37:39 INFO - PID 30797 | 18:37:42 INFO - PID 30797 | Cycle 1(1): loaded http://localhost:35627/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:35627/tests/kraken/driver.html?testName=audio-fft) 18:37:43 INFO - PID 30797 | RSS: Main: 206815232 18:37:43 INFO - PID 30797 | 18:37:45 INFO - PID 30797 | Cycle 1(1): loaded http://localhost:35627/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:35627/tests/kraken/driver.html?testName=audio-oscillator) 18:37:46 INFO - PID 30797 | RSS: Main: 208183296 18:37:46 INFO - PID 30797 | 18:37:48 INFO - PID 30797 | Cycle 1(1): loaded http://localhost:35627/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:35627/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:37:49 INFO - PID 30797 | RSS: Main: 208445440 18:37:49 INFO - PID 30797 | 18:37:52 INFO - PID 30797 | Cycle 1(1): loaded http://localhost:35627/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:35627/tests/kraken/driver.html?testName=imaging-darkroom) 18:37:52 INFO - PID 30797 | RSS: Main: 207818752 18:37:52 INFO - PID 30797 | 18:37:56 INFO - PID 30797 | Cycle 1(1): loaded http://localhost:35627/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:35627/tests/kraken/driver.html?testName=imaging-desaturate) 18:37:57 INFO - PID 30797 | RSS: Main: 208928768 18:37:57 INFO - PID 30797 | 18:37:59 INFO - PID 30797 | Cycle 1(1): loaded http://localhost:35627/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:35627/tests/kraken/driver.html?testName=json-parse-financial) 18:38:00 INFO - PID 30797 | RSS: Main: 210325504 18:38:00 INFO - PID 30797 | 18:38:02 INFO - PID 30797 | Cycle 1(1): loaded http://localhost:35627/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:35627/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:38:02 INFO - PID 30797 | RSS: Main: 210821120 18:38:02 INFO - PID 30797 | 18:38:04 INFO - PID 30797 | Cycle 1(1): loaded http://localhost:35627/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:35627/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:38:05 INFO - PID 30797 | RSS: Main: 209936384 18:38:05 INFO - PID 30797 | 18:38:07 INFO - PID 30797 | Cycle 1(1): loaded http://localhost:35627/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:35627/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:38:07 INFO - PID 30797 | RSS: Main: 208031744 18:38:07 INFO - PID 30797 | 18:38:10 INFO - PID 30797 | Cycle 1(1): loaded http://localhost:35627/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:35627/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:38:11 INFO - PID 30797 | RSS: Main: 207163392 18:38:11 INFO - PID 30797 | 18:38:14 INFO - PID 30797 | Cycle 1(1): loaded http://localhost:35627/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:35627/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:38:15 INFO - PID 30797 | RSS: Main: 206487552 18:38:15 INFO - PID 30797 | 18:38:16 INFO - PID 30797 | Cycle 1(1): loaded http://localhost:35627/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:35627/tests/kraken/driver.html?testName=ai-astar) 18:38:17 INFO - PID 30797 | RSS: Main: 208441344 18:38:17 INFO - PID 30797 | 18:38:17 INFO - PID 30797 | __start_tp_report 18:38:17 INFO - PID 30797 | _x_x_mozilla_page_load 18:38:17 INFO - PID 30797 | _x_x_mozilla_page_load_details 18:38:17 INFO - PID 30797 | |i|pagename|runs| 18:38:17 INFO - PID 30797 | |0;ai-astar;97;116;114;116;113;119;108;103;110;110 18:38:17 INFO - PID 30797 | |1;audio-beat-detection;151;123;154;173;119;151;151;119;189;124 18:38:17 INFO - PID 30797 | |2;audio-dft;166;164;167;163;164;170;167;163;170;171 18:38:17 INFO - PID 30797 | |3;audio-fft;75;77;76;170;74;74;73;78;170;79 18:38:17 INFO - PID 30797 | |4;audio-oscillator;78;81;130;80;79;79;139;79;79;129 18:38:17 INFO - PID 30797 | |5;imaging-gaussian-blur;118;124;118;115;116;124;115;122;126;122 18:38:17 INFO - PID 30797 | |6;imaging-darkroom;154;154;148;153;155;151;150;152;152;149 18:38:17 INFO - PID 30797 | |7;imaging-desaturate;95;95;94;94;94;96;94;94;94;94 18:38:17 INFO - PID 30797 | |8;json-parse-financial;53;55;53;54;52;54;61;59;50;52 18:38:17 INFO - PID 30797 | |9;json-stringify-tinderbox;38;37;39;46;38;46;42;40;44;49 18:38:17 INFO - PID 30797 | |10;stanford-crypto-aes;75;76;83;76;81;79;78;82;76;75 18:38:17 INFO - PID 30797 | |11;stanford-crypto-ccm;122;137;120;118;123;122;120;136;126;165 18:38:17 INFO - PID 30797 | |12;stanford-crypto-pbkdf2;170;180;175;177;174;184;179;185;171;181 18:38:17 INFO - PID 30797 | |13;stanford-crypto-sha256-iterative;69;68;64;68;66;64;65;66;65;66 18:38:17 INFO - PID 30797 | __end_tp_report 18:38:17 INFO - PID 30797 | __start_cc_report 18:38:17 INFO - PID 30797 | _x_x_mozilla_cycle_collect,939 18:38:17 INFO - PID 30797 | __end_cc_report 18:38:17 INFO - PID 30797 | __startTimestamp1503884297060__endTimestamp 18:38:17 INFO - PID 30797 | 18:38:17 INFO - PID 30797 | ------- Summary: start ------- 18:38:17 INFO - PID 30797 | Number of tests: 14 18:38:17 INFO - PID 30797 | 18:38:17 INFO - PID 30797 | [#0] ai-astar Cycles:10 Average:110.60 Median:115.00 stddev:6.64 (5.8%) stddev-sans-first:4.88 18:38:17 INFO - PID 30797 | Values: 97.0 116.0 114.0 116.0 113.0 119.0 108.0 103.0 110.0 110.0 18:38:17 INFO - PID 30797 | 18:38:17 INFO - PID 30797 | [#1] audio-beat-detection Cycles:10 Average:145.40 Median:151.00 stddev:24.00 (15.9%) stddev-sans-first:25.37 18:38:17 INFO - PID 30797 | Values: 151.0 123.0 154.0 173.0 119.0 151.0 151.0 119.0 189.0 124.0 18:38:17 INFO - PID 30797 | 18:38:17 INFO - PID 30797 | [#2] audio-dft Cycles:10 Average:166.50 Median:167.00 stddev:3.03 (1.8%) stddev-sans-first:3.21 18:38:17 INFO - PID 30797 | Values: 166.0 164.0 167.0 163.0 164.0 170.0 167.0 163.0 170.0 171.0 18:38:17 INFO - PID 30797 | 18:38:17 INFO - PID 30797 | [#3] audio-fft Cycles:10 Average:94.60 Median:75.50 stddev:39.78 (52.7%) stddev-sans-first:41.56 18:38:17 INFO - PID 30797 | Values: 75.0 77.0 76.0 170.0 74.0 74.0 73.0 78.0 170.0 79.0 18:38:17 INFO - PID 30797 | 18:38:17 INFO - PID 30797 | [#4] audio-oscillator Cycles:10 Average:95.30 Median:79.00 stddev:25.93 (32.8%) stddev-sans-first:26.73 18:38:17 INFO - PID 30797 | Values: 78.0 81.0 130.0 80.0 79.0 79.0 139.0 79.0 79.0 129.0 18:38:17 INFO - PID 30797 | 18:38:17 INFO - PID 30797 | [#5] imaging-gaussian-blur Cycles:10 Average:120.00 Median:122.00 stddev:4.08 (3.3%) stddev-sans-first:4.27 18:38:17 INFO - PID 30797 | Values: 118.0 124.0 118.0 115.0 116.0 124.0 115.0 122.0 126.0 122.0 18:38:17 INFO - PID 30797 | 18:38:17 INFO - PID 30797 | [#6] imaging-darkroom Cycles:10 Average:151.80 Median:152.50 stddev:2.30 (1.5%) stddev-sans-first:2.30 18:38:17 INFO - PID 30797 | Values: 154.0 154.0 148.0 153.0 155.0 151.0 150.0 152.0 152.0 149.0 18:38:17 INFO - PID 30797 | 18:38:17 INFO - PID 30797 | [#7] imaging-desaturate Cycles:10 Average:94.40 Median:94.00 stddev:0.70 (0.7%) stddev-sans-first:0.71 18:38:17 INFO - PID 30797 | Values: 95.0 95.0 94.0 94.0 94.0 96.0 94.0 94.0 94.0 94.0 18:38:17 INFO - PID 30797 | 18:38:17 INFO - PID 30797 | [#8] json-parse-financial Cycles:10 Average:54.30 Median:54.00 stddev:3.33 (6.2%) stddev-sans-first:3.50 18:38:17 INFO - PID 30797 | Values: 53.0 55.0 53.0 54.0 52.0 54.0 61.0 59.0 50.0 52.0 18:38:17 INFO - PID 30797 | 18:38:17 INFO - PID 30797 | [#9] json-stringify-tinderbox Cycles:10 Average:41.90 Median:43.00 stddev:4.15 (9.6%) stddev-sans-first:4.15 18:38:17 INFO - PID 30797 | Values: 38.0 37.0 39.0 46.0 38.0 46.0 42.0 40.0 44.0 49.0 18:38:17 INFO - PID 30797 | 18:38:17 INFO - PID 30797 | [#10] stanford-crypto-aes Cycles:10 Average:78.10 Median:78.50 stddev:3.00 (3.8%) stddev-sans-first:2.96 18:38:17 INFO - PID 30797 | Values: 75.0 76.0 83.0 76.0 81.0 79.0 78.0 82.0 76.0 75.0 18:38:17 INFO - PID 30797 | 18:38:17 INFO - PID 30797 | [#11] stanford-crypto-ccm Cycles:10 Average:128.90 Median:124.50 stddev:14.28 (11.5%) stddev-sans-first:14.92 18:38:17 INFO - PID 30797 | Values: 122.0 137.0 120.0 118.0 123.0 122.0 120.0 136.0 126.0 165.0 18:38:17 INFO - PID 30797 | 18:38:17 INFO - PID 30797 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:177.60 Median:179.50 stddev:5.13 (2.9%) stddev-sans-first:4.64 18:38:17 INFO - PID 30797 | Values: 170.0 180.0 175.0 177.0 174.0 184.0 179.0 185.0 171.0 181.0 18:38:17 INFO - PID 30797 | 18:38:17 INFO - PID 30797 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.10 Median:66.00 stddev:1.73 (2.6%) stddev-sans-first:1.48 18:38:17 INFO - PID 30797 | Values: 69.0 68.0 64.0 68.0 66.0 64.0 65.0 66.0 65.0 66.0 18:38:17 INFO - PID 30797 | -------- Summary: end -------- 18:38:17 INFO - PID 30797 | 18:38:17 INFO - PID 30797 | 18:38:17 INFO - PID 30797 | ###!!! [Parent][MessageChannel] Error: (msgtype=0x150082,name=PBrowser::Msg_Destroy) Closed channel: cannot send/recv 18:38:17 INFO - PID 30797 | 18:38:17 INFO - PID 30797 | 18:38:17 INFO - PID 30797 | ###!!! [Child][MessageChannel] Error: (msgtype=0x150001,name=PBrowser::Msg_AsyncMessage) Closed channel: cannot send/recv 18:38:17 INFO - PID 30797 | 18:38:17 INFO - PID 30797 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 18:38:17 INFO - TEST-INFO | 30797: exit 0 18:38:18 INFO - TEST-OK | kraken | took 63394ms 18:38:18 INFO - SUITE-END | took 1178s 18:38:18 INFO - Completed test suite (00:19:38) 18:38:18 INFO - javascript benchmark 18:38:18 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8423.03086765047, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3235.3529294141176, 3269.4278501262274, 3269.8855540056097, 3284.6551112133225, 3294.1435226841013, 6236.189295391604, 6287.1198032275515, 6307.117003619491, 6322.272938612061, 6382.170317858678, 6323.30443651198, 6390.840228994278, 6401.0398440233985, 6409.198850143732, 6411.070394792752, 5791.536677065825, 5806.332271788756, 5833.125031245305, 5849.619774714652, 5890.64656120632, 5842.737076831553, 5884.6763428011445, 5932.525397968152, 5979.491743201833, 5997.37027612101, 5843.737031833578, 5902.114682797572, 5929.377415371388, 5935.525157987372, 5938.198343223674, 5648.887022259573, 5794.362620111784, 5875.7062146892695, 5967.4629283364275, 5971.581989260753, 5306.151015837486, 5709.886572118438, 5781.971090144522, 5806.216160818278, 5810.796622118248, 6289.245290565162, 6351.301356850743, 6406.4554512866, 6424.518161137933, 6446.194225721785, 6691.397774200345, 6725.865482690368, 6731.0, 6801.863962720768, 6854.383105520527, 6903.206131294915, 6908.0674108995645, 6913.827154321132, 6920.30796920309, 6929.133858267716, 6291.1192433059405, 6321.431071203614, 6412.39082287182, 6412.39082287182, 6418.422341989243, 6887.827804304932, 6984.161900571964, 7032.507724459341, 7065.187503437068, 7066.3993560546905, 6533.150690410216, 6639.738449694639, 6656.667166641649, 6704.96647516769, 6742.089817874622, 6704.99425086241, 6844.555103918229, 6869.244383117853, 6870.27862074475, 6896.137982752156, 6769.42459890905, 6829.419499342513, 6866.793996180123, 6871.0, 6883.690233939485, 6751.156105486814, 6758.729650814011, 6773.322667733188, 6808.148981377328, 6814.352636499525, 5299.549538289211, 5344.251804747339, 5346.0, 5373.462653734595, 5373.892522149613, 4604.516525764813, 4645.372874661945, 4648.163330600457, 4648.86053418398, 4663.906721865544, 5944.524438044946, 5959.404059594006, 6008.789692360747, 6035.0, 6038.667873266924, 6864.725410983605, 6872.175338959257, 6876.037354770337, 6901.068355772007, 6910.101686780787, 5937.346891841894, 5975.940240597625, 5995.280566332068, 6049.8487537811025, 6058.212432383762, 5755.539556835443, 5871.765129394862, 5902.468777810018, 5926.940730592725, 5938.910916336258, 6412.23053233615, 6446.967765161145, 6543.869122617521, 6550.868982620321, 6557.245916719543, 6825.180978282638, 6856.485763567852, 6856.90289553669, 6877.037214789833, 6877.449804015567, 6394.840128996626, 6506.284308725942, 6579.703913323816, 6585.473162147113, 6586.0, 6733.7306507741105, 6737.562058466184, 6748.797536073926, 6827.863442731217, 6844.110265665432, 6685.498587606046, 6749.730010799513, 6772.390484856386, 6843.692033858389, 6848.246692863681, 6614.867702646016, 6622.106015688013, 6708.42978346846, 6711.865762684816, 6712.9664351679885, 6628.602283862983, 6650.068990341453, 6673.866522669617, 6702.396784289437, 6713.295104014008, 6540.018997150465, 6565.638889861103, 6638.402543771083, 6661.533692641662, 6672.266050734318, 6586.308437613981, 6715.899261510984, 6756.29058948823, 6811.659417029228, 6820.147481564804, 6323.1463752394675, 6429.58207716497, 6516.283208846929, 6518.478521718346, 6526.44525215343], "value": 6219.79908006063, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [767.1944458318764, 770.3875419041862, 789.5144486141022, 814.2142832166958, 818.0428898189118, 40220.0, 40573.79713101438, 41046.384304235464, 41148.17703645925, 41223.58776412235, 37190.62809371905, 37372.43941340882, 37532.0, 37555.62444375562, 37658.246835063284, 6815.352541508573, 6892.379685828275, 6943.7569685061035, 6961.860762784741, 6964.338387853146, 6859.725610975555, 6895.310468953115, 6908.689108990107, 6930.653467326639, 6945.201301850276, 6875.7937261882225, 6922.619255940921, 7634.312911837934, 7842.137364889858, 7871.9212807872045, 7794.922050779504, 7833.843323133506, 7856.096548896893, 7857.960710196441, 7864.449488535776, 7686.654100565488, 7688.6155692215725, 7733.0, 7746.225377462265, 7785.532868027936, 36205.27589448223, 36575.0, 36656.0, 36733.0, 36761.26477470436, 3389.4746314321073, 3430.7255419566377, 3450.741194410429, 3456.7580269381156, 3474.1488335358, 30549.389012219854, 30703.539446908315, 30870.845645771853, 30943.845280773676, 30985.0, 4985.875353116165, 5018.64869459138, 5074.67014644047, 5090.312807770941, 5106.463821298785, 180556.0, 181550.0, 182214.0, 183611.0, 191837.9999999986, 48917.755411222715, 49038.26442603364, 49329.0, 49532.0, 49593.752031240336, 49639.25541116886, 49672.751636241584, 49677.26129585447, 50133.498665012885, 50280.24579631309, 14498.275086245645, 14527.346269417902, 14608.0, 14631.122132672073, 14667.413303467742, 11955.342456164994, 11958.402079896145, 11978.580749673894, 11981.820272695917, 11987.760244795056, 13878.375473103735, 13924.0, 13936.372863221179, 14091.154530728189, 14326.355314010894, 5682.147677848273, 5683.943160568424, 5711.400302968129, 5716.5140963017775, 5842.0, 985.8521221816785, 986.146982859821, 993.215359865698, 999.6601155607128, 1006.0442579549457, 27744.0, 28335.71664283372, 28406.0, 28855.42289154205, 29536.113916582537, 1028.881678606955, 1052.494802494807, 1065.0521036277719, 1074.1782536359692, 1080.7514271717391, 11947.654656696139, 11949.581764638197, 12142.939285303693, 12210.26738395699, 12213.206141600767, 238986.0, 244911.0, 245290.0, 245591.0, 245977.0, 57818.0, 58167.4183258162, 58455.0, 58539.12191317134, 58608.41391586029, 1432.0, 1447.6597999470328, 1464.4801095610906, 1465.6775509387917, 1465.6848777512887, 27372.0, 27531.83928811386, 27628.171154865388, 27805.0, 28149.859250703616, 37930.241395172496, 38235.61764382321, 38238.0, 38296.808515957244, 38307.808460957516, 149595.2520237392, 149657.0, 150301.24849375684, 150363.0, 150806.0, 29479.55780663249, 31119.0, 31223.219419514695, 31240.531392029592, 35061.0, 42756.0, 42789.0, 43482.782586088135, 43577.0, 43627.0, 33414.33171336511, 34284.48573271453, 34330.0, 34351.48472772961, 34477.82761086179, 33077.338453231285, 33569.66430335665, 33593.0, 33963.0, 34321.82839085789, 37630.62369376271, 37755.81122094372, 37835.243295134496, 38060.0, 38796.41805372978, 32740.0, 32838.83580582082, 32881.67118328882, 32897.83551082229, 33832.0, 23912.163074292315, 24052.39869003219, 24154.03383864696, 24532.877335613208, 24984.750152498244], "value": 16975.58268691487, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [555.6471640508279, 556.2990631803929, 561.3516388571202, 570.9257796486456, 572.201778518966, 22216.222432214876, 22359.552808943812, 23136.190233341837, 23219.419514512145, 23284.65073023904, 25029.74970250297, 25402.618960715605, 25528.872355638196, 25543.872280638618, 25580.360490987736, 6561.835954101138, 6708.631025293607, 6730.158730158731, 6734.225564060122, 6740.6629668516625, 5792.507636850873, 6366.299707032223, 6670.032845237438, 6756.054152418666, 6764.898526522106, 6304.747810087591, 6325.968370158166, 6326.841828954267, 6332.746690132389, 6371.8407039823915, 6332.620042797447, 6349.71426285816, 6377.8405539861415, 6381.712822922957, 6388.8083357499345, 5907.379725128842, 5923.614965027303, 5928.7628494860155, 5943.31651860033, 5966.3138739045135, 27035.324116897038, 28428.71571284302, 28697.139085827457, 29505.70494295051, 29566.556501652278, 2004.0480771633358, 2021.7674967378794, 2047.8566500344982, 2051.7435320584927, 2059.5468996820678, 18479.353222637143, 19107.426777196706, 19196.136173872208, 19245.903770481058, 19246.4226073218, 2338.4972230970425, 2364.0780095762666, 2431.598786200275, 2444.4866578018637, 2453.2027091195255, 127543.36228318892, 134906.0, 135142.32428837792, 135229.0, 135815.0, 13917.86082139186, 14203.573892783026, 14410.351534180778, 14455.710885782226, 14978.47575334858, 22646.094156233896, 22653.320400387685, 22677.433064173198, 22694.546109077724, 22742.886285568467, 9508.144267015863, 9512.28657850664, 9551.331406801597, 9816.0, 9848.50757462124, 8763.912360876437, 8855.64577416909, 8975.596098175598, 9060.864087038699, 9221.769455763659, 8387.412881098386, 8964.14840590143, 9170.954145229365, 9312.8603070954, 9562.95218523903, 2745.203890871625, 2786.818856774303, 2792.5339196921373, 2844.3031457293096, 2867.641544806899, 923.6490133749132, 964.8118616869643, 973.464594473037, 973.6056896956744, 975.6195083917278, 19413.0, 19476.51308717265, 19593.706094408597, 19653.50866228327, 19745.0, 1023.8057261344652, 1024.2267088348249, 1032.6385764982197, 1040.6201736366172, 1045.038564520643, 10835.51240194193, 10839.512221949873, 10967.780644387227, 11030.944845275882, 11058.557657693764, 481997.0, 484512.0, 493060.0, 513870.0, 515653.0, 119437.40281298711, 122704.38647806883, 123077.38461307636, 125591.0, 126056.0, 1202.3327053485225, 1224.9571265005682, 1238.2137342787396, 1240.231056744824, 1251.5369313354117, 20754.481137971066, 20784.58430831405, 21054.68417973701, 21233.78766212318, 21255.149794008066, 59778.0, 60723.69638151958, 60806.39193608007, 61081.69459152676, 63298.0, 73883.0, 74413.0, 74794.0, 75214.62392688001, 76197.61901190458, 14281.571552853431, 14701.485448009269, 14813.111213327235, 14822.40710371573, 14932.954693171374, 23143.305700829, 23243.651345230173, 23253.18613848507, 23405.648915266625, 23447.17934872271, 18547.814521855147, 18550.350737724115, 18573.0, 18580.814191858448, 18582.907085464485, 15665.765013525035, 15978.0, 16385.754213686567, 16733.497995060166, 16764.413245536347, 18881.71677424867, 19007.524811879815, 19433.931133787777, 19570.412887613395, 19713.60572788565, 13864.792028119788, 14616.123032618078, 14622.341994610055, 14649.340779664728, 14662.780058299348, 17916.104194789947, 18646.0, 19064.809351906857, 19107.713384299524, 19179.712304315726], "value": 12015.543868306599, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1307.5031488034545, 1360.0139898573536, 1363.7791036741282, 1366.952156674516, 1367.9521216757412, 12517.812232816515, 14271.357788899499, 14756.188409637465, 14874.925625371885, 14888.553343399702, 12506.81239781404, 14586.197759123244, 14915.477958271462, 14925.701485970301, 14967.700645987074, 2660.2285337252224, 2691.946161076787, 2695.986520067397, 2699.85150816705, 2707.6344693466426, 2504.5992641177413, 2548.49030193961, 2607.530644483992, 2651.5227259093353, 2665.4002849358935, 2649.403884126066, 2659.6542449481635, 2685.7582817546418, 2692.057779777082, 2693.151657227967, 2440.4386990991993, 2470.8270421070533, 2472.0, 2653.6284920111198, 2717.7825773938234, 2608.0350270399886, 2656.5882288245352, 2660.188642464048, 2670.9065182718514, 2685.5434576122107, 14753.631159220999, 14785.556433307018, 14790.70418591633, 14860.256987150708, 14874.405023799036, 7587.506812057199, 7861.685532578747, 7862.331701805353, 7868.213178682143, 7881.133075361706, 5350.464953504657, 5354.464553544653, 5460.972695136499, 5562.193481945095, 5617.578681598856, 1905.7046157845557, 1936.0900376822872, 1980.99009504953, 1985.265451782835, 2299.1493147535352, 25036.374090647696, 25063.49873002548, 25152.0, 25201.62197567038, 25442.236732897858, 6550.853600619873, 6561.80314590563, 6641.734330626818, 6750.122484077038, 6802.727890884408, 16363.100029498253, 16365.59086022835, 16368.181590920645, 16380.754288685679, 16396.26216820246, 7204.53170543913, 7285.854282914418, 7287.963560182166, 7311.524750891175, 7321.707131714672, 6649.700763465656, 6666.533342665967, 6738.59568425896, 6753.49348798842, 6798.150231221098, 5150.4592017837585, 5254.737263136828, 5282.867928301823, 5424.511793938565, 5515.61390702647, 1505.3000354835053, 1537.7001484710374, 1539.3457780443266, 1557.6105973506624, 1561.921903904823, 841.1882533355341, 848.245061894914, 881.7795551112222, 887.0, 887.5695287785419, 7146.821329466805, 7256.238094999949, 7261.818454538678, 7289.271072892774, 7303.817404564822, 637.327619361578, 648.9805305840832, 650.4276236911583, 653.5359894474976, 654.5254690349553, 4423.049044455457, 4486.641068714495, 5355.437679043722, 5488.506034456838, 5565.499105080481, 19270.421887343404, 20994.685079723815, 21061.684074738892, 21323.36029919105, 21381.144754209967, 19784.604307914047, 20394.69407958882, 20417.2853950114, 20430.591388172154, 20450.488737781674, 8962.193402593668, 10426.322289051188, 10428.530716117793, 10716.0, 11041.668749937515, 8127.512349259064, 8309.584520774059, 8378.748637540884, 8382.916170838214, 8392.28665563434, 17545.385911493035, 17793.288268469634, 17962.730559041363, 18300.90849545744, 18689.065546722883, 18407.263709451472, 18510.352137675116, 18774.249030038645, 18840.905795470935, 18893.811061889206, 11143.777124457627, 11287.20989530725, 11390.487428065591, 11527.827082593936, 11566.479508421973, 12957.093003490043, 13039.282839443922, 13041.217526948414, 13223.404946777226, 13251.536196233086, 10726.624568140078, 10818.891811082103, 10955.890441095487, 10969.451527423438, 11074.280171788809, 10077.949610251902, 10241.129503991946, 10268.58925642966, 10421.895781042092, 10541.367517948947, 7954.284114429729, 8550.273226775545, 8716.78208044804, 8727.607257673293, 8948.284137269133, 6533.216014078341, 6558.57369271015, 6588.374104460068, 6592.274849766618, 6620.337966203341, 6465.579737317247, 6629.370209830058, 6747.898781518183, 6857.451403887577, 6877.140357455318], "value": 6351.608595251996, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [498.2001439884807, 504.6408042401772, 512.1626141259043, 513.307035502072, 515.8271978887072, 6371.585846919947, 6417.518686098544, 6427.485801135904, 6434.839129021777, 6449.51628627853, 6152.138700581922, 6214.316425193237, 6222.906656400158, 6243.313235544098, 6349.7142628581705, 5241.5020573045695, 5277.947220527793, 5282.366116066059, 5283.788648454057, 5298.311219541454, 5203.479652034785, 5213.4525874782985, 5245.4754524547625, 5250.31745873036, 5345.973270133644, 4897.302536584873, 5013.974930125344, 5054.898902021957, 5067.417247016585, 5087.9745601272125, 5020.347354843847, 5031.798728050873, 5071.0365030644425, 5075.898482030376, 5107.489251074881, 4471.910561788779, 4527.388802511652, 4551.726896386227, 4552.271636538171, 4559.0, 6225.097360882647, 6256.812295631093, 6280.434760871543, 6292.968535157341, 6293.811185664437, 5808.448197421239, 5829.300483941955, 5844.503217226541, 5864.882702345929, 5875.148103524965, 6610.768623098168, 6663.13379260693, 6667.133062823599, 6707.6981535831, 6717.731290748364, 4808.158572249842, 4847.0, 4859.1496488114435, 4892.706437613754, 4902.0, 6968.651567421609, 7038.577685338845, 7156.284371562802, 7162.283771622848, 7170.533915295489, 4895.681780684244, 4910.803567857317, 4949.505049495022, 4984.651074424828, 4995.275685025651, 5011.824586139468, 5017.448080711119, 5027.271045698353, 5046.0, 5060.873478163076, 5681.233033540502, 5688.658680479184, 5688.9759843227785, 5714.657055592016, 5730.656160630376, 6681.532292739559, 6701.597904125768, 6712.194536655535, 6715.832104197337, 6830.795076147723, 1676.6311411489453, 1730.4030109612415, 1770.663573920951, 1781.376518218639, 1793.0, 1214.9817752733716, 1219.451246938881, 1221.9633410997685, 1224.6570960131178, 1246.9127161098636, 5866.4160676617075, 5908.172855800191, 5941.435563621449, 5951.583389162717, 6027.186329845415, 6546.18172728409, 6778.22050464193, 6832.795016149524, 6854.657267136723, 6859.279775623488, 5887.646741195542, 5907.459792289357, 5973.522118230618, 6038.365971573009, 6061.393860613904, 3669.761465504787, 3675.298655488214, 3677.0, 3701.6668499835146, 3721.9627803721614, 6558.311377305409, 6565.934340656628, 6572.507061970371, 6603.7688680895935, 6611.735530578723, 7018.33325834045, 7041.718331266795, 7086.0, 7102.041224434738, 7129.465290103263, 6507.739690412331, 6570.536048981632, 6624.8012559623285, 6640.767573134821, 6653.800385988428, 6889.448844092561, 6936.653167341714, 6959.738085122254, 6989.650517474004, 7016.789496315118, 6828.9317106830285, 6847.390863147057, 6864.5881247125335, 6890.517663763489, 6895.5173137879865, 6508.284088750139, 6540.280569137486, 6604.405603495708, 6617.966910165418, 6637.435817955527, 5035.672681275852, 5040.974795126001, 5073.416557095981, 5073.670211436245, 5075.137226671549, 6812.965935170292, 6835.521513494008, 6867.931320686929, 6924.238333783381, 6934.0, 6603.834904127435, 6607.9669601651685, 6642.966785166043, 6661.0, 6723.0, 6632.568883022588, 6637.818174550002, 6642.269350371359, 6653.434458070925, 6712.49656275791, 6340.239171299474, 6565.179352580927, 6648.268690444144, 6692.631905245258, 6743.966280168568, 6052.0, 6468.838279043061, 6517.543771936109, 6518.282988871315, 6755.2231493378895, 6419.175562752374, 6490.8052758417325, 6503.187101612299, 6523.347665233438, 6529.967350163409], "value": 5146.540926359068, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [852.4373913217278, 886.7206829848598, 892.8928528576572, 895.6148855991925, 902.1745103230544, 16309.918450407746, 17351.826481735177, 17353.56616084597, 17414.82585174148, 17415.390461333824, 17112.572185695364, 18626.348077817278, 18698.532536686555, 18780.342688005923, 18827.52931176721, 925.9120383563568, 939.3659279986016, 942.3356533643764, 944.0748066894448, 944.0795224655974, 868.8088620503479, 950.2255661635745, 951.8001219743827, 953.5232383808096, 953.6090203016764, 950.7908260182784, 955.2071780422268, 963.3160456076195, 965.5413678502725, 984.3503287830033, 956.0248546482584, 956.5886668732443, 957.5738796235678, 958.5638534466835, 981.6711401680445, 704.7815177295055, 704.9330313620197, 705.1995984557564, 715.6350261366688, 728.8177955511122, 16777.748333775, 16878.91560542202, 16982.32070717182, 17008.829911700974, 17013.659726805396, 21150.894245528827, 21303.467413314636, 21464.141434342608, 21671.45821354479, 21831.672524912137, 16175.757363639555, 16368.427105051265, 16424.917875410545, 16449.753253701205, 16464.588385290463, 3672.210474747915, 3720.8511659533588, 3735.0, 3740.6446387593137, 3743.0829446785438, 124874.0, 128195.35902320522, 130203.0, 131353.99999999904, 131434.3428282862, 16157.596060098356, 16265.349386024664, 16526.6694666106, 16939.915300423418, 16962.406315778888, 29484.262893427407, 29548.261293467407, 29600.851995739882, 29652.258693532833, 29680.554791678143, 8132.349412047022, 8177.713780017671, 8210.712625058095, 8240.670373185005, 8309.169083091643, 4054.391841223781, 4122.793860306972, 4125.422440858282, 4127.071408932966, 4140.37894315849, 4948.777305021283, 5064.316317297192, 5085.0338435697095, 5087.643864929493, 5087.745612719349, 884.6638277454655, 901.6078006067381, 916.4272329793879, 917.1928702741678, 918.7886786039248, 661.163628010567, 684.4011489946297, 684.7055766020559, 689.1489011071322, 702.8980797784293, 10688.893111068945, 10766.407847568458, 11081.279716818543, 12122.878771212176, 12371.319577423328, 655.3479288108363, 664.8437617159964, 666.643345809999, 676.7022510095542, 680.9421199198025, 5164.715940623302, 5360.892782144413, 5507.697076660742, 5823.213866128103, 5874.853128671817, 106270.0, 109916.4504177474, 110420.44789776161, 113289.4335528317, 115001.4249928745, 96024.51987740156, 97392.51303743436, 97545.0, 100353.49823250837, 102007.0, 87748.5612571933, 87774.0, 89862.0, 90086.54956725174, 90871.99999999868, 10478.161747060065, 10611.2041596879, 10768.838467422838, 10780.029797318279, 10830.620928267474, 39020.6097939017, 40055.79972100121, 40782.0, 40806.591934081465, 41703.0, 51519.74240128775, 51783.74108129435, 51835.0, 52468.0, 53060.4693953071, 14551.708965820835, 14619.780703289247, 14717.632059198606, 14786.0, 15079.170645614158, 15979.440719574759, 16226.0, 16266.918665406598, 16323.428679996143, 16554.089525076237, 13976.021678482408, 16818.915905420396, 18003.459896203134, 18796.872187668785, 18847.6230475387, 16611.252493638058, 16836.0, 17301.653966920843, 17458.650826983136, 17532.82467175312, 15953.361865525249, 17128.14359282006, 17180.570485737957, 17643.117844108, 17945.192466338784, 11766.58816941403, 11804.350760708243, 11863.762724745284, 12342.13605047638, 12504.937475312565, 13509.324533773075, 13540.187588744708, 13720.794188086988, 13732.313384330544, 13777.931110344383], "value": 8610.75700705509, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1525.5, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 116.0, 114.0, 116.0, 113.0, 119.0, 108.0, 103.0, 110.0, 110.0], "value": 110.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 123.0, 154.0, 173.0, 119.0, 151.0, 151.0, 119.0, 189.0, 124.0], "value": 145.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 164.0, 167.0, 163.0, 164.0, 170.0, 167.0, 163.0, 170.0, 171.0], "value": 166.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 77.0, 76.0, 170.0, 74.0, 74.0, 73.0, 78.0, 170.0, 79.0], "value": 94.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 81.0, 130.0, 80.0, 79.0, 79.0, 139.0, 79.0, 79.0, 129.0], "value": 95.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 124.0, 118.0, 115.0, 116.0, 124.0, 115.0, 122.0, 126.0, 122.0], "value": 120.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 154.0, 148.0, 153.0, 155.0, 151.0, 150.0, 152.0, 152.0, 149.0], "value": 151.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 94.0, 94.0, 94.0, 96.0, 94.0, 94.0, 94.0, 94.0], "value": 94.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 55.0, 53.0, 54.0, 52.0, 54.0, 61.0, 59.0, 50.0, 52.0], "value": 54.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [38.0, 37.0, 39.0, 46.0, 38.0, 46.0, 42.0, 40.0, 44.0, 49.0], "value": 41.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 83.0, 76.0, 81.0, 79.0, 78.0, 82.0, 76.0, 75.0], "value": 78.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [122.0, 137.0, 120.0, 118.0, 123.0, 122.0, 120.0, 136.0, 126.0, 165.0], "value": 128.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 180.0, 175.0, 177.0, 174.0, 184.0, 179.0, 185.0, 171.0, 181.0], "value": 177.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 68.0, 64.0, 68.0, 66.0, 64.0, 65.0, 66.0, 65.0, 66.0], "value": 66.1, "unit": "score"}]}]} 18:38:18 INFO - Return code: 0 18:38:18 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:38:18 INFO - # TBPL SUCCESS # 18:38:18 INFO - Running post-action listener: _package_coverage_data 18:38:18 INFO - Running post-action listener: _resource_record_post_action 18:38:18 INFO - [mozharness: 2017-08-28 01:38:18.366257Z] Finished run-tests step (success) 18:38:18 INFO - Running post-run listener: _resource_record_post_run 18:38:18 INFO - Total resource usage - Wall time: 1196s; CPU: 13.0%; Read bytes: 12288; Write bytes: 427532288; Read time: 388; Write time: 355148 18:38:18 INFO - TinderboxPrint: CPU usage
13.3% 18:38:18 INFO - TinderboxPrint: I/O read bytes / time
12,288 / 388 18:38:18 INFO - TinderboxPrint: I/O write bytes / time
427,532,288 / 355,148 18:38:18 INFO - TinderboxPrint: CPU idle
8,264.3 (86.7%) 18:38:18 INFO - TinderboxPrint: CPU user
1,203.9 (12.6%) 18:38:18 INFO - TinderboxPrint: Swap in / out
0 / 0 18:38:18 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 105947136; Read time: 0; Write time: 88128 18:38:18 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 18:38:18 INFO - run-tests - Wall time: 1179s; CPU: 13.0%; Read bytes: 12288; Write bytes: 321585152; Read time: 388; Write time: 267020 18:38:19 INFO - Running post-run listener: _upload_blobber_files 18:38:19 INFO - Blob upload gear active. 18:38:19 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:38:19 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 18:38:19 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'] 18:38:19 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 18:38:19 INFO - (blobuploader) - INFO - Open directory for files ... 18:38:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:38:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:38:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:38:26 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:38:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:38:26 INFO - (blobuploader) - INFO - Done attempting. 18:38:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 18:38:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:38:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:38:27 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 18:38:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:38:27 INFO - (blobuploader) - INFO - Done attempting. 18:38:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 18:38:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:38:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:38:28 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 18:38:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:38:28 INFO - (blobuploader) - INFO - Done attempting. 18:38:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 18:38:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:38:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:38:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 18:38:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:38:29 INFO - (blobuploader) - INFO - Done attempting. 18:38:29 INFO - (blobuploader) - INFO - Iteration through files over. 18:38:29 INFO - Return code: 0 18:38:29 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:38:29 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:38:29 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5462d18d4fcb003b414aeb7979d517dc504f4282b2431aec3a2bb638d42918c5d5fdf3bba22e383bfc1d0921cfe00c496db34b6ab5ae738cdc62df8c0bfae6a7", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0eb71c1296288872d793b08ffcc58a452e931c83084e2e4b817c0e5c8e9cab688e2881601546e6d8e5e61b193aab08209446ccab814baa38bf45df5be906c11f", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5bbbefad8d54793dd10b6da569f44aae5f99f113f5b6c1027e881a3a19a4b85c660649fa7b027b92ec076e4436d8e80a7bfa1ba003edbc4a6b568a676d503ce5", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c50da29997ef7f810173784e1256c3cec5632c86a6e075c690e4f215c087d596850d214be7eb1f008c6e2a1e2b9f0923c5aa3d4ae0a21de6ff9e0959b478f5b8"} 18:38:29 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:38:29 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:38:29 INFO - Contents: 18:38:29 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5462d18d4fcb003b414aeb7979d517dc504f4282b2431aec3a2bb638d42918c5d5fdf3bba22e383bfc1d0921cfe00c496db34b6ab5ae738cdc62df8c0bfae6a7", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0eb71c1296288872d793b08ffcc58a452e931c83084e2e4b817c0e5c8e9cab688e2881601546e6d8e5e61b193aab08209446ccab814baa38bf45df5be906c11f", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5bbbefad8d54793dd10b6da569f44aae5f99f113f5b6c1027e881a3a19a4b85c660649fa7b027b92ec076e4436d8e80a7bfa1ba003edbc4a6b568a676d503ce5", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c50da29997ef7f810173784e1256c3cec5632c86a6e075c690e4f215c087d596850d214be7eb1f008c6e2a1e2b9f0923c5aa3d4ae0a21de6ff9e0959b478f5b8"} 18:38:29 INFO - Running post-run listener: copy_logs_to_upload_dir 18:38:29 INFO - Copying logs to upload dir... 18:38:29 INFO - mkdir: /builds/slave/test/build/upload/logs 18:38:29 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1263.723759 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 3 secs) (at 2017-08-27 18:38:29.630193) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-27 18:38:29.634063) ========= 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=eca2ab57546de7b41f256b19000001ef-1503859188.360365-1270311827 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5462d18d4fcb003b414aeb7979d517dc504f4282b2431aec3a2bb638d42918c5d5fdf3bba22e383bfc1d0921cfe00c496db34b6ab5ae738cdc62df8c0bfae6a7", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0eb71c1296288872d793b08ffcc58a452e931c83084e2e4b817c0e5c8e9cab688e2881601546e6d8e5e61b193aab08209446ccab814baa38bf45df5be906c11f", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5bbbefad8d54793dd10b6da569f44aae5f99f113f5b6c1027e881a3a19a4b85c660649fa7b027b92ec076e4436d8e80a7bfa1ba003edbc4a6b568a676d503ce5", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c50da29997ef7f810173784e1256c3cec5632c86a6e075c690e4f215c087d596850d214be7eb1f008c6e2a1e2b9f0923c5aa3d4ae0a21de6ff9e0959b478f5b8"} build_url:https://queue.taskcluster.net/v1/task/FgF7GK1MTSiueBqY-_8-1Q/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011340 build_url: 'https://queue.taskcluster.net/v1/task/FgF7GK1MTSiueBqY-_8-1Q/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5462d18d4fcb003b414aeb7979d517dc504f4282b2431aec3a2bb638d42918c5d5fdf3bba22e383bfc1d0921cfe00c496db34b6ab5ae738cdc62df8c0bfae6a7", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0eb71c1296288872d793b08ffcc58a452e931c83084e2e4b817c0e5c8e9cab688e2881601546e6d8e5e61b193aab08209446ccab814baa38bf45df5be906c11f", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5bbbefad8d54793dd10b6da569f44aae5f99f113f5b6c1027e881a3a19a4b85c660649fa7b027b92ec076e4436d8e80a7bfa1ba003edbc4a6b568a676d503ce5", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c50da29997ef7f810173784e1256c3cec5632c86a6e075c690e4f215c087d596850d214be7eb1f008c6e2a1e2b9f0923c5aa3d4ae0a21de6ff9e0959b478f5b8"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-27 18:38:29.663157) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-27 18:38:29.663511) ========= 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=eca2ab57546de7b41f256b19000001ef-1503859188.360365-1270311827 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004529 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-27 18:38:29.745628) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-27 18:38:29.749018) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-27 18:38:29.749383) ========= ========= Total master_lag: 0.29 =========