builder: mozilla-central_ubuntu64_hw_stylo_test-dromaeojs-e10s slave: talos-linux64-ix-088 starttime: 1503883045.36 results: success (0) revision: d10c97627b51a226e19d0fa801201897fe1932f6 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-08-27 18:17:25.356293) ========= 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:25.356677) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-27 18:17:25.356955) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-27 18:17:25.500747) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-27 18:17:25.501013) ========= 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=9f7544b6a75ada7b6b576032000001a7-1503859159.723767-947771518 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-08-27 18:17:25-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 222M=0s 2017-08-27 18:17:25 (222 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.109225 ========= master_lag: 0.05 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-27 18:17:25.656366) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-27 18:17:25.656699) ========= 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=9f7544b6a75ada7b6b576032000001a7-1503859159.723767-947771518 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018792 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-27 18:17:25.711184) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-27 18:17:25.711524) ========= 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=9f7544b6a75ada7b6b576032000001a7-1503859159.723767-947771518 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-08-27 18:17:25,782 truncating revision to first 12 chars 2017-08-27 18:17:25,782 Setting DEBUG logging. 2017-08-27 18:17:25,783 attempt 1/10 2017-08-27 18:17:25,783 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:26,089 unpacking tar archive at: mozilla-central-d10c97627b51/testing/mozharness/ program finished with exit code 0 elapsedTime=0.608071 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-27 18:17:26.356957) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-27 18:17:26.357249) ========= script_repo_revision: d10c97627b51a226e19d0fa801201897fe1932f6 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-27 18:17:26.357597) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-27 18:17:26.357882) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-27 18:17:26.379432) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 56 secs) (at 2017-08-27 18:17:26.379717) ========= /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=9f7544b6a75ada7b6b576032000001a7-1503859159.723767-947771518 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-088', 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.587867Z] 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.610844Z] Finished clobber step (success) 18:17:27 INFO - [mozharness: 2017-08-28 01:17:27.610941Z] 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_stylo_test-dromaeojs-e10s", 18:17:27 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 18:17:27 INFO - "buildername": "Ubuntu HW 12.04 x64 stylo mozilla-central talos dromaeojs-e10s", 18:17:27 INFO - "stage_platform": "linux64-stylo", 18:17:27 INFO - "basedir": "/builds/slave/test", 18:17:27 INFO - "buildnumber": 108, 18:17:27 INFO - "platform": "ubuntu64_hw_stylo", 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": "YxMk_I5uTLK8DZNriZc1ng", 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-088", 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/YxMk_I5uTLK8DZNriZc1ng'}, attempt #1 18:17:28 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YxMk_I5uTLK8DZNriZc1ng'}, attempt #1 18:17:28 INFO - Task dependencies: FgF7GK1MTSiueBqY-_8-1Q 18:17:28 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.986632Z] Finished read-buildbot-config step (success) 18:17:28 INFO - [mozharness: 2017-08-28 01:17:28.986849Z] 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:59 INFO - Content-Length response header: 14496611 18:17:59 INFO - Bytes received: 14496611 18:17:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:17:59 INFO - trying https://queue.taskcluster.net/v1/task/FgF7GK1MTSiueBqY-_8-1Q/artifacts/public/build/target.tar.bz2 18:17:59 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:59 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.344014Z] Finished download-and-extract step (success) 18:18:02 INFO - [mozharness: 2017-08-28 01:18:02.344241Z] 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.366709Z] Finished populate-webroot step (success) 18:18:02 INFO - [mozharness: 2017-08-28 01:18:02.366798Z] 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:02 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 0xf651f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1130a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12d3f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12d1130>, '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 0x12d2030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12d24c0>, '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': '9f7544b6a75ada7b6b576032000001a7-1503859159.723767-947771518', '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': '9f7544b6a75ada7b6b576032000001a7-1503859159.723767-947771518', 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 0xf651f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1130a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12d3f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12d1130>, '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 0x12d2030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12d24c0>, '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': '9f7544b6a75ada7b6b576032000001a7-1503859159.723767-947771518', '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': '9f7544b6a75ada7b6b576032000001a7-1503859159.723767-947771518', 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 0xf651f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1130a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12d3f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12d1130>, '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 0x12d2030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12d24c0>, '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': '9f7544b6a75ada7b6b576032000001a7-1503859159.723767-947771518', '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': '9f7544b6a75ada7b6b576032000001a7-1503859159.723767-947771518', 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:07 INFO - Successfully installed mozsystemmonitor 18:18:07 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 0xf651f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1130a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12d3f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12d1130>, '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 0x12d2030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12d24c0>, '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': '9f7544b6a75ada7b6b576032000001a7-1503859159.723767-947771518', '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': '9f7544b6a75ada7b6b576032000001a7-1503859159.723767-947771518', 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 0xf651f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1130a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12d3f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12d1130>, '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 0x12d2030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12d24c0>, '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': '9f7544b6a75ada7b6b576032000001a7-1503859159.723767-947771518', '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': '9f7544b6a75ada7b6b576032000001a7-1503859159.723767-947771518', 18:18:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:18:09 INFO - '_': '/tools/buildbot/bin/python'} 18:18:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:18:09 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:18:09 INFO - Cleaning up... 18:18:09 INFO - Return code: 0 18:18:09 INFO - Installing blobuploader==1.2.4 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', '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 0xf651f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1130a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12d3f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12d1130>, '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 0x12d2030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12d24c0>, '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': '9f7544b6a75ada7b6b576032000001a7-1503859159.723767-947771518', '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', 'blobuploader==1.2.4'] 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 blobuploader==1.2.4 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': '9f7544b6a75ada7b6b576032000001a7-1503859159.723767-947771518', 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 - 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:11 INFO - Installing collected packages: blobuploader, requests, docopt 18:18:11 INFO - Running setup.py install for blobuploader 18:18:11 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:18:11 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:18:11 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 0xf651f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1130a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12d3f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12d1130>, '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 0x12d2030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12d24c0>, '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': '9f7544b6a75ada7b6b576032000001a7-1503859159.723767-947771518', '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': '9f7544b6a75ada7b6b576032000001a7-1503859159.723767-947771518', 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-lkpZEK-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-sXTquq-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-SYspfe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:18:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:18:12 INFO - Running setup.py (path:/tmp/pip-JUOjJy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:18:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:18:12 INFO - Running setup.py (path:/tmp/pip-L6cqOA-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-aWhpGw-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-RT9oqK-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-peMICH-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-UXtJPE-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-09g_0O-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-st6V0l-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-681ePX-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-BWMfXX-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-fHzDzy-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-AImiHF-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-HGQCmg-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-KKCqQI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:18:13 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:18:13 INFO - Running setup.py install for manifestparser 18:18:13 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:18:13 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:14 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:18:14 INFO - Running setup.py install for moznetwork 18:18:14 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:18:14 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 0xf651f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1130a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12d3f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12d1130>, '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 0x12d2030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12d24c0>, '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': '9f7544b6a75ada7b6b576032000001a7-1503859159.723767-947771518', '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': '9f7544b6a75ada7b6b576032000001a7-1503859159.723767-947771518', 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-anJWf5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:18:15 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:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:18:15 INFO - Running setup.py (path:/tmp/pip-9wj3jy-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-OQH4P2-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-f1aXP1-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-O8l6X1-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-8PcWjY-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-QKC2VG-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-On02ZV-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-bSDKcc-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-Xb4uFB-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-CAJDYq-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-AkNw_y-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-Aw8dsb-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-PiSXAC-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:16 INFO - Running setup.py (path:/tmp/pip-j2UPGp-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-ZZdERz-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-YSkbC0-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 0xf651f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1130a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12d3f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12d1130>, '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 0x12d2030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12d24c0>, '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': '9f7544b6a75ada7b6b576032000001a7-1503859159.723767-947771518', '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': '9f7544b6a75ada7b6b576032000001a7-1503859159.723767-947771518', 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 0xf651f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1130a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12d3f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12d1130>, '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 0x12d2030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12d24c0>, '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': '9f7544b6a75ada7b6b576032000001a7-1503859159.723767-947771518', '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': '9f7544b6a75ada7b6b576032000001a7-1503859159.723767-947771518', 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.429612Z] Finished create-virtualenv step (success) 18:18:21 INFO - [mozharness: 2017-08-28 01:18:21.430157Z] 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.420248Z] Finished install step (success) 18:18:39 INFO - [mozharness: 2017-08-28 01:18:39.420591Z] 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.422259Z] Finished setup-mitmproxy step (success) 18:18:39 INFO - [mozharness: 2017-08-28 01:18:39.422574Z] 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 0x12d1130>, '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 0x12d2030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12d24c0>, '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-088', '--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-088 --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_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 - '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 - 'STYLO_FORCE_ENABLED': '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': '9f7544b6a75ada7b6b576032000001a7-1503859159.723767-947771518', 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-088', '--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:33053' 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:33053/getInfo.html -profile /tmp/tmpyMCPGx/profile 18:18:39 INFO - TEST-INFO | started process 13495 (/builds/slave/test/build/application/firefox/firefox http://localhost:33053/getInfo.html) 18:18:45 INFO - TEST-INFO | 13495: exit 0 18:18:45 INFO - Browser initialized. 18:18:45 INFO - Running cycle 1/1 for dromaeo_css test... 18:18:45 INFO - Using env: {'DISPLAY': ':0', 18:18:45 INFO - 'HOME': '/home/cltbld', 18:18:45 INFO - 'JSGC_DISABLE_POISONING': '1', 18:18:45 INFO - 'LANG': 'en_US.UTF-8', 18:18:45 INFO - 'LANGUAGE': 'en_US:en', 18:18:45 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 18:18:45 INFO - 'LOGNAME': 'cltbld', 18:18:45 INFO - 'MAIL': '/var/mail/cltbld', 18:18:45 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:18:45 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:18:45 INFO - 'MOZ_CRASHREPORTER': '1', 18:18:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:18:45 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 18:18:45 INFO - 'MOZ_NO_REMOTE': '1', 18:18:45 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:18:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:18:45 INFO - 'NO_EM_RESTART': '1', 18:18:45 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:18:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:18:45 INFO - 'PWD': '/builds/slave/test', 18:18:45 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:18:45 INFO - 'RUST_BACKTRACE': 'full', 18:18:45 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 18:18:45 INFO - 'SHELL': '/bin/bash', 18:18:45 INFO - 'SHLVL': '1', 18:18:45 INFO - 'STYLO_FORCE_ENABLED': '1', 18:18:45 INFO - 'TERM': 'linux', 18:18:45 INFO - 'TMOUT': '86400', 18:18:45 INFO - 'USER': 'cltbld', 18:18:45 INFO - 'XDG_SESSION_COOKIE': '9f7544b6a75ada7b6b576032000001a7-1503859159.723767-947771518', 18:18:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:18:45 INFO - '_': '/tools/buildbot/bin/python'} 18:18:45 INFO - TEST-INFO | started process 13720 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpyMCPGx/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 18:18:46 INFO - PID 13720 | 18:18:46 INFO - PID 13720 | (/builds/slave/test/build/application/firefox/firefox:13769): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 18:18:46 INFO - PID 13720 | 18:18:46 INFO - PID 13720 | 18:18:46 INFO - PID 13720 | (/builds/slave/test/build/application/firefox/firefox:13833): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 18:18:46 INFO - PID 13720 | 18:18:46 INFO - PID 13720 | __metrics Screen width/height:1600/1200 18:18:46 INFO - PID 13720 | colorDepth:24 18:18:46 INFO - PID 13720 | Browser inner width/height: 1024/768 18:18:46 INFO - PID 13720 | __metrics 18:18:56 INFO - PID 13720 | RSS: Main: 175222784 18:18:56 INFO - PID 13720 | 18:21:55 INFO - PID 13720 | Cycle 1(1): loaded http://localhost:33053/tests/dromaeo/cssquery-dojo.html (next: http://localhost:33053/tests/dromaeo/cssquery-ext.html) 18:21:56 INFO - PID 13720 | RSS: Main: 178925568 18:21:56 INFO - PID 13720 | 18:24:58 INFO - PID 13720 | Cycle 1(1): loaded http://localhost:33053/tests/dromaeo/cssquery-ext.html (next: http://localhost:33053/tests/dromaeo/cssquery-jquery.html) 18:24:58 INFO - PID 13720 | RSS: Main: 172687360 18:24:58 INFO - PID 13720 | 18:28:00 INFO - PID 13720 | Cycle 1(1): loaded http://localhost:33053/tests/dromaeo/cssquery-jquery.html (next: http://localhost:33053/tests/dromaeo/cssquery-mootools.html) 18:28:01 INFO - PID 13720 | RSS: Main: 177909760 18:28:01 INFO - PID 13720 | 18:31:03 INFO - PID 13720 | Cycle 1(1): loaded http://localhost:33053/tests/dromaeo/cssquery-mootools.html (next: http://localhost:33053/tests/dromaeo/cssquery-prototype.html) 18:31:03 INFO - PID 13720 | RSS: Main: 175419392 18:31:03 INFO - PID 13720 | 18:34:06 INFO - PID 13720 | Cycle 1(1): loaded http://localhost:33053/tests/dromaeo/cssquery-prototype.html (next: http://localhost:33053/tests/dromaeo/cssquery-yui.html) 18:34:07 INFO - PID 13720 | RSS: Main: 177270784 18:34:07 INFO - PID 13720 | 18:37:09 INFO - PID 13720 | Cycle 1(1): loaded http://localhost:33053/tests/dromaeo/cssquery-yui.html (next: http://localhost:33053/tests/dromaeo/cssquery-dojo.html) 18:37:09 INFO - PID 13720 | RSS: Main: 180297728 18:37:09 INFO - PID 13720 | 18:37:09 INFO - PID 13720 | __start_tp_report 18:37:09 INFO - PID 13720 | _x_x_mozilla_page_load 18:37:09 INFO - PID 13720 | _x_x_mozilla_page_load_details 18:37:09 INFO - PID 13720 | |i|pagename|runs| 18:37:09 INFO - PID 13720 | |0;dojo.html;3157.2896098377855;3277.8524966376513;3283.8522266498007;3283.950740738889;3319.3859136059814;6314.842128946767;6340.175777148976;6398.1042654028415;6419.069234960928;6501.38236867498;6207.224096987877;6294.213223347082;6312.2425308962875;6329.58857674252;6337.144485494457;5790.13148027795;5837.270341207349;5839.8540036499;5871.324797648261;5952.047672372421;5949.88100237995;5956.642601443906;5963.3738457462;5986.700664966735;5987.970060149693;1083.7160735769246;5801.477866991949;5863.677497737623;5908.054711246202;5994.190784244115;5794.855128621776;5798.710064496759;5814.273215848019;5916.940830591682;5927.585069045169;5809.2738407699035;5825.396511098364;5902.026165682657;5908.14331921869;5918.023526118185;6325.96837015812;6336.778212762577;6361.809145725636;6424.839379015515;6438;6786.524943253974;6820.965895170492;6910.965445172792;6918.342757438086;6930.06444130041;6898.310168983112;6903.102596662402;6908.274631163706;6993.125859267591;6995.40539054181;6246.843828904268;6331.461825744771;6355.205599300088;6360.840978975516;6361.236651601791;7011.368976792113;7012.333828286304;7021.122359705037;7022.964885175644;7026.437884969292;6673.265940746514;6698.497612679069;6704.161979752531;6705.362990515893;6706.329367063255;6772.356626120511;6819.352161544645;6821.386075253251;6831.248562658104;6906.689198986058;6765.72937082521;6818.693158807866;6882.793516194522;6898.827529311707;6908.723651053901;6716.630585317855;6828.726850926007;6858.759943401957;6863.142107236596;6903.620300883399;5381.4349493303425;5401.864953376197;5421.674699518042;5423.322084739408;5431.456854314537;4688.859334219979;4743.833965811249;4744.620430365562;4751.382320298339;4786.95213047872;6011;6017.518598512109;6043.7280322385595;6067.757289708362;6102.962496375627;6722.731090756412;6812.727490900308;6890.552114112566;6939.548929319578;6964.965175174092;6051.546134039964;6055.333913269536;6063.787767428119;6079.148919151322;6093.177421048191;5855.560832937546;5872.677002764889;5916.970415147983;5942.316633587193;5958.7914422995855;6518.054882042077;6553;6598.37315455031;6629.801105966829;6640.568363056386;6740.292269311848;6839.6580170990255;6848.828779280558;6870.62211578368;6990.196127445409;6557.114789503356;6568.934310656832;6586.40722334973;6593.769218077345;6593.835154121185;6737.090492783607;6753.864922701617;6842.486813489108;6852.280510546321;6880.415164711056;6852.965735171292;6921.584704917721;6951.27011663768;6973.860522789414;6979.336962988508;6489.740410383721;6559.114519539992;6609.768658096944;6647.60114393138;6744.190697116377;6700.229473610402;6700.93299067003;6702.564333318319;6703.363180497845;6711.899321510084;6571.671416429255;6596.90104648421;6698.799036028927;6699.129113215251;6700.564463309869;6653.900191497035;6751.966240168767;6801.557898736566;6813.591184528944;6847.07564478789;6304;6430.614163150226;6441.355864413521;6475.7733479328;6547.508936829852 18:37:09 INFO - PID 13720 | |1;ext.html;767.251929368865;798.0822054637166;819.512390127874;828.0436096308762;1122.7866705325991;29276.26809329768;30254;40145.99635009123;41134;41256.38115428271;40740.796296018554;41233.38149927746;41253.79373103131;41261.17477650445;41270.58729412705;7847.72532961345;7983.520988740665;7987.960060199691;7999.40004499662;8000.639971201281;7831.256030677105;7906.2884340409355;7909.841803163933;7934.7222847200355;7937.960310198441;7914.841703165933;7928.286454219119;7938.563379014152;7959.562224077674;7960.800979975518;7848.568328741917;7925.048994120713;7928.127905930343;7929.365650747926;7932.682692692314;7788.260115289038;7839.137694853562;7853.0576330840095;7855.607219638995;7859.6070196489945;36596;36706.26587468236;36983.445248321295;37044.44433333502;37073.629263707284;3500.7899526028264;3523.559555055618;3528.329617372691;3538.9469157962653;3544.858205671796;30585.08244752661;30953.916612918663;31005.534916976263;31014;31080.067597972098;5160.225966105077;5164.096283150434;5192.558632516203;5193.506616871429;5229.816956406507;182026;182128.99999999866;182257;182400.0879995605;183348;45187;45280.3207951881;45397.546024539995;45479.317810232875;45584.77207614007;47398.5260147401;47472.7626361866;47770.04459910782;47806.760966195645;47958.76020619944;15190.772138417933;15546.76679849803;15578.143202123765;15606.843931560767;15612.3755049799;13011.349432528335;13019.934900325627;13020.47918083266;13022.153560018569;13034.674133146747;12175.939120304341;12378.44297006637;12398.5660501882;12474.251544907334;12539.623811285675;5169.741512924339;5178;5236.136037553837;5237.292965449692;5244.580433565306;997.7256254530063;999.3803841618243;1005.8290090684454;1015.8933312002281;1016.5781200801732;21883.781162188494;21888.343349699535;22137.557248854933;22147.557048858933;22203.55592888133;1062.6971313175864;1103.27184058521;1105.690406686129;1106.6071544601712;1109.3842917181041;11578.42107894602;11751.706207344885;11840.822387664191;11845.5854045108;11887;229062;229678;230572;235595;237211;54099;54291.45708542943;54351.72824135933;54586;54614.18078728822;1405.2201028429115;1429.4925301517812;1432.6776475292975;1438.3671184678708;1440.8487108853628;25497.362565936;25571.66634178474;25700.87149564315;25842;26149.869250653624;34260;34391.140221494665;34439.65560344364;34521.48217776786;34664;183383;183629;183891;183914;184173;31309.217269568442;31441.842790786814;31458;31478;31486;43748;44100.33849492324;44211.55788442074;44710.10579788451;44753;32309;32483.35033299368;32485.837570811993;32640.183995400304;32998.50502242516;31615.525767113973;31961.520577190895;31973.20066998344;32100.678993209767;32569.674303257612;36489.270214596094;36560.634393655724;36563.817180913924;36676.08309792277;36862.44706329353;31475.685243147276;31650.366992659558;31775.523367149974;31854.522182167748;31974.68025319717;22777.702847837325;22858.657120143544;23295.41761455977;23460.296191114296;23525.41186470352 18:37:09 INFO - PID 13720 | |2;jquery.html;561.9381867994521;565.4006752841985;570.4552152694175;571.1204744693173;586.9442402971717;21780.34658960232;22132.778672213273;22801.31596052119;22822.08711651532;22847.771522284773;24642.507149856992;25046.499070018588;25136.874315628396;25173.24480265595;25238.116665916696;6516.739330426789;6665.533412661116;6670.6331151786635;6671.532992690513;6673.132492775957;6150.969245153768;6557.704903279364;6630.4364129048845;6705.966470167642;6714.932850671504;6672.5662831916015;6678.56589321695;6688.297728738486;6695.866082678343;6698.430633396143;6642.136522252124;6643.900341494881;6663.966680166593;6677.232118306409;6678.599284042949;6144.723487443076;6165.7533698652;6166.044263139221;6166.383361663842;6181.536384771161;28171.29571760702;28744.856275718696;29053.128406147636;29064.564031539543;29641.703582964325;1985.9205631774712;2025.3518873960338;2025.7366542349548;2033.2070492507933;2034.1456588232977;19339.613207735765;19347.709784353247;20509;20533.486662833548;20548.897255513777;2433.8661373624445;2450.093465417807;2492.3519884829893;2494.451220731436;2510.3598582361383;135369.32315338458;138808.30595846957;139053;139092.30453847768;139823;14534;14756.554307116105;14780.630484237765;14790.778138327934;15191.392344306325;20449.386518404473;20484;20487.487812804502;20487.8975605121;20574.58850822975;9054.592543335566;9162.175404213653;9220.077992200726;9267.44395336282;10143.64497242593;8002.119766825761;8053.395995300376;8107.148749381348;8130.3495720342235;8132.146124656944;9065.41074830134;9065.77335566616;9080.22818060459;9277.536123193948;9410.623575057058;2666.9066582669902;2670.7596316331624;2733.7812974961957;2792.6992431655026;2854.24362543926;961.9182369498672;967.8209531236744;972.7568107973007;982.7985263021063;983.9360441571275;18937.81062189388;19290.4212873614;19613.117409716597;19779.60440791176;19780.109895054757;1016.1362841584594;1027.3681685763202;1027.6146445083093;1030.443560477334;1031.664708969588;10533.420661863518;10598.523066462029;10611.575536978435;10737.892621073846;10953.452327383598;505762;508279;510824.8917510852;513375;524628;111772;112084.43957780159;114227;114911;114932.42533787277;1038.9792204156026;1082.5290998415558;1136.0854512117824;1186.2763714134371;1191.4042978510572;20431.284905028253;20530.69203961912;20597.279095231595;20615.690764638242;20735.68896466524;54630.45369546254;55191.72404137954;55914.72042639761;55974.72012640074;57379.71310143422;67090.66454667696;68934;69206;69449;70024;13932.164070155417;14232.430702771773;14232.857671423153;14246.786298205328;14259.857401426269;22227.666585000916;22279.888600556893;22585.322440326818;22591.661125082806;22609.773902261426;17584;17942.28230870803;18093.276268949096;18101.547461313574;18227.726584100983;14687.192204428431;14971.625709357353;15194.544163674665;15454.227288635748;15552.37790488413;18611.627767444304;18711.1579978904;18713.345032923782;18759.249630014645;18867;13349.866501334862;14002.929985350009;14162.787558186841;14166.433342666178;14170.43318267299;16973.745393818856;17073.48779536616;17316.740248896527;17701.114944252993;17721.91139044348 18:37:09 INFO - PID 13720 | |3;mootools.html;1288.4910460368155;1352.8985326100544;1356.8236129303193;1372.8421231558361;1373.4231622718464;14345.856541434583;14764.409423623069;15128;15138.545843624695;15152.696946061073;14959.850401495982;15207.087574745523;15259.847401525982;15264.313105910232;15273.694526109499;2661.44109736955;2707.7698395636394;2719.524083285427;2735.575985722216;2736.6715994080637;2613.3727905302685;2650.350664087301;2716.5925111233373;2730.986345068272;2731.1396909973396;2569.871506424681;2588.3787890906237;2597.727238639944;2613.63409122723;2616.2020583721956;2662.9334266643295;2666.8799904004172;2694.878730457134;2711.064682684471;2712.7694145997616;2655.8937642494275;2669.012465387819;2677.5448173810305;2684.503366877134;2691.0581296546247;14298.857011429856;14622;14627.853721462756;14632.41470341185;14635.63410914725;7523.435742319293;7747.961260193663;7756.418268629819;7785.182555831613;7832.451728379016;5371.597130215249;5390.78436862525;5446.83659490212;5529.198266251411;5626.887462250773;1860.5627677495777;1869.9813001869943;1906.4852489827808;1914.1864707499258;1935.5644979879555;25279.11523096683;25513.74486255132;25538.233852984253;25604.615930761054;25609.61585576218;6420.871582568322;6542.7710030149665;6600.2409722882485;6726.428253598499;6796.048553202556;16615.50153495397;16616.086115263777;16624.83375166257;16647.667046659;16659.25033373501;7588.734394296173;7748.070231572247;7750.844983100306;7753.728619498291;7757.689692412353;6557.377049180321;7197.244289349647;7313.012743279695;7331.156916954512;7341.77974660761;4593.747343896118;5033.672811267329;5153.665011774223;5217.217417387346;5256.316678831728;1387.341013018808;1535.838736932606;1556.8676662483815;1562.2735428026022;1564.5932057665088;826.1325608111459;827.8923739913772;833.9166083391613;851.5742128935532;867.5445391169686;7654.923450765533;8045.316148127357;8209.13804050578;8590.355723320776;8603.827923441497;957.2246480350799;970.6360114956891;983.7098056073476;1006.3408467453829;1024.533837104116;4820.566149046603;4939.0121975605025;4997.525235102659;5029.346184995927;5187.559057480081;20270;20458.897705511376;21184.78815211859;21513.031913563926;21634.459138521663;20730.896345518177;20904;21058.684119738216;21119.155233790476;21183.78816211849;10326.793464130675;10882.401467919182;11138.3873886937;11154.10767138627;11174.60888868902;7994.2805147537;8113.269805717514;8128.918710813053;8283.917160828556;8458.957705211435;17895.910520447316;17928.103594819946;18092.638147237245;18235.270589176285;18936.958467284432;17966;18497.815021850147;18738.344157954405;18876.150573223964;19195.61608767845;11348.773024539298;11555.306681599131;11721.003714684348;11804.173707840711;11809.586664466702;12994.35028248565;13276.53532126371;13278.468861245829;13281.269530175547;13286.269255190671;10745.194110441591;10855.945720271347;10873.510692019036;10893.237473376788;11143.777124457627;10580.523876425425;10581.735456613646;10584.84122738175;10792.352458852494;10990.17573681961;8665.480071195749;8728.170823771732;9037.50293733851;9048.095190480899;9057.411268267806;6291.905621415591;6304.495640348669;6352.078948552342;6360.459360954371;6525.445337146396;6726.461883049442;6809.114815074008;6873.621950792754;6916;6926.896096558657 18:37:09 INFO - PID 13720 | |4;prototype.html;501.9799208031679;504.5938019893985;522.837920244724;524.7612336386942;527.1328664347149;6415.230172379309;6421.967890160548;6424.261209960856;6440.454290970169;6448.999999999988;6260.311365749764;6307.180066591348;6309.369063093688;6330.6834658267135;6333;5166.457521960197;5189.922151167735;5195.324607800981;5255.789768409278;5283.471652834724;5227.686338819664;5229.006488767122;5338.813141540047;5343.545798607123;5348.304720386363;5278.261043453919;5291.761870715808;5296.761645725952;5327.121025030865;5358.65168764031;5219.321488206528;5257.158854583267;5290.709011004393;5298.695067645586;5341.065313570149;4932.53140951609;4940.308356830046;4944.33251511045;4968.403791545002;4976.884823949807;6164.4143806338325;6222.875542489171;6328.398802113792;6368.331325210879;6415.454686351665;5995.880082398371;6006.369331220247;6012.18835457212;6054.909176362358;6066.423689749467;6707.396334329934;6709.329067093252;6737.898931516032;6740.359665831787;6771.187457505131;5333.57331413486;5346.251524786533;5367.677939323614;5385.488378604026;5393.05621516237;6612.801615951529;6772.356626120511;6916.723331066752;6993.720251189946;7054.470914681368;5505.36645813761;5524.530414914777;5534.667919924736;5552.7778888844805;5582.91625625616;5541.501264886179;5575.414581468968;5578.9163162552595;5591.133374326986;5645.350784659735;6045.153678485016;6101.223943105876;6104.351825007223;6124.173236613089;6132.356102609251;6723.697433615499;6778.8305292367095;6820.965895170492;6838.48711346651;6839.179298484114;1936.4190742777112;1945;1961.9509512262307;1967.6458237517097;1983.7520309961255;1379.951701690436;1418.9290535473185;1449;1452.2738630684657;1462.517369268139;5912.290525137011;5961.4038596140035;5988.281406231193;6043.184170136975;6046.395360463918;6693.899591506131;6747.831304217336;6838.521303508706;6898.793036208922;6915.89626155608;5934.643921364732;5941.910871336933;5963.5825492216;5970.701464926823;6063.636181829104;3583.674040604993;3594.155373487228;3624.2932628137787;3648.8540458381367;3650.379435495972;6421.5825971311715;6429.331827291621;6476.384743449365;6561.639109848908;6577.079208910756;7033.788986330418;7041.964790176016;7045.647717614099;7062.92937070633;7079.469039822034;6526.314736952551;6527.869442611216;6572.671366431755;6583.506237032242;6652.201735791639;6773.966130169318;6819.874720671034;6822.965885170543;6915.515913886181;6920.273371295942;6783.796486105425;6830.351116643911;6846.418054465426;6846.897296540456;6864.519483636098;6467.838304042437;6556;6557.573757705733;6570.704318305592;6610.4711623071;5428.83713488596;5472.50747432733;5540.833774986596;5541.806036788693;5548.639338442988;6528.4419991105915;6633.943477521983;6680.5991640501725;6758.831029224309;6807.21717002551;6648.235452922976;6651.235107962646;6651.866962660816;6663.800085997428;6672.499562532732;6534.803955881332;6559.311272316529;6583.267943803268;6585.769498067736;6696.430803381767;6481.4814814815645;6495.480361570969;6498;6534.444572211415;6630;6602.372774586406;6654.733810647714;6657.468782180029;6729.528932974645;6734.225564060196;6401.807945761635;6432.6140431572385;6435.742570297136;6505.121808555785;6520.478361731145 18:37:09 INFO - PID 13720 | |5;yui.html;868.9913100868989;884.3941899798639;895.7088946092522;897.9685711000117;898.4654130792178;16085.75871361929;16924.66150676986;16957;16968.660626787456;18148.09259537021;17887.821121788777;17936.82063179368;17955.371561995333;17996.10019499023;18005.279788808464;919.1681528216974;926.4811705444955;934.8130373925209;936.9109934556207;936.9859452108224;690.3890057299301;703.5180901082749;907.6732376344511;922.912323329286;929.60027188309;986.8371718666411;992.7567745902228;992.7865508915581;993.359283262299;993.73169244304;982.9017098290185;987.7876256604865;988.125508924607;988.243993345088;988.6144403682498;725.7822653204071;725.8838585826243;726.0234983946583;726.3426598927925;727.3817255332978;16880.07159606221;17174.74237886433;17259.30962761488;17324.653506929917;17394.30422783087;20214.696779548172;20326.695099573517;20633.17467301306;20803.68794468084;20837.479063023547;15586.922065389714;15916.204189790464;15950.282237299349;16132.59668508285;16141.596460088474;3626.4016437287814;3691.667749902495;3691.8154092295276;3706.944395834065;3707.870224542155;128805;130414.34792825975;130993;131193;133121.33439332838;15872.365105395655;15969.760453593206;16052.438164664181;16208.35166593323;16275;29066;29076.709232907822;29243.853780731384;29284;29318.853405732836;7936.6031698414845;7982.201779822088;8038.718644847402;8039.236272554215;8048.356131509465;4218.240716670967;4319.157764236007;4325.632321252729;4338.739675619409;4340.848070317524;4546.318052292117;5430.864228394243;5470.863228419322;5500.037493438632;5514.531264842453;1047.0681093826502;1050.579768092769;1070.7376692710336;1073.2165519171049;1075.8816530181673;662.3575132121834;681.7784220128478;682.969266383014;690.3372762148293;697.3096634332077;11216.719582010352;11405.14461415397;11425;12000.1599888007;12621.684457888627;668.1247565688963;675.4933799650262;676.4960104722013;676.6921050921819;694.8645014222279;4921.212605983025;5091.363579552556;5133.153029750124;6067.817965461043;6102.206713127353;105742;108534.45732771285;109355;111218.44390778156;111905.44047279711;95105;96268.51865740627;96669.51665241629;97003.51498242463;101758.47362289572;88546.55726721454;88628.11371886327;90113.09886901047;90393;90919;12640.74718505643;12795.74408511806;12846.421911014213;12948;12991.28547929835;38957.41563876601;39626.60373396229;40292.79853600713;40326.1934761309;41745.1650966985;49683.751581241864;50186;50296.497035029184;51324.48675513197;74667.62666186634;19066.142023608692;19215.807841921403;19238.23047078157;19306;19624.116914738584;14179.291035448392;16378.508644740677;19860.801391985897;20160.79839201589;21004.789952100284;15806.9209653951;17358.5660358487;17671.38150164738;18458.077096145407;18756;17301.394451194148;17389.739153912447;17396.565085872953;17504.73742893883;17536.12319384;17476.03881786514;17681.204345804726;18159;18331.45005649833;18397.172127254573;13112.278824664354;13268.004899632373;13283.00377471674;13310.201387916755;13367.465301387836;12990;13065.412056457671;13200.537981170613;13436.731265374834;13954.162750235018 18:37:09 INFO - PID 13720 | __end_tp_report 18:37:09 INFO - PID 13720 | __start_cc_report 18:37:09 INFO - PID 13720 | _x_x_mozilla_cycle_collect,367 18:37:09 INFO - PID 13720 | __end_cc_report 18:37:09 INFO - PID 13720 | __startTimestamp1503884229626__endTimestamp 18:37:09 INFO - PID 13720 | 18:37:09 INFO - PID 13720 | ------- Summary: start ------- 18:37:09 INFO - PID 13720 | Number of tests: 6 18:37:09 INFO - PID 13720 | 18:37:09 INFO - PID 13720 | [#0] dojo.html Cycles:165 Average:6278.67 Median:6553.00 stddev:845.11 (12.9%) stddev-sans-first:811.45 18:37:09 INFO - PID 13720 | Values: 3157.3 3277.9 3283.9 3284.0 3319.4 6314.8 6340.2 6398.1 6419.1 6501.4 6207.2 6294.2 6312.2 6329.6 6337.1 5790.1 5837.3 5839.9 5871.3 5952.0 5949.9 5956.6 5963.4 5986.7 5988.0 1083.7 5801.5 5863.7 5908.1 5994.2 5794.9 5798.7 5814.3 5916.9 5927.6 5809.3 5825.4 5902.0 5908.1 5918.0 6326.0 6336.8 6361.8 6424.8 6438.0 6786.5 6821.0 6911.0 6918.3 6930.1 6898.3 6903.1 6908.3 6993.1 6995.4 6246.8 6331.5 6355.2 6360.8 6361.2 7011.4 7012.3 7021.1 7023.0 7026.4 6673.3 6698.5 6704.2 6705.4 6706.3 6772.4 6819.4 6821.4 6831.2 6906.7 6765.7 6818.7 6882.8 6898.8 6908.7 6716.6 6828.7 6858.8 6863.1 6903.6 5381.4 5401.9 5421.7 5423.3 5431.5 4688.9 4743.8 4744.6 4751.4 4787.0 6011.0 6017.5 6043.7 6067.8 6103.0 6722.7 6812.7 6890.6 6939.5 6965.0 6051.5 6055.3 6063.8 6079.1 6093.2 5855.6 5872.7 5917.0 5942.3 5958.8 6518.1 6553.0 6598.4 6629.8 6640.6 6740.3 6839.7 6848.8 6870.6 6990.2 6557.1 6568.9 6586.4 6593.8 6593.8 6737.1 6753.9 6842.5 6852.3 6880.4 6853.0 6921.6 6951.3 6973.9 6979.3 6489.7 6559.1 6609.8 6647.6 6744.2 6700.2 6700.9 6702.6 6703.4 6711.9 6571.7 6596.9 6698.8 6699.1 6700.6 6653.9 6752.0 6801.6 6813.6 6847.1 6304.0 6430.6 6441.4 6475.8 6547.5 18:37:09 INFO - PID 13720 | 18:37:09 INFO - PID 13720 | [#1] ext.html Cycles:180 Average:36562.84 Median:33629.25 stddev:52046.75 (154.8%) stddev-sans-first:52123.35 18:37:09 INFO - PID 13720 | Values: 767.3 798.1 819.5 828.0 1122.8 29276.3 30254.0 40146.0 41134.0 41256.4 40740.8 41233.4 41253.8 41261.2 41270.6 7847.7 7983.5 7988.0 7999.4 8000.6 7831.3 7906.3 7909.8 7934.7 7938.0 7914.8 7928.3 7938.6 7959.6 7960.8 7848.6 7925.0 7928.1 7929.4 7932.7 7788.3 7839.1 7853.1 7855.6 7859.6 36596.0 36706.3 36983.4 37044.4 37073.6 3500.8 3523.6 3528.3 3538.9 3544.9 30585.1 30953.9 31005.5 31014.0 31080.1 5160.2 5164.1 5192.6 5193.5 5229.8 182026.0 182129.0 182257.0 182400.1 183348.0 45187.0 45280.3 45397.5 45479.3 45584.8 47398.5 47472.8 47770.0 47806.8 47958.8 15190.8 15546.8 15578.1 15606.8 15612.4 13011.3 13019.9 13020.5 13022.2 13034.7 12175.9 12378.4 12398.6 12474.3 12539.6 5169.7 5178.0 5236.1 5237.3 5244.6 997.7 999.4 1005.8 1015.9 1016.6 21883.8 21888.3 22137.6 22147.6 22203.6 1062.7 1103.3 1105.7 1106.6 1109.4 11578.4 11751.7 11840.8 11845.6 11887.0 229062.0 229678.0 230572.0 235595.0 237211.0 54099.0 54291.5 54351.7 54586.0 54614.2 1405.2 1429.5 1432.7 1438.4 1440.8 25497.4 25571.7 25700.9 25842.0 26149.9 34260.0 34391.1 34439.7 34521.5 34664.0 183383.0 183629.0 183891.0 183914.0 184173.0 31309.2 31441.8 31458.0 31478.0 31486.0 43748.0 44100.3 44211.6 44710.1 44753.0 32309.0 32483.4 32485.8 32640.2 32998.5 31615.5 31961.5 31973.2 32100.7 32569.7 36489.3 36560.6 36563.8 36676.1 36862.4 31475.7 31650.4 31775.5 31854.5 31974.7 22777.7 22858.7 23295.4 23460.3 23525.4 18:37:09 INFO - PID 13720 | 18:37:09 INFO - PID 13720 | [#2] jquery.html Cycles:180 Average:35057.20 Median:22588.49 stddev:86032.89 (380.9%) stddev-sans-first:86235.25 18:37:09 INFO - PID 13720 | Values: 561.9 565.4 570.5 571.1 586.9 21780.3 22132.8 22801.3 22822.1 22847.8 24642.5 25046.5 25136.9 25173.2 25238.1 6516.7 6665.5 6670.6 6671.5 6673.1 6151.0 6557.7 6630.4 6706.0 6714.9 6672.6 6678.6 6688.3 6695.9 6698.4 6642.1 6643.9 6664.0 6677.2 6678.6 6144.7 6165.8 6166.0 6166.4 6181.5 28171.3 28744.9 29053.1 29064.6 29641.7 1985.9 2025.4 2025.7 2033.2 2034.1 19339.6 19347.7 20509.0 20533.5 20548.9 2433.9 2450.1 2492.4 2494.5 2510.4 135369.3 138808.3 139053.0 139092.3 139823.0 14534.0 14756.6 14780.6 14790.8 15191.4 20449.4 20484.0 20487.5 20487.9 20574.6 9054.6 9162.2 9220.1 9267.4 10143.6 8002.1 8053.4 8107.1 8130.3 8132.1 9065.4 9065.8 9080.2 9277.5 9410.6 2666.9 2670.8 2733.8 2792.7 2854.2 961.9 967.8 972.8 982.8 983.9 18937.8 19290.4 19613.1 19779.6 19780.1 1016.1 1027.4 1027.6 1030.4 1031.7 10533.4 10598.5 10611.6 10737.9 10953.5 505762.0 508279.0 510824.9 513375.0 524628.0 111772.0 112084.4 114227.0 114911.0 114932.4 1039.0 1082.5 1136.1 1186.3 1191.4 20431.3 20530.7 20597.3 20615.7 20735.7 54630.5 55191.7 55914.7 55974.7 57379.7 67090.7 68934.0 69206.0 69449.0 70024.0 13932.2 14232.4 14232.9 14246.8 14259.9 22227.7 22279.9 22585.3 22591.7 22609.8 17584.0 17942.3 18093.3 18101.5 18227.7 14687.2 14971.6 15194.5 15454.2 15552.4 18611.6 18711.2 18713.3 18759.2 18867.0 13349.9 14002.9 14162.8 14166.4 14170.4 16973.7 17073.5 17316.7 17701.1 17721.9 18:37:09 INFO - PID 13720 | 18:37:09 INFO - PID 13720 | [#3] mootools.html Cycles:180 Average:9064.94 Median:2613.50 stddev:6479.27 (247.9%) stddev-sans-first:6471.10 18:37:09 INFO - PID 13720 | Values: 1288.5 1352.9 1356.8 1372.8 1373.4 14345.9 14764.4 15128.0 15138.5 15152.7 14959.9 15207.1 15259.8 15264.3 15273.7 2661.4 2707.8 2719.5 2735.6 2736.7 2613.4 2650.4 2716.6 2731.0 2731.1 2569.9 2588.4 2597.7 2613.6 2616.2 2662.9 2666.9 2694.9 2711.1 2712.8 2655.9 2669.0 2677.5 2684.5 2691.1 14298.9 14622.0 14627.9 14632.4 14635.6 7523.4 7748.0 7756.4 7785.2 7832.5 5371.6 5390.8 5446.8 5529.2 5626.9 1860.6 1870.0 1906.5 1914.2 1935.6 25279.1 25513.7 25538.2 25604.6 25609.6 6420.9 6542.8 6600.2 6726.4 6796.0 16615.5 16616.1 16624.8 16647.7 16659.3 7588.7 7748.1 7750.8 7753.7 7757.7 6557.4 7197.2 7313.0 7331.2 7341.8 4593.7 5033.7 5153.7 5217.2 5256.3 1387.3 1535.8 1556.9 1562.3 1564.6 826.1 827.9 833.9 851.6 867.5 7654.9 8045.3 8209.1 8590.4 8603.8 957.2 970.6 983.7 1006.3 1024.5 4820.6 4939.0 4997.5 5029.3 5187.6 20270.0 20458.9 21184.8 21513.0 21634.5 20730.9 20904.0 21058.7 21119.2 21183.8 10326.8 10882.4 11138.4 11154.1 11174.6 7994.3 8113.3 8128.9 8283.9 8459.0 17895.9 17928.1 18092.6 18235.3 18937.0 17966.0 18497.8 18738.3 18876.2 19195.6 11348.8 11555.3 11721.0 11804.2 11809.6 12994.4 13276.5 13278.5 13281.3 13286.3 10745.2 10855.9 10873.5 10893.2 11143.8 10580.5 10581.7 10584.8 10792.4 10990.2 8665.5 8728.2 9037.5 9048.1 9057.4 6291.9 6304.5 6352.1 6360.5 6525.4 6726.5 6809.1 6873.6 6916.0 6926.9 18:37:09 INFO - PID 13720 | 18:37:09 INFO - PID 13720 | [#4] prototype.html Cycles:180 Average:5719.18 Median:6329.54 stddev:1522.59 (24.1%) stddev-sans-first:1475.65 18:37:09 INFO - PID 13720 | Values: 502.0 504.6 522.8 524.8 527.1 6415.2 6422.0 6424.3 6440.5 6449.0 6260.3 6307.2 6309.4 6330.7 6333.0 5166.5 5189.9 5195.3 5255.8 5283.5 5227.7 5229.0 5338.8 5343.5 5348.3 5278.3 5291.8 5296.8 5327.1 5358.7 5219.3 5257.2 5290.7 5298.7 5341.1 4932.5 4940.3 4944.3 4968.4 4976.9 6164.4 6222.9 6328.4 6368.3 6415.5 5995.9 6006.4 6012.2 6054.9 6066.4 6707.4 6709.3 6737.9 6740.4 6771.2 5333.6 5346.3 5367.7 5385.5 5393.1 6612.8 6772.4 6916.7 6993.7 7054.5 5505.4 5524.5 5534.7 5552.8 5582.9 5541.5 5575.4 5578.9 5591.1 5645.4 6045.2 6101.2 6104.4 6124.2 6132.4 6723.7 6778.8 6821.0 6838.5 6839.2 1936.4 1945.0 1962.0 1967.6 1983.8 1380.0 1418.9 1449.0 1452.3 1462.5 5912.3 5961.4 5988.3 6043.2 6046.4 6693.9 6747.8 6838.5 6898.8 6915.9 5934.6 5941.9 5963.6 5970.7 6063.6 3583.7 3594.2 3624.3 3648.9 3650.4 6421.6 6429.3 6476.4 6561.6 6577.1 7033.8 7042.0 7045.6 7062.9 7079.5 6526.3 6527.9 6572.7 6583.5 6652.2 6774.0 6819.9 6823.0 6915.5 6920.3 6783.8 6830.4 6846.4 6846.9 6864.5 6467.8 6556.0 6557.6 6570.7 6610.5 5428.8 5472.5 5540.8 5541.8 5548.6 6528.4 6633.9 6680.6 6758.8 6807.2 6648.2 6651.2 6651.9 6663.8 6672.5 6534.8 6559.3 6583.3 6585.8 6696.4 6481.5 6495.5 6498.0 6534.4 6630.0 6602.4 6654.7 6657.5 6729.5 6734.2 6401.8 6432.6 6435.7 6505.1 6520.5 18:37:09 INFO - PID 13720 | 18:37:09 INFO - PID 13720 | [#5] yui.html Cycles:180 Average:23141.31 Median:25035.39 stddev:32272.77 (128.9%) stddev-sans-first:32319.97 18:37:09 INFO - PID 13720 | Values: 869.0 884.4 895.7 898.0 898.5 16085.8 16924.7 16957.0 16968.7 18148.1 17887.8 17936.8 17955.4 17996.1 18005.3 919.2 926.5 934.8 936.9 937.0 690.4 703.5 907.7 922.9 929.6 986.8 992.8 992.8 993.4 993.7 982.9 987.8 988.1 988.2 988.6 725.8 725.9 726.0 726.3 727.4 16880.1 17174.7 17259.3 17324.7 17394.3 20214.7 20326.7 20633.2 20803.7 20837.5 15586.9 15916.2 15950.3 16132.6 16141.6 3626.4 3691.7 3691.8 3706.9 3707.9 128805.0 130414.3 130993.0 131193.0 133121.3 15872.4 15969.8 16052.4 16208.4 16275.0 29066.0 29076.7 29243.9 29284.0 29318.9 7936.6 7982.2 8038.7 8039.2 8048.4 4218.2 4319.2 4325.6 4338.7 4340.8 4546.3 5430.9 5470.9 5500.0 5514.5 1047.1 1050.6 1070.7 1073.2 1075.9 662.4 681.8 683.0 690.3 697.3 11216.7 11405.1 11425.0 12000.2 12621.7 668.1 675.5 676.5 676.7 694.9 4921.2 5091.4 5133.2 6067.8 6102.2 105742.0 108534.5 109355.0 111218.4 111905.4 95105.0 96268.5 96669.5 97003.5 101758.5 88546.6 88628.1 90113.1 90393.0 90919.0 12640.7 12795.7 12846.4 12948.0 12991.3 38957.4 39626.6 40292.8 40326.2 41745.2 49683.8 50186.0 50296.5 51324.5 74667.6 19066.1 19215.8 19238.2 19306.0 19624.1 14179.3 16378.5 19860.8 20160.8 21004.8 15806.9 17358.6 17671.4 18458.1 18756.0 17301.4 17389.7 17396.6 17504.7 17536.1 17476.0 17681.2 18159.0 18331.5 18397.2 13112.3 13268.0 13283.0 13310.2 13367.5 12990.0 13065.4 13200.5 13436.7 13954.2 18:37:09 INFO - PID 13720 | -------- Summary: end -------- 18:37:09 INFO - PID 13720 | 18:37:10 INFO - TEST-INFO | 13720: exit 0 18:37:10 INFO - TEST-OK | dromaeo_css | took 1110484ms 18:37:10 INFO - TEST-START | kraken 18:37:10 INFO - Initialising browser for kraken test... 18:37:10 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:33053/getInfo.html -profile /tmp/tmp5hC2A9/profile 18:37:10 INFO - TEST-INFO | started process 14142 (/builds/slave/test/build/application/firefox/firefox http://localhost:33053/getInfo.html) 18:37:15 INFO - TEST-INFO | 14142: exit 0 18:37:15 INFO - Browser initialized. 18:37:15 INFO - Running cycle 1/1 for kraken test... 18:37:15 INFO - Using env: {'DISPLAY': ':0', 18:37:15 INFO - 'HOME': '/home/cltbld', 18:37:15 INFO - 'JSGC_DISABLE_POISONING': '1', 18:37:15 INFO - 'LANG': 'en_US.UTF-8', 18:37:15 INFO - 'LANGUAGE': 'en_US:en', 18:37:15 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 18:37:15 INFO - 'LOGNAME': 'cltbld', 18:37:15 INFO - 'MAIL': '/var/mail/cltbld', 18:37:15 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:37:15 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:37:15 INFO - 'MOZ_CRASHREPORTER': '1', 18:37:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:37:15 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 18:37:15 INFO - 'MOZ_NO_REMOTE': '1', 18:37:15 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:37:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:37:15 INFO - 'NO_EM_RESTART': '1', 18:37:15 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:37:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:37:15 INFO - 'PWD': '/builds/slave/test', 18:37:15 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:37:15 INFO - 'RUST_BACKTRACE': 'full', 18:37:15 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 18:37:15 INFO - 'SHELL': '/bin/bash', 18:37:15 INFO - 'SHLVL': '1', 18:37:15 INFO - 'STYLO_FORCE_ENABLED': '1', 18:37:15 INFO - 'TERM': 'linux', 18:37:15 INFO - 'TMOUT': '86400', 18:37:15 INFO - 'USER': 'cltbld', 18:37:15 INFO - 'XDG_SESSION_COOKIE': '9f7544b6a75ada7b6b576032000001a7-1503859159.723767-947771518', 18:37:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:37:15 INFO - '_': '/tools/buildbot/bin/python'} 18:37:15 INFO - TEST-INFO | started process 14371 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp5hC2A9/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 18:37:16 INFO - PID 14371 | 18:37:16 INFO - PID 14371 | (/builds/slave/test/build/application/firefox/firefox:14421): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 18:37:16 INFO - PID 14371 | 18:37:17 INFO - PID 14371 | 18:37:17 INFO - PID 14371 | (/builds/slave/test/build/application/firefox/firefox:14481): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 18:37:17 INFO - PID 14371 | 18:37:17 INFO - PID 14371 | __metrics Screen width/height:1600/1200 18:37:17 INFO - PID 14371 | colorDepth:24 18:37:17 INFO - PID 14371 | Browser inner width/height: 1024/768 18:37:17 INFO - PID 14371 | __metrics 18:37:27 INFO - PID 14371 | RSS: Main: 175042560 18:37:27 INFO - PID 14371 | 18:37:29 INFO - PID 14371 | Cycle 1(1): loaded http://localhost:33053/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:33053/tests/kraken/driver.html?testName=audio-beat-detection) 18:37:30 INFO - PID 14371 | RSS: Main: 178720768 18:37:30 INFO - PID 14371 | 18:37:33 INFO - PID 14371 | Cycle 1(1): loaded http://localhost:33053/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:33053/tests/kraken/driver.html?testName=audio-dft) 18:37:33 INFO - PID 14371 | RSS: Main: 179343360 18:37:33 INFO - PID 14371 | 18:37:36 INFO - PID 14371 | Cycle 1(1): loaded http://localhost:33053/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:33053/tests/kraken/driver.html?testName=audio-fft) 18:37:37 INFO - PID 14371 | RSS: Main: 173821952 18:37:37 INFO - PID 14371 | 18:37:39 INFO - PID 14371 | Cycle 1(1): loaded http://localhost:33053/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:33053/tests/kraken/driver.html?testName=audio-oscillator) 18:37:40 INFO - PID 14371 | RSS: Main: 174358528 18:37:40 INFO - PID 14371 | 18:37:42 INFO - PID 14371 | Cycle 1(1): loaded http://localhost:33053/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:33053/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:37:43 INFO - PID 14371 | RSS: Main: 175013888 18:37:43 INFO - PID 14371 | 18:37:46 INFO - PID 14371 | Cycle 1(1): loaded http://localhost:33053/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:33053/tests/kraken/driver.html?testName=imaging-darkroom) 18:37:46 INFO - PID 14371 | RSS: Main: 175759360 18:37:46 INFO - PID 14371 | 18:37:50 INFO - PID 14371 | Cycle 1(1): loaded http://localhost:33053/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:33053/tests/kraken/driver.html?testName=imaging-desaturate) 18:37:51 INFO - PID 14371 | RSS: Main: 175546368 18:37:51 INFO - PID 14371 | 18:37:53 INFO - PID 14371 | Cycle 1(1): loaded http://localhost:33053/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:33053/tests/kraken/driver.html?testName=json-parse-financial) 18:37:54 INFO - PID 14371 | RSS: Main: 175927296 18:37:54 INFO - PID 14371 | 18:37:56 INFO - PID 14371 | Cycle 1(1): loaded http://localhost:33053/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:33053/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:37:56 INFO - PID 14371 | RSS: Main: 175968256 18:37:56 INFO - PID 14371 | 18:37:58 INFO - PID 14371 | Cycle 1(1): loaded http://localhost:33053/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:33053/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:37:59 INFO - PID 14371 | RSS: Main: 176140288 18:37:59 INFO - PID 14371 | 18:38:01 INFO - PID 14371 | Cycle 1(1): loaded http://localhost:33053/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:33053/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:38:01 INFO - PID 14371 | RSS: Main: 176013312 18:38:01 INFO - PID 14371 | 18:38:04 INFO - PID 14371 | Cycle 1(1): loaded http://localhost:33053/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:33053/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:38:05 INFO - PID 14371 | RSS: Main: 176078848 18:38:05 INFO - PID 14371 | 18:38:08 INFO - PID 14371 | Cycle 1(1): loaded http://localhost:33053/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:33053/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:38:08 INFO - PID 14371 | RSS: Main: 175988736 18:38:08 INFO - PID 14371 | 18:38:10 INFO - PID 14371 | Cycle 1(1): loaded http://localhost:33053/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:33053/tests/kraken/driver.html?testName=ai-astar) 18:38:10 INFO - PID 14371 | RSS: Main: 177594368 18:38:10 INFO - PID 14371 | 18:38:10 INFO - PID 14371 | __start_tp_report 18:38:10 INFO - PID 14371 | _x_x_mozilla_page_load 18:38:10 INFO - PID 14371 | _x_x_mozilla_page_load_details 18:38:10 INFO - PID 14371 | |i|pagename|runs| 18:38:10 INFO - PID 14371 | |0;ai-astar;97;111;106;107;106;110;108;97;108;108 18:38:10 INFO - PID 14371 | |1;audio-beat-detection;142;116;147;168;118;141;138;118;165;117 18:38:10 INFO - PID 14371 | |2;audio-dft;165;163;164;166;163;164;163;164;165;164 18:38:10 INFO - PID 14371 | |3;audio-fft;77;74;73;165;75;74;74;76;152;77 18:38:10 INFO - PID 14371 | |4;audio-oscillator;86;78;128;78;79;79;138;79;80;148 18:38:10 INFO - PID 14371 | |5;imaging-gaussian-blur;115;114;123;114;114;115;117;116;117;122 18:38:10 INFO - PID 14371 | |6;imaging-darkroom;147;149;147;147;147;149;149;148;151;149 18:38:10 INFO - PID 14371 | |7;imaging-desaturate;94;93;94;94;94;94;94;94;94;93 18:38:10 INFO - PID 14371 | |8;json-parse-financial;53;52;51;67;53;54;52;52;52;52 18:38:10 INFO - PID 14371 | |9;json-stringify-tinderbox;38;38;38;38;38;39;38;39;40;39 18:38:10 INFO - PID 14371 | |10;stanford-crypto-aes;73;75;73;73;74;74;73;73;75;73 18:38:10 INFO - PID 14371 | |11;stanford-crypto-ccm;129;128;131;122;124;122;119;120;116;160 18:38:10 INFO - PID 14371 | |12;stanford-crypto-pbkdf2;172;173;170;169;172;172;179;172;182;165 18:38:10 INFO - PID 14371 | |13;stanford-crypto-sha256-iterative;67;65;67;64;64;64;65;63;63;62 18:38:10 INFO - PID 14371 | __end_tp_report 18:38:10 INFO - PID 14371 | __start_cc_report 18:38:10 INFO - PID 14371 | _x_x_mozilla_cycle_collect,971 18:38:10 INFO - PID 14371 | __end_cc_report 18:38:10 INFO - PID 14371 | __startTimestamp1503884290844__endTimestamp 18:38:10 INFO - PID 14371 | 18:38:10 INFO - PID 14371 | ------- Summary: start ------- 18:38:10 INFO - PID 14371 | Number of tests: 14 18:38:10 INFO - PID 14371 | 18:38:10 INFO - PID 14371 | [#0] ai-astar Cycles:10 Average:105.80 Median:109.00 stddev:4.89 (4.5%) stddev-sans-first:4.02 18:38:10 INFO - PID 14371 | Values: 97.0 111.0 106.0 107.0 106.0 110.0 108.0 97.0 108.0 108.0 18:38:10 INFO - PID 14371 | 18:38:10 INFO - PID 14371 | [#1] audio-beat-detection Cycles:10 Average:137.00 Median:141.50 stddev:19.58 (13.8%) stddev-sans-first:20.68 18:38:10 INFO - PID 14371 | Values: 142.0 116.0 147.0 168.0 118.0 141.0 138.0 118.0 165.0 117.0 18:38:10 INFO - PID 14371 | 18:38:10 INFO - PID 14371 | [#2] audio-dft Cycles:10 Average:164.10 Median:164.00 stddev:0.99 (0.6%) stddev-sans-first:1.00 18:38:10 INFO - PID 14371 | Values: 165.0 163.0 164.0 166.0 163.0 164.0 163.0 164.0 165.0 164.0 18:38:10 INFO - PID 14371 | 18:38:10 INFO - PID 14371 | [#3] audio-fft Cycles:10 Average:91.70 Median:74.50 stddev:35.36 (47.5%) stddev-sans-first:37.11 18:38:10 INFO - PID 14371 | Values: 77.0 74.0 73.0 165.0 75.0 74.0 74.0 76.0 152.0 77.0 18:38:10 INFO - PID 14371 | 18:38:10 INFO - PID 14371 | [#4] audio-oscillator Cycles:10 Average:97.30 Median:79.00 stddev:28.57 (36.2%) stddev-sans-first:30.01 18:38:10 INFO - PID 14371 | Values: 86.0 78.0 128.0 78.0 79.0 79.0 138.0 79.0 80.0 148.0 18:38:10 INFO - PID 14371 | 18:38:10 INFO - PID 14371 | [#5] imaging-gaussian-blur Cycles:10 Average:116.70 Median:116.50 stddev:3.27 (2.8%) stddev-sans-first:3.41 18:38:10 INFO - PID 14371 | Values: 115.0 114.0 123.0 114.0 114.0 115.0 117.0 116.0 117.0 122.0 18:38:10 INFO - PID 14371 | 18:38:10 INFO - PID 14371 | [#6] imaging-darkroom Cycles:10 Average:148.30 Median:149.00 stddev:1.34 (0.9%) stddev-sans-first:1.33 18:38:10 INFO - PID 14371 | Values: 147.0 149.0 147.0 147.0 147.0 149.0 149.0 148.0 151.0 149.0 18:38:10 INFO - PID 14371 | 18:38:10 INFO - PID 14371 | [#7] imaging-desaturate Cycles:10 Average:93.80 Median:94.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 18:38:10 INFO - PID 14371 | Values: 94.0 93.0 94.0 94.0 94.0 94.0 94.0 94.0 94.0 93.0 18:38:10 INFO - PID 14371 | 18:38:10 INFO - PID 14371 | [#8] json-parse-financial Cycles:10 Average:53.80 Median:52.50 stddev:4.71 (9.0%) stddev-sans-first:4.99 18:38:10 INFO - PID 14371 | Values: 53.0 52.0 51.0 67.0 53.0 54.0 52.0 52.0 52.0 52.0 18:38:10 INFO - PID 14371 | 18:38:10 INFO - PID 14371 | [#9] json-stringify-tinderbox Cycles:10 Average:38.50 Median:38.50 stddev:0.71 (1.8%) stddev-sans-first:0.73 18:38:10 INFO - PID 14371 | Values: 38.0 38.0 38.0 38.0 38.0 39.0 38.0 39.0 40.0 39.0 18:38:10 INFO - PID 14371 | 18:38:10 INFO - PID 14371 | [#10] stanford-crypto-aes Cycles:10 Average:73.60 Median:73.50 stddev:0.84 (1.1%) stddev-sans-first:0.87 18:38:10 INFO - PID 14371 | Values: 73.0 75.0 73.0 73.0 74.0 74.0 73.0 73.0 75.0 73.0 18:38:10 INFO - PID 14371 | 18:38:10 INFO - PID 14371 | [#11] stanford-crypto-ccm Cycles:10 Average:127.10 Median:126.00 stddev:12.49 (9.9%) stddev-sans-first:13.22 18:38:10 INFO - PID 14371 | Values: 129.0 128.0 131.0 122.0 124.0 122.0 119.0 120.0 116.0 160.0 18:38:10 INFO - PID 14371 | 18:38:10 INFO - PID 14371 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:172.60 Median:172.00 stddev:4.81 (2.8%) stddev-sans-first:5.10 18:38:10 INFO - PID 14371 | Values: 172.0 173.0 170.0 169.0 172.0 172.0 179.0 172.0 182.0 165.0 18:38:10 INFO - PID 14371 | 18:38:10 INFO - PID 14371 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.40 Median:64.50 stddev:1.65 (2.6%) stddev-sans-first:1.45 18:38:10 INFO - PID 14371 | Values: 67.0 65.0 67.0 64.0 64.0 64.0 65.0 63.0 63.0 62.0 18:38:10 INFO - PID 14371 | -------- Summary: end -------- 18:38:10 INFO - PID 14371 | 18:38:10 INFO - PID 14371 | 18:38:10 INFO - PID 14371 | ###!!! [Parent][MessageChannel] Error: (msgtype=0x150082,name=PBrowser::Msg_Destroy) Closed channel: cannot send/recv 18:38:10 INFO - PID 14371 | 18:38:10 INFO - PID 14371 | 18:38:10 INFO - PID 14371 | ###!!! [Child][MessageChannel] Error: (msgtype=0x150001,name=PBrowser::Msg_AsyncMessage) Closed channel: cannot send/recv 18:38:10 INFO - PID 14371 | 18:38:11 INFO - PID 14371 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 18:38:11 INFO - TEST-INFO | 14371: exit 0 18:38:11 INFO - TEST-OK | kraken | took 61273ms 18:38:11 INFO - SUITE-END | took 1171s 18:38:12 INFO - Completed test suite (00:19:32) 18:38:12 INFO - javascript benchmark 18:38:12 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8487.187594127849, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3157.2896098377855, 3277.8524966376513, 3283.8522266498007, 3283.950740738889, 3319.3859136059814, 6314.842128946767, 6340.175777148976, 6398.1042654028415, 6419.069234960928, 6501.38236867498, 6207.224096987877, 6294.213223347082, 6312.2425308962875, 6329.58857674252, 6337.144485494457, 5790.13148027795, 5837.270341207349, 5839.8540036499, 5871.324797648261, 5952.047672372421, 5949.88100237995, 5956.642601443906, 5963.3738457462, 5986.700664966735, 5987.970060149693, 1083.7160735769246, 5801.477866991949, 5863.677497737623, 5908.054711246202, 5994.190784244115, 5794.855128621776, 5798.710064496759, 5814.273215848019, 5916.940830591682, 5927.585069045169, 5809.2738407699035, 5825.396511098364, 5902.026165682657, 5908.14331921869, 5918.023526118185, 6325.96837015812, 6336.778212762577, 6361.809145725636, 6424.839379015515, 6438.0, 6786.524943253974, 6820.965895170492, 6910.965445172792, 6918.342757438086, 6930.06444130041, 6898.310168983112, 6903.102596662402, 6908.274631163706, 6993.125859267591, 6995.40539054181, 6246.843828904268, 6331.461825744771, 6355.205599300088, 6360.840978975516, 6361.236651601791, 7011.368976792113, 7012.333828286304, 7021.122359705037, 7022.964885175644, 7026.437884969292, 6673.265940746514, 6698.497612679069, 6704.161979752531, 6705.362990515893, 6706.329367063255, 6772.356626120511, 6819.352161544645, 6821.386075253251, 6831.248562658104, 6906.689198986058, 6765.72937082521, 6818.693158807866, 6882.793516194522, 6898.827529311707, 6908.723651053901, 6716.630585317855, 6828.726850926007, 6858.759943401957, 6863.142107236596, 6903.620300883399, 5381.4349493303425, 5401.864953376197, 5421.674699518042, 5423.322084739408, 5431.456854314537, 4688.859334219979, 4743.833965811249, 4744.620430365562, 4751.382320298339, 4786.95213047872, 6011.0, 6017.518598512109, 6043.7280322385595, 6067.757289708362, 6102.962496375627, 6722.731090756412, 6812.727490900308, 6890.552114112566, 6939.548929319578, 6964.965175174092, 6051.546134039964, 6055.333913269536, 6063.787767428119, 6079.148919151322, 6093.177421048191, 5855.560832937546, 5872.677002764889, 5916.970415147983, 5942.316633587193, 5958.7914422995855, 6518.054882042077, 6553.0, 6598.37315455031, 6629.801105966829, 6640.568363056386, 6740.292269311848, 6839.6580170990255, 6848.828779280558, 6870.62211578368, 6990.196127445409, 6557.114789503356, 6568.934310656832, 6586.40722334973, 6593.769218077345, 6593.835154121185, 6737.090492783607, 6753.864922701617, 6842.486813489108, 6852.280510546321, 6880.415164711056, 6852.965735171292, 6921.584704917721, 6951.27011663768, 6973.860522789414, 6979.336962988508, 6489.740410383721, 6559.114519539992, 6609.768658096944, 6647.60114393138, 6744.190697116377, 6700.229473610402, 6700.93299067003, 6702.564333318319, 6703.363180497845, 6711.899321510084, 6571.671416429255, 6596.90104648421, 6698.799036028927, 6699.129113215251, 6700.564463309869, 6653.900191497035, 6751.966240168767, 6801.557898736566, 6813.591184528944, 6847.07564478789, 6304.0, 6430.614163150226, 6441.355864413521, 6475.7733479328, 6547.508936829852], "value": 6218.882508348222, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [767.251929368865, 798.0822054637166, 819.512390127874, 828.0436096308762, 1122.7866705325991, 29276.26809329768, 30254.0, 40145.99635009123, 41134.0, 41256.38115428271, 40740.796296018554, 41233.38149927746, 41253.79373103131, 41261.17477650445, 41270.58729412705, 7847.72532961345, 7983.520988740665, 7987.960060199691, 7999.40004499662, 8000.639971201281, 7831.256030677105, 7906.2884340409355, 7909.841803163933, 7934.7222847200355, 7937.960310198441, 7914.841703165933, 7928.286454219119, 7938.563379014152, 7959.562224077674, 7960.800979975518, 7848.568328741917, 7925.048994120713, 7928.127905930343, 7929.365650747926, 7932.682692692314, 7788.260115289038, 7839.137694853562, 7853.0576330840095, 7855.607219638995, 7859.6070196489945, 36596.0, 36706.26587468236, 36983.445248321295, 37044.44433333502, 37073.629263707284, 3500.7899526028264, 3523.559555055618, 3528.329617372691, 3538.9469157962653, 3544.858205671796, 30585.08244752661, 30953.916612918663, 31005.534916976263, 31014.0, 31080.067597972098, 5160.225966105077, 5164.096283150434, 5192.558632516203, 5193.506616871429, 5229.816956406507, 182026.0, 182128.99999999866, 182257.0, 182400.0879995605, 183348.0, 45187.0, 45280.3207951881, 45397.546024539995, 45479.317810232875, 45584.77207614007, 47398.5260147401, 47472.7626361866, 47770.04459910782, 47806.760966195645, 47958.76020619944, 15190.772138417933, 15546.76679849803, 15578.143202123765, 15606.843931560767, 15612.3755049799, 13011.349432528335, 13019.934900325627, 13020.47918083266, 13022.153560018569, 13034.674133146747, 12175.939120304341, 12378.44297006637, 12398.5660501882, 12474.251544907334, 12539.623811285675, 5169.741512924339, 5178.0, 5236.136037553837, 5237.292965449692, 5244.580433565306, 997.7256254530063, 999.3803841618243, 1005.8290090684454, 1015.8933312002281, 1016.5781200801732, 21883.781162188494, 21888.343349699535, 22137.557248854933, 22147.557048858933, 22203.55592888133, 1062.6971313175864, 1103.27184058521, 1105.690406686129, 1106.6071544601712, 1109.3842917181041, 11578.42107894602, 11751.706207344885, 11840.822387664191, 11845.5854045108, 11887.0, 229062.0, 229678.0, 230572.0, 235595.0, 237211.0, 54099.0, 54291.45708542943, 54351.72824135933, 54586.0, 54614.18078728822, 1405.2201028429115, 1429.4925301517812, 1432.6776475292975, 1438.3671184678708, 1440.8487108853628, 25497.362565936, 25571.66634178474, 25700.87149564315, 25842.0, 26149.869250653624, 34260.0, 34391.140221494665, 34439.65560344364, 34521.48217776786, 34664.0, 183383.0, 183629.0, 183891.0, 183914.0, 184173.0, 31309.217269568442, 31441.842790786814, 31458.0, 31478.0, 31486.0, 43748.0, 44100.33849492324, 44211.55788442074, 44710.10579788451, 44753.0, 32309.0, 32483.35033299368, 32485.837570811993, 32640.183995400304, 32998.50502242516, 31615.525767113973, 31961.520577190895, 31973.20066998344, 32100.678993209767, 32569.674303257612, 36489.270214596094, 36560.634393655724, 36563.817180913924, 36676.08309792277, 36862.44706329353, 31475.685243147276, 31650.366992659558, 31775.523367149974, 31854.522182167748, 31974.68025319717, 22777.702847837325, 22858.657120143544, 23295.41761455977, 23460.296191114296, 23525.41186470352], "value": 16851.47904660943, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [561.9381867994521, 565.4006752841985, 570.4552152694175, 571.1204744693173, 586.9442402971717, 21780.34658960232, 22132.778672213273, 22801.31596052119, 22822.08711651532, 22847.771522284773, 24642.507149856992, 25046.499070018588, 25136.874315628396, 25173.24480265595, 25238.116665916696, 6516.739330426789, 6665.533412661116, 6670.6331151786635, 6671.532992690513, 6673.132492775957, 6150.969245153768, 6557.704903279364, 6630.4364129048845, 6705.966470167642, 6714.932850671504, 6672.5662831916015, 6678.56589321695, 6688.297728738486, 6695.866082678343, 6698.430633396143, 6642.136522252124, 6643.900341494881, 6663.966680166593, 6677.232118306409, 6678.599284042949, 6144.723487443076, 6165.7533698652, 6166.044263139221, 6166.383361663842, 6181.536384771161, 28171.29571760702, 28744.856275718696, 29053.128406147636, 29064.564031539543, 29641.703582964325, 1985.9205631774712, 2025.3518873960338, 2025.7366542349548, 2033.2070492507933, 2034.1456588232977, 19339.613207735765, 19347.709784353247, 20509.0, 20533.486662833548, 20548.897255513777, 2433.8661373624445, 2450.093465417807, 2492.3519884829893, 2494.451220731436, 2510.3598582361383, 135369.32315338458, 138808.30595846957, 139053.0, 139092.30453847768, 139823.0, 14534.0, 14756.554307116105, 14780.630484237765, 14790.778138327934, 15191.392344306325, 20449.386518404473, 20484.0, 20487.487812804502, 20487.8975605121, 20574.58850822975, 9054.592543335566, 9162.175404213653, 9220.077992200726, 9267.44395336282, 10143.64497242593, 8002.119766825761, 8053.395995300376, 8107.148749381348, 8130.3495720342235, 8132.146124656944, 9065.41074830134, 9065.77335566616, 9080.22818060459, 9277.536123193948, 9410.623575057058, 2666.9066582669902, 2670.7596316331624, 2733.7812974961957, 2792.6992431655026, 2854.24362543926, 961.9182369498672, 967.8209531236744, 972.7568107973007, 982.7985263021063, 983.9360441571275, 18937.81062189388, 19290.4212873614, 19613.117409716597, 19779.60440791176, 19780.109895054757, 1016.1362841584594, 1027.3681685763202, 1027.6146445083093, 1030.443560477334, 1031.664708969588, 10533.420661863518, 10598.523066462029, 10611.575536978435, 10737.892621073846, 10953.452327383598, 505762.0, 508279.0, 510824.8917510852, 513375.0, 524628.0, 111772.0, 112084.43957780159, 114227.0, 114911.0, 114932.42533787277, 1038.9792204156026, 1082.5290998415558, 1136.0854512117824, 1186.2763714134371, 1191.4042978510572, 20431.284905028253, 20530.69203961912, 20597.279095231595, 20615.690764638242, 20735.68896466524, 54630.45369546254, 55191.72404137954, 55914.72042639761, 55974.72012640074, 57379.71310143422, 67090.66454667696, 68934.0, 69206.0, 69449.0, 70024.0, 13932.164070155417, 14232.430702771773, 14232.857671423153, 14246.786298205328, 14259.857401426269, 22227.666585000916, 22279.888600556893, 22585.322440326818, 22591.661125082806, 22609.773902261426, 17584.0, 17942.28230870803, 18093.276268949096, 18101.547461313574, 18227.726584100983, 14687.192204428431, 14971.625709357353, 15194.544163674665, 15454.227288635748, 15552.37790488413, 18611.627767444304, 18711.1579978904, 18713.345032923782, 18759.249630014645, 18867.0, 13349.866501334862, 14002.929985350009, 14162.787558186841, 14166.433342666178, 14170.43318267299, 16973.745393818856, 17073.48779536616, 17316.740248896527, 17701.114944252993, 17721.91139044348], "value": 11797.335414991683, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1288.4910460368155, 1352.8985326100544, 1356.8236129303193, 1372.8421231558361, 1373.4231622718464, 14345.856541434583, 14764.409423623069, 15128.0, 15138.545843624695, 15152.696946061073, 14959.850401495982, 15207.087574745523, 15259.847401525982, 15264.313105910232, 15273.694526109499, 2661.44109736955, 2707.7698395636394, 2719.524083285427, 2735.575985722216, 2736.6715994080637, 2613.3727905302685, 2650.350664087301, 2716.5925111233373, 2730.986345068272, 2731.1396909973396, 2569.871506424681, 2588.3787890906237, 2597.727238639944, 2613.63409122723, 2616.2020583721956, 2662.9334266643295, 2666.8799904004172, 2694.878730457134, 2711.064682684471, 2712.7694145997616, 2655.8937642494275, 2669.012465387819, 2677.5448173810305, 2684.503366877134, 2691.0581296546247, 14298.857011429856, 14622.0, 14627.853721462756, 14632.41470341185, 14635.63410914725, 7523.435742319293, 7747.961260193663, 7756.418268629819, 7785.182555831613, 7832.451728379016, 5371.597130215249, 5390.78436862525, 5446.83659490212, 5529.198266251411, 5626.887462250773, 1860.5627677495777, 1869.9813001869943, 1906.4852489827808, 1914.1864707499258, 1935.5644979879555, 25279.11523096683, 25513.74486255132, 25538.233852984253, 25604.615930761054, 25609.61585576218, 6420.871582568322, 6542.7710030149665, 6600.2409722882485, 6726.428253598499, 6796.048553202556, 16615.50153495397, 16616.086115263777, 16624.83375166257, 16647.667046659, 16659.25033373501, 7588.734394296173, 7748.070231572247, 7750.844983100306, 7753.728619498291, 7757.689692412353, 6557.377049180321, 7197.244289349647, 7313.012743279695, 7331.156916954512, 7341.77974660761, 4593.747343896118, 5033.672811267329, 5153.665011774223, 5217.217417387346, 5256.316678831728, 1387.341013018808, 1535.838736932606, 1556.8676662483815, 1562.2735428026022, 1564.5932057665088, 826.1325608111459, 827.8923739913772, 833.9166083391613, 851.5742128935532, 867.5445391169686, 7654.923450765533, 8045.316148127357, 8209.13804050578, 8590.355723320776, 8603.827923441497, 957.2246480350799, 970.6360114956891, 983.7098056073476, 1006.3408467453829, 1024.533837104116, 4820.566149046603, 4939.0121975605025, 4997.525235102659, 5029.346184995927, 5187.559057480081, 20270.0, 20458.897705511376, 21184.78815211859, 21513.031913563926, 21634.459138521663, 20730.896345518177, 20904.0, 21058.684119738216, 21119.155233790476, 21183.78816211849, 10326.793464130675, 10882.401467919182, 11138.3873886937, 11154.10767138627, 11174.60888868902, 7994.2805147537, 8113.269805717514, 8128.918710813053, 8283.917160828556, 8458.957705211435, 17895.910520447316, 17928.103594819946, 18092.638147237245, 18235.270589176285, 18936.958467284432, 17966.0, 18497.815021850147, 18738.344157954405, 18876.150573223964, 19195.61608767845, 11348.773024539298, 11555.306681599131, 11721.003714684348, 11804.173707840711, 11809.586664466702, 12994.35028248565, 13276.53532126371, 13278.468861245829, 13281.269530175547, 13286.269255190671, 10745.194110441591, 10855.945720271347, 10873.510692019036, 10893.237473376788, 11143.777124457627, 10580.523876425425, 10581.735456613646, 10584.84122738175, 10792.352458852494, 10990.17573681961, 8665.480071195749, 8728.170823771732, 9037.50293733851, 9048.095190480899, 9057.411268267806, 6291.905621415591, 6304.495640348669, 6352.078948552342, 6360.459360954371, 6525.445337146396, 6726.461883049442, 6809.114815074008, 6873.621950792754, 6916.0, 6926.896096558657], "value": 6502.231221286139, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [501.9799208031679, 504.5938019893985, 522.837920244724, 524.7612336386942, 527.1328664347149, 6415.230172379309, 6421.967890160548, 6424.261209960856, 6440.454290970169, 6448.999999999988, 6260.311365749764, 6307.180066591348, 6309.369063093688, 6330.6834658267135, 6333.0, 5166.457521960197, 5189.922151167735, 5195.324607800981, 5255.789768409278, 5283.471652834724, 5227.686338819664, 5229.006488767122, 5338.813141540047, 5343.545798607123, 5348.304720386363, 5278.261043453919, 5291.761870715808, 5296.761645725952, 5327.121025030865, 5358.65168764031, 5219.321488206528, 5257.158854583267, 5290.709011004393, 5298.695067645586, 5341.065313570149, 4932.53140951609, 4940.308356830046, 4944.33251511045, 4968.403791545002, 4976.884823949807, 6164.4143806338325, 6222.875542489171, 6328.398802113792, 6368.331325210879, 6415.454686351665, 5995.880082398371, 6006.369331220247, 6012.18835457212, 6054.909176362358, 6066.423689749467, 6707.396334329934, 6709.329067093252, 6737.898931516032, 6740.359665831787, 6771.187457505131, 5333.57331413486, 5346.251524786533, 5367.677939323614, 5385.488378604026, 5393.05621516237, 6612.801615951529, 6772.356626120511, 6916.723331066752, 6993.720251189946, 7054.470914681368, 5505.36645813761, 5524.530414914777, 5534.667919924736, 5552.7778888844805, 5582.91625625616, 5541.501264886179, 5575.414581468968, 5578.9163162552595, 5591.133374326986, 5645.350784659735, 6045.153678485016, 6101.223943105876, 6104.351825007223, 6124.173236613089, 6132.356102609251, 6723.697433615499, 6778.8305292367095, 6820.965895170492, 6838.48711346651, 6839.179298484114, 1936.4190742777112, 1945.0, 1961.9509512262307, 1967.6458237517097, 1983.7520309961255, 1379.951701690436, 1418.9290535473185, 1449.0, 1452.2738630684657, 1462.517369268139, 5912.290525137011, 5961.4038596140035, 5988.281406231193, 6043.184170136975, 6046.395360463918, 6693.899591506131, 6747.831304217336, 6838.521303508706, 6898.793036208922, 6915.89626155608, 5934.643921364732, 5941.910871336933, 5963.5825492216, 5970.701464926823, 6063.636181829104, 3583.674040604993, 3594.155373487228, 3624.2932628137787, 3648.8540458381367, 3650.379435495972, 6421.5825971311715, 6429.331827291621, 6476.384743449365, 6561.639109848908, 6577.079208910756, 7033.788986330418, 7041.964790176016, 7045.647717614099, 7062.92937070633, 7079.469039822034, 6526.314736952551, 6527.869442611216, 6572.671366431755, 6583.506237032242, 6652.201735791639, 6773.966130169318, 6819.874720671034, 6822.965885170543, 6915.515913886181, 6920.273371295942, 6783.796486105425, 6830.351116643911, 6846.418054465426, 6846.897296540456, 6864.519483636098, 6467.838304042437, 6556.0, 6557.573757705733, 6570.704318305592, 6610.4711623071, 5428.83713488596, 5472.50747432733, 5540.833774986596, 5541.806036788693, 5548.639338442988, 6528.4419991105915, 6633.943477521983, 6680.5991640501725, 6758.831029224309, 6807.21717002551, 6648.235452922976, 6651.235107962646, 6651.866962660816, 6663.800085997428, 6672.499562532732, 6534.803955881332, 6559.311272316529, 6583.267943803268, 6585.769498067736, 6696.430803381767, 6481.4814814815645, 6495.480361570969, 6498.0, 6534.444572211415, 6630.0, 6602.372774586406, 6654.733810647714, 6657.468782180029, 6729.528932974645, 6734.225564060196, 6401.807945761635, 6432.6140431572385, 6435.742570297136, 6505.121808555785, 6520.478361731145], "value": 5277.085032041875, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [868.9913100868989, 884.3941899798639, 895.7088946092522, 897.9685711000117, 898.4654130792178, 16085.75871361929, 16924.66150676986, 16957.0, 16968.660626787456, 18148.09259537021, 17887.821121788777, 17936.82063179368, 17955.371561995333, 17996.10019499023, 18005.279788808464, 919.1681528216974, 926.4811705444955, 934.8130373925209, 936.9109934556207, 936.9859452108224, 690.3890057299301, 703.5180901082749, 907.6732376344511, 922.912323329286, 929.60027188309, 986.8371718666411, 992.7567745902228, 992.7865508915581, 993.359283262299, 993.73169244304, 982.9017098290185, 987.7876256604865, 988.125508924607, 988.243993345088, 988.6144403682498, 725.7822653204071, 725.8838585826243, 726.0234983946583, 726.3426598927925, 727.3817255332978, 16880.07159606221, 17174.74237886433, 17259.30962761488, 17324.653506929917, 17394.30422783087, 20214.696779548172, 20326.695099573517, 20633.17467301306, 20803.68794468084, 20837.479063023547, 15586.922065389714, 15916.204189790464, 15950.282237299349, 16132.59668508285, 16141.596460088474, 3626.4016437287814, 3691.667749902495, 3691.8154092295276, 3706.944395834065, 3707.870224542155, 128805.0, 130414.34792825975, 130993.0, 131193.0, 133121.33439332838, 15872.365105395655, 15969.760453593206, 16052.438164664181, 16208.35166593323, 16275.0, 29066.0, 29076.709232907822, 29243.853780731384, 29284.0, 29318.853405732836, 7936.6031698414845, 7982.201779822088, 8038.718644847402, 8039.236272554215, 8048.356131509465, 4218.240716670967, 4319.157764236007, 4325.632321252729, 4338.739675619409, 4340.848070317524, 4546.318052292117, 5430.864228394243, 5470.863228419322, 5500.037493438632, 5514.531264842453, 1047.0681093826502, 1050.579768092769, 1070.7376692710336, 1073.2165519171049, 1075.8816530181673, 662.3575132121834, 681.7784220128478, 682.969266383014, 690.3372762148293, 697.3096634332077, 11216.719582010352, 11405.14461415397, 11425.0, 12000.1599888007, 12621.684457888627, 668.1247565688963, 675.4933799650262, 676.4960104722013, 676.6921050921819, 694.8645014222279, 4921.212605983025, 5091.363579552556, 5133.153029750124, 6067.817965461043, 6102.206713127353, 105742.0, 108534.45732771285, 109355.0, 111218.44390778156, 111905.44047279711, 95105.0, 96268.51865740627, 96669.51665241629, 97003.51498242463, 101758.47362289572, 88546.55726721454, 88628.11371886327, 90113.09886901047, 90393.0, 90919.0, 12640.74718505643, 12795.74408511806, 12846.421911014213, 12948.0, 12991.28547929835, 38957.41563876601, 39626.60373396229, 40292.79853600713, 40326.1934761309, 41745.1650966985, 49683.751581241864, 50186.0, 50296.497035029184, 51324.48675513197, 74667.62666186634, 19066.142023608692, 19215.807841921403, 19238.23047078157, 19306.0, 19624.116914738584, 14179.291035448392, 16378.508644740677, 19860.801391985897, 20160.79839201589, 21004.789952100284, 15806.9209653951, 17358.5660358487, 17671.38150164738, 18458.077096145407, 18756.0, 17301.394451194148, 17389.739153912447, 17396.565085872953, 17504.73742893883, 17536.12319384, 17476.03881786514, 17681.204345804726, 18159.0, 18331.45005649833, 18397.172127254573, 13112.278824664354, 13268.004899632373, 13283.00377471674, 13310.201387916755, 13367.465301387836, 12990.0, 13065.412056457671, 13200.537981170613, 13436.731265374834, 13954.162750235018], "value": 8809.844192216236, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1484.6999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 111.0, 106.0, 107.0, 106.0, 110.0, 108.0, 97.0, 108.0, 108.0], "value": 105.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 116.0, 147.0, 168.0, 118.0, 141.0, 138.0, 118.0, 165.0, 117.0], "value": 137.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 163.0, 164.0, 166.0, 163.0, 164.0, 163.0, 164.0, 165.0, 164.0], "value": 164.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 74.0, 73.0, 165.0, 75.0, 74.0, 74.0, 76.0, 152.0, 77.0], "value": 91.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 78.0, 128.0, 78.0, 79.0, 79.0, 138.0, 79.0, 80.0, 148.0], "value": 97.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 114.0, 123.0, 114.0, 114.0, 115.0, 117.0, 116.0, 117.0, 122.0], "value": 116.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 149.0, 147.0, 147.0, 147.0, 149.0, 149.0, 148.0, 151.0, 149.0], "value": 148.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 93.0, 94.0, 94.0, 94.0, 94.0, 94.0, 94.0, 94.0, 93.0], "value": 93.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 52.0, 51.0, 67.0, 53.0, 54.0, 52.0, 52.0, 52.0, 52.0], "value": 53.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [38.0, 38.0, 38.0, 38.0, 38.0, 39.0, 38.0, 39.0, 40.0, 39.0], "value": 38.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 75.0, 73.0, 73.0, 74.0, 74.0, 73.0, 73.0, 75.0, 73.0], "value": 73.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [129.0, 128.0, 131.0, 122.0, 124.0, 122.0, 119.0, 120.0, 116.0, 160.0], "value": 127.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 173.0, 170.0, 169.0, 172.0, 172.0, 179.0, 172.0, 182.0, 165.0], "value": 172.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 65.0, 67.0, 64.0, 64.0, 64.0, 65.0, 63.0, 63.0, 62.0], "value": 64.4, "unit": "score"}]}]} 18:38:12 INFO - Return code: 0 18:38:12 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:38:12 INFO - # TBPL SUCCESS # 18:38:12 INFO - Running post-action listener: _package_coverage_data 18:38:12 INFO - Running post-action listener: _resource_record_post_action 18:38:12 INFO - [mozharness: 2017-08-28 01:38:12.155601Z] Finished run-tests step (success) 18:38:12 INFO - Running post-run listener: _resource_record_post_run 18:38:12 INFO - Total resource usage - Wall time: 1190s; CPU: 13.0%; Read bytes: 12288; Write bytes: 434544640; Read time: 236; Write time: 275728 18:38:12 INFO - TinderboxPrint: CPU usage
13.1% 18:38:12 INFO - TinderboxPrint: I/O read bytes / time
12,288 / 236 18:38:12 INFO - TinderboxPrint: I/O write bytes / time
434,544,640 / 275,728 18:38:12 INFO - TinderboxPrint: CPU idle
8,243.6 (86.9%) 18:38:12 INFO - TinderboxPrint: CPU user
1,195.9 (12.6%) 18:38:12 INFO - TinderboxPrint: Swap in / out
0 / 0 18:38:12 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 4096; Write bytes: 105603072; Read time: 52; Write time: 63860 18:38:12 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:12 INFO - run-tests - Wall time: 1173s; CPU: 13.0%; Read bytes: 8192; Write bytes: 328536064; Read time: 184; Write time: 211840 18:38:12 INFO - Running post-run listener: _upload_blobber_files 18:38:12 INFO - Blob upload gear active. 18:38:12 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:38:12 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:12 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:12 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:13 INFO - (blobuploader) - INFO - Open directory for files ... 18:38:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 18:38:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:38:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:38:14 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 18:38:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:38:14 INFO - (blobuploader) - INFO - Done attempting. 18:38:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:38:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:38:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:38:21 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:38:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:38:21 INFO - (blobuploader) - INFO - Done attempting. 18:38:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 18:38:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:38:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:38:22 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 18:38:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:38:22 INFO - (blobuploader) - INFO - Done attempting. 18:38:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 18:38:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:38:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:38:23 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 18:38:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:38:23 INFO - (blobuploader) - INFO - Done attempting. 18:38:23 INFO - (blobuploader) - INFO - Iteration through files over. 18:38:23 INFO - Return code: 0 18:38:23 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:38:23 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:38:23 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9201a44a46616223385b5aaaf37367873cafee35779d139059216cdd560418c2ae60ad29139f685ba3212577b40f3150fac2a752b0b1ce40bb287a1871f72f04", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ae817989f26f5018166ac4d4ec55b9477a2fe406326f5cd74f1d70fceeddbbd3fe079365870e9dde0e9eadd79c6c8039fe9d6e349c703c6acc2fb455d1275217", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5b8b37afa8ba87ea4d7f0c59b52a50a7abac0cbb9a8e7cbf5757c5b9235f15a0dec4a497601275e92e48cd415bf1b00eac6a710500a84cdf0490a4b4b54a69dc", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/10fbbf7f5b3c1d6f5de1aa3dad399b6530a373c0ce4cf8fff3e6ae4718fffec1910b2a0b594a812a790d9335f8482547fe43722fef69634c06ca60c60c2deb5d"} 18:38:23 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:38:23 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:38:23 INFO - Contents: 18:38:23 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9201a44a46616223385b5aaaf37367873cafee35779d139059216cdd560418c2ae60ad29139f685ba3212577b40f3150fac2a752b0b1ce40bb287a1871f72f04", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ae817989f26f5018166ac4d4ec55b9477a2fe406326f5cd74f1d70fceeddbbd3fe079365870e9dde0e9eadd79c6c8039fe9d6e349c703c6acc2fb455d1275217", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5b8b37afa8ba87ea4d7f0c59b52a50a7abac0cbb9a8e7cbf5757c5b9235f15a0dec4a497601275e92e48cd415bf1b00eac6a710500a84cdf0490a4b4b54a69dc", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/10fbbf7f5b3c1d6f5de1aa3dad399b6530a373c0ce4cf8fff3e6ae4718fffec1910b2a0b594a812a790d9335f8482547fe43722fef69634c06ca60c60c2deb5d"} 18:38:23 INFO - Running post-run listener: copy_logs_to_upload_dir 18:38:23 INFO - Copying logs to upload dir... 18:38:23 INFO - mkdir: /builds/slave/test/build/upload/logs 18:38:23 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1256.771020 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 56 secs) (at 2017-08-27 18:38:23.196806) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-27 18:38:23.201018) ========= 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=9f7544b6a75ada7b6b576032000001a7-1503859159.723767-947771518 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/9201a44a46616223385b5aaaf37367873cafee35779d139059216cdd560418c2ae60ad29139f685ba3212577b40f3150fac2a752b0b1ce40bb287a1871f72f04", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ae817989f26f5018166ac4d4ec55b9477a2fe406326f5cd74f1d70fceeddbbd3fe079365870e9dde0e9eadd79c6c8039fe9d6e349c703c6acc2fb455d1275217", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5b8b37afa8ba87ea4d7f0c59b52a50a7abac0cbb9a8e7cbf5757c5b9235f15a0dec4a497601275e92e48cd415bf1b00eac6a710500a84cdf0490a4b4b54a69dc", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/10fbbf7f5b3c1d6f5de1aa3dad399b6530a373c0ce4cf8fff3e6ae4718fffec1910b2a0b594a812a790d9335f8482547fe43722fef69634c06ca60c60c2deb5d"} 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.011329 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/9201a44a46616223385b5aaaf37367873cafee35779d139059216cdd560418c2ae60ad29139f685ba3212577b40f3150fac2a752b0b1ce40bb287a1871f72f04", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ae817989f26f5018166ac4d4ec55b9477a2fe406326f5cd74f1d70fceeddbbd3fe079365870e9dde0e9eadd79c6c8039fe9d6e349c703c6acc2fb455d1275217", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5b8b37afa8ba87ea4d7f0c59b52a50a7abac0cbb9a8e7cbf5757c5b9235f15a0dec4a497601275e92e48cd415bf1b00eac6a710500a84cdf0490a4b4b54a69dc", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/10fbbf7f5b3c1d6f5de1aa3dad399b6530a373c0ce4cf8fff3e6ae4718fffec1910b2a0b594a812a790d9335f8482547fe43722fef69634c06ca60c60c2deb5d"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-27 18:38:23.230624) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-27 18:38:23.230948) ========= 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=9f7544b6a75ada7b6b576032000001a7-1503859159.723767-947771518 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004602 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-27 18:38:23.311616) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-27 18:38:23.314952) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-27 18:38:23.315242) ========= ========= Total master_lag: 0.26 =========