builder: mozilla-central_ubuntu64_hw_styloseq_test-dromaeojs-e10s slave: talos-linux64-ix-050 starttime: 1504151177.11 results: success (0) revision: 04b6be50a2526c7a26a63715f441c47e1aa1f9be ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-08-30 20:46:17.107079) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-08-30 20:46:17.107569) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-30 20:46:17.107840) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-30 20:46:17.124067) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-30 20:46:17.124455) ========= 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=ca6c2627acea03e666270f68000001e0-1504151176.325908-188269164 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-08-30 20:46:17-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 46.2M=0s 2017-08-30 20:46:17 (46.2 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.149823 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-30 20:46:17.292645) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-30 20:46:17.292989) ========= 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=ca6c2627acea03e666270f68000001e0-1504151176.325908-188269164 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019115 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-30 20:46:17.342995) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-30 20:46:17.346397) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 04b6be50a2526c7a26a63715f441c47e1aa1f9be --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 04b6be50a2526c7a26a63715f441c47e1aa1f9be --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=ca6c2627acea03e666270f68000001e0-1504151176.325908-188269164 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-08-30 20:46:17,573 truncating revision to first 12 chars 2017-08-30 20:46:17,573 Setting DEBUG logging. 2017-08-30 20:46:17,573 attempt 1/10 2017-08-30 20:46:17,574 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/04b6be50a252?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-08-30 20:46:17,847 unpacking tar archive at: mozilla-central-04b6be50a252/testing/mozharness/ program finished with exit code 0 elapsedTime=0.595890 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-30 20:46:17.956833) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-30 20:46:17.960187) ========= script_repo_revision: 04b6be50a2526c7a26a63715f441c47e1aa1f9be ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-30 20:46:17.960589) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-30 20:46:17.960845) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-30 20:46:17.972933) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 46 secs) (at 2017-08-30 20:46:17.973326) ========= /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=ca6c2627acea03e666270f68000001e0-1504151176.325908-188269164 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 20:46:18 INFO - MultiFileLogger online at 20170830 20:46:18 in /builds/slave/test 20:46:18 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 20:46:18 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 20:46:18 INFO - {'append_to_log': False, 20:46:18 INFO - 'base_work_dir': '/builds/slave/test', 20:46:18 INFO - 'blob_upload_branch': 'Firefox', 20:46:18 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 20:46:18 INFO - 'branch': 'Firefox', 20:46:18 INFO - 'buildbot_json_path': 'buildprops.json', 20:46:18 INFO - 'code_coverage': False, 20:46:18 INFO - 'config_files': ('talos/linux_config.py',), 20:46:18 INFO - 'default_actions': ('clobber', 20:46:18 INFO - 'read-buildbot-config', 20:46:18 INFO - 'download-and-extract', 20:46:18 INFO - 'populate-webroot', 20:46:18 INFO - 'create-virtualenv', 20:46:18 INFO - 'install', 20:46:18 INFO - 'setup-mitmproxy', 20:46:18 INFO - 'run-tests'), 20:46:18 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 20:46:18 INFO - 'disable_ccov_upload': False, 20:46:18 INFO - 'disable_stylo': False, 20:46:18 INFO - 'download_minidump_stackwalk': True, 20:46:18 INFO - 'download_symbols': 'ondemand', 20:46:18 INFO - 'enable_stylo': False, 20:46:18 INFO - 'enable_webrender': False, 20:46:18 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 20:46:18 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 20:46:18 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 20:46:18 INFO - 'gecko_profile': False, 20:46:18 INFO - 'gecko_profile_interval': 0, 20:46:18 INFO - 'installer_path': 'installer.exe', 20:46:18 INFO - 'log_level': 'info', 20:46:18 INFO - 'log_name': 'talos', 20:46:18 INFO - 'log_to_console': True, 20:46:18 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 20:46:18 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 20:46:18 INFO - 'opt_config_files': (), 20:46:18 INFO - 'pip_index': False, 20:46:18 INFO - 'suite': 'dromaeojs-e10s', 20:46:18 INFO - 'system_bits': '32', 20:46:18 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 20:46:18 INFO - 'title': 'talos-linux64-ix-050', 20:46:18 INFO - 'tooltool_cache': '/builds/tooltool_cache', 20:46:18 INFO - 'use_talos_json': True, 20:46:18 INFO - 'verify': 'False', 20:46:18 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 20:46:18 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 20:46:18 INFO - 'work_dir': 'build'} 20:46:18 INFO - [mozharness: 2017-08-31 03:46:18.349465Z] Running clobber step. 20:46:18 INFO - Running pre-action listener: _resource_record_pre_action 20:46:18 INFO - Running main action method: clobber 20:46:18 INFO - rmtree: /builds/slave/test/build 20:46:18 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 20:46:19 INFO - Running post-action listener: _resource_record_post_action 20:46:19 INFO - [mozharness: 2017-08-31 03:46:19.349721Z] Finished clobber step (success) 20:46:19 INFO - [mozharness: 2017-08-31 03:46:19.349819Z] Running read-buildbot-config step. 20:46:19 INFO - Running pre-action listener: _resource_record_pre_action 20:46:19 INFO - Running main action method: read_buildbot_config 20:46:19 INFO - Using buildbot properties: 20:46:19 INFO - { 20:46:19 INFO - "project": "", 20:46:19 INFO - "product": "firefox", 20:46:19 INFO - "who": "kwierso@gmail.com", 20:46:19 INFO - "installer_path": "public/build/target.tar.bz2", 20:46:19 INFO - "builddir": "mozilla-central_ubuntu64_hw_styloseq_test-dromaeojs-e10s", 20:46:19 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 20:46:19 INFO - "buildername": "Ubuntu HW 12.04 x64 stylo-sequential mozilla-central talos dromaeojs-e10s", 20:46:19 INFO - "stage_platform": "linux64-stylosequential", 20:46:19 INFO - "basedir": "/builds/slave/test", 20:46:19 INFO - "buildnumber": 51, 20:46:19 INFO - "platform": "ubuntu64_hw_styloseq", 20:46:19 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 20:46:19 INFO - "slavebuilddir": "test", 20:46:19 INFO - "taskId": "SUYz4CkHSuazoTHkBHSQQw", 20:46:19 INFO - "branch": "mozilla-central", 20:46:19 INFO - "script_repo_revision": "production", 20:46:19 INFO - "revision": "04b6be50a2526c7a26a63715f441c47e1aa1f9be", 20:46:19 INFO - "slavename": "talos-linux64-ix-050", 20:46:19 INFO - "repo_path": "mozilla-central" 20:46:19 INFO - } 20:46:19 INFO - Finding installer, test and symbols from parent task. 20:46:19 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/SUYz4CkHSuazoTHkBHSQQw'}, attempt #1 20:46:19 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/SUYz4CkHSuazoTHkBHSQQw'}, attempt #1 20:46:20 INFO - Task dependencies: Cu5bJiXdSKGYWl3gR-gpGg 20:46:20 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Cu5bJiXdSKGYWl3gR-gpGg'}, attempt #1 20:46:20 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/Cu5bJiXdSKGYWl3gR-gpGg/artifacts/public/build/target.tar.bz2 20:46:20 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/Cu5bJiXdSKGYWl3gR-gpGg/artifacts/public/build/target.test_packages.json 20:46:20 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/Cu5bJiXdSKGYWl3gR-gpGg/artifacts/public/build/target.crashreporter-symbols.zip 20:46:20 INFO - Running post-action listener: _resource_record_post_action 20:46:20 INFO - [mozharness: 2017-08-31 03:46:20.698414Z] Finished read-buildbot-config step (success) 20:46:20 INFO - [mozharness: 2017-08-31 03:46:20.698633Z] Running download-and-extract step. 20:46:20 INFO - Running pre-action listener: _resource_record_pre_action 20:46:20 INFO - Running main action method: download_and_extract 20:46:20 INFO - mkdir: /builds/slave/test/build/tests 20:46:20 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')]} 20:46:20 INFO - trying https://queue.taskcluster.net/v1/task/Cu5bJiXdSKGYWl3gR-gpGg/artifacts/public/build/target.test_packages.json 20:46:20 INFO - Downloading https://queue.taskcluster.net/v1/task/Cu5bJiXdSKGYWl3gR-gpGg/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 20:46:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Cu5bJiXdSKGYWl3gR-gpGg/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 20:46:21 INFO - Downloaded 989 bytes. 20:46:21 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 20:46:21 INFO - Using the following test package requirements: 20:46:21 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 20:46:21 INFO - u'common': [u'target.common.tests.zip'], 20:46:21 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 20:46:21 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 20:46:21 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 20:46:21 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 20:46:21 INFO - u'mozbase': [u'target.common.tests.zip'], 20:46:21 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 20:46:21 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 20:46:21 INFO - u'web-platform': [u'target.common.tests.zip', 20:46:21 INFO - u'target.web-platform.tests.tar.gz'], 20:46:21 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 20:46:21 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 20:46:21 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Cu5bJiXdSKGYWl3gR-gpGg/artifacts/public/build/target.common.tests.zip 20:46:21 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Cu5bJiXdSKGYWl3gR-gpGg/artifacts/public/build/target.common.tests.zip'}, attempt #1 20:46:21 INFO - Fetch https://queue.taskcluster.net/v1/task/Cu5bJiXdSKGYWl3gR-gpGg/artifacts/public/build/target.common.tests.zip into memory 20:46:22 INFO - Content-Length response header: 54950260 20:46:22 INFO - Bytes received: 54950260 20:46:33 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 20:46:33 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Cu5bJiXdSKGYWl3gR-gpGg/artifacts/public/build/target.common.tests.zip 20:46:33 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Cu5bJiXdSKGYWl3gR-gpGg/artifacts/public/build/target.common.tests.zip'}, attempt #1 20:46:33 INFO - Fetch https://queue.taskcluster.net/v1/task/Cu5bJiXdSKGYWl3gR-gpGg/artifacts/public/build/target.common.tests.zip into memory 20:46:34 INFO - Content-Length response header: 54950260 20:46:34 INFO - Bytes received: 54950260 20:46:46 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Cu5bJiXdSKGYWl3gR-gpGg/artifacts/public/build/target.talos.tests.zip 20:46:46 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Cu5bJiXdSKGYWl3gR-gpGg/artifacts/public/build/target.talos.tests.zip'}, attempt #1 20:46:46 INFO - Fetch https://queue.taskcluster.net/v1/task/Cu5bJiXdSKGYWl3gR-gpGg/artifacts/public/build/target.talos.tests.zip into memory 20:46:47 INFO - Content-Length response header: 14496650 20:46:47 INFO - Bytes received: 14496650 20:46:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:46:47 INFO - trying https://queue.taskcluster.net/v1/task/Cu5bJiXdSKGYWl3gR-gpGg/artifacts/public/build/target.tar.bz2 20:46:47 INFO - Downloading https://queue.taskcluster.net/v1/task/Cu5bJiXdSKGYWl3gR-gpGg/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 20:46:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Cu5bJiXdSKGYWl3gR-gpGg/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 20:46:48 INFO - Downloaded 62651819 bytes. 20:46:48 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Cu5bJiXdSKGYWl3gR-gpGg/artifacts/public/build/target.tar.bz2 20:46:48 INFO - mkdir: /builds/slave/test/properties 20:46:48 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 20:46:48 INFO - Writing to file /builds/slave/test/properties/build_url 20:46:48 INFO - Contents: 20:46:48 INFO - build_url:https://queue.taskcluster.net/v1/task/Cu5bJiXdSKGYWl3gR-gpGg/artifacts/public/build/target.tar.bz2 20:46:48 INFO - Running post-action listener: _resource_record_post_action 20:46:48 INFO - Running post-action listener: find_tests_for_verification 20:46:48 INFO - Running post-action listener: set_extra_try_arguments 20:46:48 INFO - [mozharness: 2017-08-31 03:46:48.890059Z] Finished download-and-extract step (success) 20:46:48 INFO - [mozharness: 2017-08-31 03:46:48.890154Z] Running populate-webroot step. 20:46:48 INFO - Running pre-action listener: _resource_record_pre_action 20:46:48 INFO - Running main action method: populate_webroot 20:46:48 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 20:46:48 INFO - 'chromez-stylo-e10s': {'talos_options': ['--enable-stylo'], 20:46:48 INFO - 'tests': ['tresize', 'tcanvasmark']}, 20:46:48 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 20:46:48 INFO - 'dromaeojs-stylo-e10s': {'talos_options': ['--enable-stylo'], 20:46:48 INFO - 'tests': ['dromaeo_css', 'kraken']}, 20:46:48 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 20:46:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:46:48 INFO - 'g1-stylo-e10s': {'pagesets_name': 'tp5n.zip', 20:46:48 INFO - 'talos_options': ['--enable-stylo'], 20:46:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:46:48 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 20:46:48 INFO - 'tests': ['damp', 'tps']}, 20:46:48 INFO - 'g2-stylo-e10s': {'pagesets_name': 'tp5n.zip', 20:46:48 INFO - 'talos_options': ['--enable-stylo'], 20:46:48 INFO - 'tests': ['damp', 'tps']}, 20:46:48 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 20:46:48 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 20:46:48 INFO - 'g4-stylo-e10s': {'talos_options': ['--enable-stylo'], 20:46:48 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 20:46:48 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 20:46:48 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 20:46:48 INFO - 'g5-stylo-e10s': {'pagesets_name': 'tp5n.zip', 20:46:48 INFO - 'talos_options': ['--enable-stylo'], 20:46:48 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 20:46:48 INFO - 'other-e10s': {'tests': ['a11yr', 20:46:48 INFO - 'ts_paint', 20:46:48 INFO - 'tpaint', 20:46:48 INFO - 'sessionrestore', 20:46:48 INFO - 'sessionrestore_many_windows', 20:46:48 INFO - 'sessionrestore_no_auto_restore', 20:46:48 INFO - 'tabpaint']}, 20:46:48 INFO - 'other-stylo-e10s': {'talos_options': ['--enable-stylo'], 20:46:48 INFO - 'tests': ['a11yr', 20:46:48 INFO - 'ts_paint', 20:46:48 INFO - 'tpaint', 20:46:48 INFO - 'sessionrestore', 20:46:48 INFO - 'sessionrestore_many_windows', 20:46:48 INFO - 'sessionrestore_no_auto_restore', 20:46:48 INFO - 'tabpaint']}, 20:46:48 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 20:46:48 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 20:46:48 INFO - 'perf-reftest-singletons-stylo-e10s': {'talos_options': ['--enable-stylo'], 20:46:48 INFO - 'tests': ['bloom_basic_singleton']}, 20:46:48 INFO - 'perf-reftest-stylo-e10s': {'talos_options': ['--enable-stylo'], 20:46:48 INFO - 'tests': ['bloom_basic']}, 20:46:48 INFO - 'svgr-e10s': {'tests': ['tsvgx', 20:46:48 INFO - 'tsvgr_opacity', 20:46:48 INFO - 'tart', 20:46:48 INFO - 'tscrollx', 20:46:48 INFO - 'tsvg_static']}, 20:46:48 INFO - 'svgr-stylo-e10s': {'talos_options': ['--enable-stylo'], 20:46:48 INFO - 'tests': ['tsvgx', 20:46:48 INFO - 'tsvgr_opacity', 20:46:48 INFO - 'tart', 20:46:48 INFO - 'tscrollx', 20:46:48 INFO - 'tsvg_static']}, 20:46:48 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 20:46:48 INFO - 'tp5o-stylo-e10s': {'pagesets_name': 'tp5n.zip', 20:46:48 INFO - 'talos_options': ['--enable-stylo'], 20:46:48 INFO - 'tests': ['tp5o']}, 20:46:48 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 20:46:48 INFO - 'talos_options': ['--mitmproxy', 20:46:48 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 20:46:48 INFO - '--firstNonBlankPaint'], 20:46:48 INFO - 'tests': ['tp6_google', 20:46:48 INFO - 'tp6_youtube', 20:46:48 INFO - 'tp6_amazon', 20:46:48 INFO - 'tp6_facebook']}, 20:46:48 INFO - 'tp6-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 20:46:48 INFO - 'talos_options': ['--enable-stylo', 20:46:48 INFO - '--mitmproxy', 20:46:48 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 20:46:48 INFO - '--firstNonBlankPaint'], 20:46:48 INFO - 'tests': ['tp6_google', 20:46:48 INFO - 'tp6_youtube', 20:46:48 INFO - 'tp6_amazon', 20:46:48 INFO - 'tp6_facebook']}, 20:46:48 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 20:46:48 INFO - 'talos_options': ['--enable-stylo', 20:46:48 INFO - '--stylo-threads=1', 20:46:48 INFO - '--mitmproxy', 20:46:48 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 20:46:48 INFO - '--firstNonBlankPaint'], 20:46:48 INFO - 'tests': ['tp6_google', 20:46:48 INFO - 'tp6_youtube', 20:46:48 INFO - 'tp6_amazon', 20:46:48 INFO - 'tp6_facebook']}, 20:46:48 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 20:46:48 INFO - 'talos_options': ['--xperf_path', 20:46:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:46:48 INFO - 'tests': ['tp5n']}, 20:46:48 INFO - 'xperf-stylo-e10s': {'pagesets_name': 'tp5n.zip', 20:46:48 INFO - 'talos_options': ['--enable-stylo', 20:46:48 INFO - '--xperf_path', 20:46:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:46:48 INFO - 'tests': ['tp5n']}}} 20:46:48 INFO - Running post-action listener: _resource_record_post_action 20:46:48 INFO - [mozharness: 2017-08-31 03:46:48.904414Z] Finished populate-webroot step (success) 20:46:48 INFO - [mozharness: 2017-08-31 03:46:48.904523Z] Running create-virtualenv step. 20:46:48 INFO - Running pre-action listener: _resource_record_pre_action 20:46:48 INFO - Running main action method: create_virtualenv 20:46:48 INFO - Creating virtualenv /builds/slave/test/build/venv 20:46:48 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 20:46:48 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 20:46:48 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 20:46:49 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 20:46:49 INFO - New python executable in /builds/slave/test/build/venv/bin/python 20:46:49 INFO - Installing distribute.............................................................................................................................................................................................done. 20:46:49 INFO - Installing pip...............done. 20:46:49 INFO - Return code: 0 20:46:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 20:46:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 20:46:49 INFO - Reading from file tmpfile_stdout 20:46:49 INFO - Output received: 20:46:49 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 20:46:49 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 20:46:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:46:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:46:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:46:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:46:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25461f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x270ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28b5b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28b2580>, '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 0x28b3480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28b3910>, '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': 'ca6c2627acea03e666270f68000001e0-1504151176.325908-188269164', '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 20:46:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 20:46:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 20:46:49 INFO - Using env: {'DISPLAY': ':0', 20:46:49 INFO - 'HOME': '/home/cltbld', 20:46:49 INFO - 'LANG': 'en_US.UTF-8', 20:46:49 INFO - 'LANGUAGE': 'en_US:en', 20:46:49 INFO - 'LOGNAME': 'cltbld', 20:46:49 INFO - 'MAIL': '/var/mail/cltbld', 20:46:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:46:49 INFO - 'MOZ_NO_REMOTE': '1', 20:46:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:46:49 INFO - 'NO_EM_RESTART': '1', 20:46:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:46:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:46:49 INFO - 'PWD': '/builds/slave/test', 20:46:49 INFO - 'SHELL': '/bin/bash', 20:46:49 INFO - 'SHLVL': '1', 20:46:49 INFO - 'TERM': 'linux', 20:46:49 INFO - 'TMOUT': '86400', 20:46:49 INFO - 'USER': 'cltbld', 20:46:49 INFO - 'XDG_SESSION_COOKIE': 'ca6c2627acea03e666270f68000001e0-1504151176.325908-188269164', 20:46:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:46:49 INFO - '_': '/tools/buildbot/bin/python'} 20:46:51 INFO - Ignoring indexes: http://pypi.python.org/simple/ 20:46:51 INFO - Downloading/unpacking pip>=1.5 20:46:51 INFO - Running setup.py egg_info for package pip 20:46:51 INFO - warning: no files found matching 'pip/cacert.pem' 20:46:51 INFO - warning: no files found matching '*.html' under directory 'docs' 20:46:51 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 20:46:51 INFO - no previously-included directories found matching 'docs/_build/_sources' 20:46:51 INFO - Installing collected packages: pip 20:46:51 INFO - Found existing installation: pip 1.1 20:46:51 INFO - Uninstalling pip: 20:46:51 INFO - Successfully uninstalled pip 20:46:51 INFO - Running setup.py install for pip 20:46:51 INFO - warning: no files found matching 'pip/cacert.pem' 20:46:51 INFO - warning: no files found matching '*.html' under directory 'docs' 20:46:51 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 20:46:51 INFO - no previously-included directories found matching 'docs/_build/_sources' 20:46:51 INFO - Installing pip script to /builds/slave/test/build/venv/bin 20:46:51 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 20:46:51 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 20:46:51 INFO - Successfully installed pip 20:46:51 INFO - Cleaning up... 20:46:51 INFO - Return code: 0 20:46:51 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 20:46:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:46:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:46:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:46:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:46:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x25461f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x270ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28b5b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28b2580>, '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 0x28b3480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28b3910>, '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': 'ca6c2627acea03e666270f68000001e0-1504151176.325908-188269164', '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 20:46:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 20:46:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 20:46:51 INFO - Using env: {'DISPLAY': ':0', 20:46:51 INFO - 'HOME': '/home/cltbld', 20:46:51 INFO - 'LANG': 'en_US.UTF-8', 20:46:51 INFO - 'LANGUAGE': 'en_US:en', 20:46:51 INFO - 'LOGNAME': 'cltbld', 20:46:51 INFO - 'MAIL': '/var/mail/cltbld', 20:46:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:46:51 INFO - 'MOZ_NO_REMOTE': '1', 20:46:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:46:51 INFO - 'NO_EM_RESTART': '1', 20:46:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:46:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:46:51 INFO - 'PWD': '/builds/slave/test', 20:46:51 INFO - 'SHELL': '/bin/bash', 20:46:51 INFO - 'SHLVL': '1', 20:46:51 INFO - 'TERM': 'linux', 20:46:51 INFO - 'TMOUT': '86400', 20:46:51 INFO - 'USER': 'cltbld', 20:46:51 INFO - 'XDG_SESSION_COOKIE': 'ca6c2627acea03e666270f68000001e0-1504151176.325908-188269164', 20:46:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:46:51 INFO - '_': '/tools/buildbot/bin/python'} 20:46:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:46:51 INFO - Downloading/unpacking psutil>=3.1.1 20:46:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:46:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:46:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:46:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:46:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 20:46:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:46:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:46:52 INFO - Installing collected packages: psutil 20:46:52 INFO - Running setup.py install for psutil 20:46:52 INFO - building 'psutil._psutil_linux' extension 20:46:52 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 20:46:52 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 20:46:52 INFO - building 'psutil._psutil_posix' extension 20:46:52 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 20:46:52 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 20:46:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:46:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:46:53 INFO - Successfully installed psutil 20:46:53 INFO - Cleaning up... 20:46:53 INFO - Return code: 0 20:46:53 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 20:46:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:46:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:46:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:46:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:46:53 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 0x25461f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x270ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28b5b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28b2580>, '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 0x28b3480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28b3910>, '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': 'ca6c2627acea03e666270f68000001e0-1504151176.325908-188269164', '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 20:46:53 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 20:46:53 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 20:46:53 INFO - Using env: {'DISPLAY': ':0', 20:46:53 INFO - 'HOME': '/home/cltbld', 20:46:53 INFO - 'LANG': 'en_US.UTF-8', 20:46:53 INFO - 'LANGUAGE': 'en_US:en', 20:46:53 INFO - 'LOGNAME': 'cltbld', 20:46:53 INFO - 'MAIL': '/var/mail/cltbld', 20:46:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:46:53 INFO - 'MOZ_NO_REMOTE': '1', 20:46:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:46:53 INFO - 'NO_EM_RESTART': '1', 20:46:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:46:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:46:53 INFO - 'PWD': '/builds/slave/test', 20:46:53 INFO - 'SHELL': '/bin/bash', 20:46:53 INFO - 'SHLVL': '1', 20:46:53 INFO - 'TERM': 'linux', 20:46:53 INFO - 'TMOUT': '86400', 20:46:53 INFO - 'USER': 'cltbld', 20:46:53 INFO - 'XDG_SESSION_COOKIE': 'ca6c2627acea03e666270f68000001e0-1504151176.325908-188269164', 20:46:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:46:53 INFO - '_': '/tools/buildbot/bin/python'} 20:46:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:46:53 INFO - Downloading/unpacking mozsystemmonitor==0.3 20:46:53 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 20:46:53 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 20:46:53 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 20:46:53 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 20:46:54 INFO - Downloading mozsystemmonitor-0.3.tar.gz 20:46:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 20:46:54 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 20:46:54 INFO - Installing collected packages: mozsystemmonitor 20:46:54 INFO - Running setup.py install for mozsystemmonitor 20:46:54 INFO - Successfully installed mozsystemmonitor 20:46:54 INFO - Cleaning up... 20:46:54 INFO - Return code: 0 20:46:54 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 20:46:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:46:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:46:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:46:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:46:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x25461f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x270ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28b5b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28b2580>, '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 0x28b3480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28b3910>, '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': 'ca6c2627acea03e666270f68000001e0-1504151176.325908-188269164', '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 20:46:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 20:46:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 20:46:54 INFO - Using env: {'DISPLAY': ':0', 20:46:54 INFO - 'HOME': '/home/cltbld', 20:46:54 INFO - 'LANG': 'en_US.UTF-8', 20:46:54 INFO - 'LANGUAGE': 'en_US:en', 20:46:54 INFO - 'LOGNAME': 'cltbld', 20:46:54 INFO - 'MAIL': '/var/mail/cltbld', 20:46:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:46:54 INFO - 'MOZ_NO_REMOTE': '1', 20:46:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:46:54 INFO - 'NO_EM_RESTART': '1', 20:46:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:46:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:46:54 INFO - 'PWD': '/builds/slave/test', 20:46:54 INFO - 'SHELL': '/bin/bash', 20:46:54 INFO - 'SHLVL': '1', 20:46:54 INFO - 'TERM': 'linux', 20:46:54 INFO - 'TMOUT': '86400', 20:46:54 INFO - 'USER': 'cltbld', 20:46:54 INFO - 'XDG_SESSION_COOKIE': 'ca6c2627acea03e666270f68000001e0-1504151176.325908-188269164', 20:46:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:46:54 INFO - '_': '/tools/buildbot/bin/python'} 20:46:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:46:54 INFO - Downloading/unpacking jsonschema==2.5.1 20:46:54 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 20:46:54 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 20:46:54 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 20:46:54 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 20:46:55 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 20:46:55 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 20:46:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:46:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:46:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:46:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:46:55 INFO - Downloading functools32-3.2.3-2.tar.gz 20:46:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 20:46:56 INFO - warning: no files found matching '*.txt' 20:46:56 INFO - no previously-included directories found matching 'build' 20:46:56 INFO - no previously-included directories found matching 'dist' 20:46:56 INFO - no previously-included directories found matching '.git*' 20:46:56 INFO - Installing collected packages: jsonschema, functools32 20:46:56 INFO - Running setup.py install for functools32 20:46:56 INFO - warning: no files found matching '*.txt' 20:46:56 INFO - no previously-included directories found matching 'build' 20:46:56 INFO - no previously-included directories found matching 'dist' 20:46:56 INFO - no previously-included directories found matching '.git*' 20:46:56 INFO - Successfully installed jsonschema functools32 20:46:56 INFO - Cleaning up... 20:46:56 INFO - Return code: 0 20:46:56 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 20:46:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:46:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:46:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:46:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:46:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25461f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x270ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28b5b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28b2580>, '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 0x28b3480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28b3910>, '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': 'ca6c2627acea03e666270f68000001e0-1504151176.325908-188269164', '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 20:46:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 20:46:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 20:46:56 INFO - Using env: {'DISPLAY': ':0', 20:46:56 INFO - 'HOME': '/home/cltbld', 20:46:56 INFO - 'LANG': 'en_US.UTF-8', 20:46:56 INFO - 'LANGUAGE': 'en_US:en', 20:46:56 INFO - 'LOGNAME': 'cltbld', 20:46:56 INFO - 'MAIL': '/var/mail/cltbld', 20:46:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:46:56 INFO - 'MOZ_NO_REMOTE': '1', 20:46:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:46:56 INFO - 'NO_EM_RESTART': '1', 20:46:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:46:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:46:56 INFO - 'PWD': '/builds/slave/test', 20:46:56 INFO - 'SHELL': '/bin/bash', 20:46:56 INFO - 'SHLVL': '1', 20:46:56 INFO - 'TERM': 'linux', 20:46:56 INFO - 'TMOUT': '86400', 20:46:56 INFO - 'USER': 'cltbld', 20:46:56 INFO - 'XDG_SESSION_COOKIE': 'ca6c2627acea03e666270f68000001e0-1504151176.325908-188269164', 20:46:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:46:56 INFO - '_': '/tools/buildbot/bin/python'} 20:46:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:46:56 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 20:46:56 INFO - Cleaning up... 20:46:56 INFO - Return code: 0 20:46:56 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 20:46:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:46:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:46:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:46:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:46:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25461f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x270ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28b5b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28b2580>, '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 0x28b3480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28b3910>, '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': 'ca6c2627acea03e666270f68000001e0-1504151176.325908-188269164', '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 20:46:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 20:46:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 20:46:56 INFO - Using env: {'DISPLAY': ':0', 20:46:56 INFO - 'HOME': '/home/cltbld', 20:46:56 INFO - 'LANG': 'en_US.UTF-8', 20:46:56 INFO - 'LANGUAGE': 'en_US:en', 20:46:56 INFO - 'LOGNAME': 'cltbld', 20:46:56 INFO - 'MAIL': '/var/mail/cltbld', 20:46:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:46:56 INFO - 'MOZ_NO_REMOTE': '1', 20:46:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:46:56 INFO - 'NO_EM_RESTART': '1', 20:46:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:46:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:46:56 INFO - 'PWD': '/builds/slave/test', 20:46:56 INFO - 'SHELL': '/bin/bash', 20:46:56 INFO - 'SHLVL': '1', 20:46:56 INFO - 'TERM': 'linux', 20:46:56 INFO - 'TMOUT': '86400', 20:46:56 INFO - 'USER': 'cltbld', 20:46:56 INFO - 'XDG_SESSION_COOKIE': 'ca6c2627acea03e666270f68000001e0-1504151176.325908-188269164', 20:46:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:46:56 INFO - '_': '/tools/buildbot/bin/python'} 20:46:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:46:56 INFO - Downloading/unpacking blobuploader==1.2.4 20:46:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:46:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:46:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:46:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:46:57 INFO - Downloading blobuploader-1.2.4.tar.gz 20:46:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 20:46:57 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 20:46:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:46:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:46:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:46:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:46:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 20:46:58 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 20:46:58 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 20:46:58 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 20:46:58 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 20:46:58 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 20:46:58 INFO - Downloading docopt-0.6.1.tar.gz 20:46:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 20:46:58 INFO - Installing collected packages: blobuploader, requests, docopt 20:46:58 INFO - Running setup.py install for blobuploader 20:46:58 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 20:46:58 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 20:46:58 INFO - Running setup.py install for requests 20:46:58 INFO - Running setup.py install for docopt 20:46:58 INFO - Successfully installed blobuploader requests docopt 20:46:58 INFO - Cleaning up... 20:46:58 INFO - Return code: 0 20:46:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:46:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:46:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:46:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:46:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:46:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25461f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x270ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28b5b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28b2580>, '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 0x28b3480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28b3910>, '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': 'ca6c2627acea03e666270f68000001e0-1504151176.325908-188269164', '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 20:46:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 20:46:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 20:46:58 INFO - Using env: {'DISPLAY': ':0', 20:46:58 INFO - 'HOME': '/home/cltbld', 20:46:58 INFO - 'LANG': 'en_US.UTF-8', 20:46:58 INFO - 'LANGUAGE': 'en_US:en', 20:46:58 INFO - 'LOGNAME': 'cltbld', 20:46:58 INFO - 'MAIL': '/var/mail/cltbld', 20:46:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:46:58 INFO - 'MOZ_NO_REMOTE': '1', 20:46:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:46:58 INFO - 'NO_EM_RESTART': '1', 20:46:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:46:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:46:58 INFO - 'PWD': '/builds/slave/test', 20:46:58 INFO - 'SHELL': '/bin/bash', 20:46:58 INFO - 'SHLVL': '1', 20:46:58 INFO - 'TERM': 'linux', 20:46:58 INFO - 'TMOUT': '86400', 20:46:58 INFO - 'USER': 'cltbld', 20:46:58 INFO - 'XDG_SESSION_COOKIE': 'ca6c2627acea03e666270f68000001e0-1504151176.325908-188269164', 20:46:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:46:58 INFO - '_': '/tools/buildbot/bin/python'} 20:46:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:46:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:46:59 INFO - Running setup.py (path:/tmp/pip-3KDkJB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:46:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:46:59 INFO - Running setup.py (path:/tmp/pip-Gca3L8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:46:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:46:59 INFO - Running setup.py (path:/tmp/pip-ivfttj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:46:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:46:59 INFO - Running setup.py (path:/tmp/pip-qUgJzt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:46:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:46:59 INFO - Running setup.py (path:/tmp/pip-c3A51e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:46:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:46:59 INFO - Running setup.py (path:/tmp/pip-CM0RmF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:46:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:46:59 INFO - Running setup.py (path:/tmp/pip-chSNyk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:46:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:46:59 INFO - Running setup.py (path:/tmp/pip-fGqdWa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:46:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:46:59 INFO - Running setup.py (path:/tmp/pip-HoW12I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:46:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:46:59 INFO - Running setup.py (path:/tmp/pip-2lLNW9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:46:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:46:59 INFO - Running setup.py (path:/tmp/pip-Zxyipx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:46:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:46:59 INFO - Running setup.py (path:/tmp/pip-0J3i78-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:46:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:46:59 INFO - Running setup.py (path:/tmp/pip-DKl9bF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:47:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:47:00 INFO - Running setup.py (path:/tmp/pip-6WrrxA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:47:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:47:00 INFO - Running setup.py (path:/tmp/pip-SjOmt7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:47:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:47:00 INFO - Running setup.py (path:/tmp/pip-fbez5d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:47:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:47:00 INFO - Running setup.py (path:/tmp/pip-xXypwk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:47:00 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 20:47:00 INFO - Running setup.py install for manifestparser 20:47:00 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 20:47:00 INFO - Running setup.py install for mozcrash 20:47:00 INFO - Running setup.py install for mozdebug 20:47:00 INFO - Running setup.py install for mozdevice 20:47:00 INFO - Installing dm script to /builds/slave/test/build/venv/bin 20:47:00 INFO - Running setup.py install for mozfile 20:47:00 INFO - Running setup.py install for mozhttpd 20:47:00 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 20:47:00 INFO - Running setup.py install for mozinfo 20:47:01 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 20:47:01 INFO - Running setup.py install for mozInstall 20:47:01 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 20:47:01 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 20:47:01 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 20:47:01 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 20:47:01 INFO - Running setup.py install for mozleak 20:47:01 INFO - Running setup.py install for mozlog 20:47:01 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 20:47:01 INFO - Running setup.py install for moznetwork 20:47:01 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 20:47:01 INFO - Running setup.py install for mozprocess 20:47:01 INFO - Running setup.py install for mozprofile 20:47:01 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 20:47:01 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 20:47:01 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 20:47:01 INFO - Running setup.py install for mozrunner 20:47:01 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 20:47:01 INFO - Running setup.py install for mozscreenshot 20:47:01 INFO - Running setup.py install for moztest 20:47:01 INFO - Running setup.py install for mozversion 20:47:02 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 20:47:02 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 20:47:02 INFO - Cleaning up... 20:47:02 INFO - Return code: 0 20:47:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:47:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:47:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:47:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:47:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:47:02 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 0x25461f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x270ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28b5b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28b2580>, '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 0x28b3480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28b3910>, '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': 'ca6c2627acea03e666270f68000001e0-1504151176.325908-188269164', '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 20:47:02 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 20:47:02 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 20:47:02 INFO - Using env: {'DISPLAY': ':0', 20:47:02 INFO - 'HOME': '/home/cltbld', 20:47:02 INFO - 'LANG': 'en_US.UTF-8', 20:47:02 INFO - 'LANGUAGE': 'en_US:en', 20:47:02 INFO - 'LOGNAME': 'cltbld', 20:47:02 INFO - 'MAIL': '/var/mail/cltbld', 20:47:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:47:02 INFO - 'MOZ_NO_REMOTE': '1', 20:47:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:47:02 INFO - 'NO_EM_RESTART': '1', 20:47:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:47:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:47:02 INFO - 'PWD': '/builds/slave/test', 20:47:02 INFO - 'SHELL': '/bin/bash', 20:47:02 INFO - 'SHLVL': '1', 20:47:02 INFO - 'TERM': 'linux', 20:47:02 INFO - 'TMOUT': '86400', 20:47:02 INFO - 'USER': 'cltbld', 20:47:02 INFO - 'XDG_SESSION_COOKIE': 'ca6c2627acea03e666270f68000001e0-1504151176.325908-188269164', 20:47:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:47:02 INFO - '_': '/tools/buildbot/bin/python'} 20:47:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:47:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:47:02 INFO - Running setup.py (path:/tmp/pip-IJuzaL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:47:02 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 20:47:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:47:02 INFO - Running setup.py (path:/tmp/pip-jv5Lb_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:47:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 20:47:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:47:02 INFO - Running setup.py (path:/tmp/pip-1JAzcC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:47:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 20:47:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:47:02 INFO - Running setup.py (path:/tmp/pip-UJHa3z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:47:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 20:47:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:47:02 INFO - Running setup.py (path:/tmp/pip-SzKvjf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:47:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 20:47:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:47:02 INFO - Running setup.py (path:/tmp/pip-H2Pmsg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:47:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 20:47:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:47:02 INFO - Running setup.py (path:/tmp/pip-c3b8el-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:47:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 20:47:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:47:02 INFO - Running setup.py (path:/tmp/pip-Lf0IlA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:47:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.13 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 20:47:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:47:02 INFO - Running setup.py (path:/tmp/pip-V9SK9N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:47:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 20:47:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:47:02 INFO - Running setup.py (path:/tmp/pip-pCFspP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:47:03 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)) 20:47:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:47:03 INFO - Running setup.py (path:/tmp/pip-tNM6nu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:47:03 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)) 20:47:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:47:03 INFO - Running setup.py (path:/tmp/pip-fZDbXf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:47:03 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)) 20:47:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:47:03 INFO - Running setup.py (path:/tmp/pip-2cokmh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:47:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 20:47:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:47:03 INFO - Running setup.py (path:/tmp/pip-Neb4vY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:47:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 20:47:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:47:03 INFO - Running setup.py (path:/tmp/pip-p4mQBi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:47:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 20:47:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:47:03 INFO - Running setup.py (path:/tmp/pip-sKRlv8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:47:03 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 20:47:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:47:03 INFO - Running setup.py (path:/tmp/pip-vfmz2O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:47:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 20:47:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 20:47:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 20:47:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 20:47:03 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 20:47:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 20:47:03 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.13->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 20:47:03 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 20:47:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:47:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:47:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:47:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:47:04 INFO - Downloading blessings-1.6.tar.gz 20:47:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 20:47:04 INFO - Installing collected packages: blessings 20:47:04 INFO - Running setup.py install for blessings 20:47:04 INFO - Successfully installed blessings 20:47:04 INFO - Cleaning up... 20:47:04 INFO - Return code: 0 20:47:04 INFO - Done creating virtualenv /builds/slave/test/build/venv. 20:47:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:47:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:47:05 INFO - Reading from file tmpfile_stdout 20:47:05 INFO - Current package versions: 20:47:05 INFO - argparse == 1.2.1 20:47:05 INFO - blessings == 1.6 20:47:05 INFO - blobuploader == 1.2.4 20:47:05 INFO - docopt == 0.6.1 20:47:05 INFO - functools32 == 3.2.3-2 20:47:05 INFO - jsonschema == 2.5.1 20:47:05 INFO - manifestparser == 1.1 20:47:05 INFO - mozInstall == 1.13 20:47:05 INFO - mozcrash == 1.0 20:47:05 INFO - mozdebug == 0.1 20:47:05 INFO - mozdevice == 0.51 20:47:05 INFO - mozfile == 1.2 20:47:05 INFO - mozhttpd == 0.7 20:47:05 INFO - mozinfo == 0.10 20:47:05 INFO - mozleak == 0.1 20:47:05 INFO - mozlog == 3.5 20:47:05 INFO - moznetwork == 0.27 20:47:05 INFO - mozprocess == 0.25 20:47:05 INFO - mozprofile == 0.28 20:47:05 INFO - mozrunner == 6.13 20:47:05 INFO - mozscreenshot == 0.1 20:47:05 INFO - mozsystemmonitor == 0.3 20:47:05 INFO - moztest == 0.8 20:47:05 INFO - mozversion == 1.4 20:47:05 INFO - psutil == 3.1.1 20:47:05 INFO - requests == 1.2.3 20:47:05 INFO - wsgiref == 0.1.2 20:47:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:47:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:47:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:47:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:47:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:47:05 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 0x25461f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x270ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28b5b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28b2580>, '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 0x28b3480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28b3910>, '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': 'ca6c2627acea03e666270f68000001e0-1504151176.325908-188269164', '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 20:47:05 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 20:47:05 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 20:47:05 INFO - Using env: {'DISPLAY': ':0', 20:47:05 INFO - 'HOME': '/home/cltbld', 20:47:05 INFO - 'LANG': 'en_US.UTF-8', 20:47:05 INFO - 'LANGUAGE': 'en_US:en', 20:47:05 INFO - 'LOGNAME': 'cltbld', 20:47:05 INFO - 'MAIL': '/var/mail/cltbld', 20:47:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:47:05 INFO - 'MOZ_NO_REMOTE': '1', 20:47:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:47:05 INFO - 'NO_EM_RESTART': '1', 20:47:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:47:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:47:05 INFO - 'PWD': '/builds/slave/test', 20:47:05 INFO - 'SHELL': '/bin/bash', 20:47:05 INFO - 'SHLVL': '1', 20:47:05 INFO - 'TERM': 'linux', 20:47:05 INFO - 'TMOUT': '86400', 20:47:05 INFO - 'USER': 'cltbld', 20:47:05 INFO - 'XDG_SESSION_COOKIE': 'ca6c2627acea03e666270f68000001e0-1504151176.325908-188269164', 20:47:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:47:05 INFO - '_': '/tools/buildbot/bin/python'} 20:47:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:47:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 20:47:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 20:47:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 20:47:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 20:47:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 20:47:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 20:47:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 20:47:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 20:47:05 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 20:47:05 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 20:47:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:47:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:47:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:47:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:47:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 20:47:06 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 20:47:06 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 20:47:06 INFO - Installing collected packages: simplejson 20:47:06 INFO - Running setup.py install for simplejson 20:47:06 INFO - building 'simplejson._speedups' extension 20:47:06 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c simplejson/_speedups.c -o build/temp.linux-x86_64-2.7/simplejson/_speedups.o 20:47:07 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/simplejson/_speedups.o -o build/lib.linux-x86_64-2.7/simplejson/_speedups.so 20:47:07 INFO - Successfully installed simplejson 20:47:07 INFO - Cleaning up... 20:47:07 INFO - Return code: 0 20:47:07 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 20:47:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:47:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:47:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:47:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:47:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25461f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x270ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28b5b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28b2580>, '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 0x28b3480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28b3910>, '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': 'ca6c2627acea03e666270f68000001e0-1504151176.325908-188269164', '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 20:47:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 20:47:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 20:47:07 INFO - Using env: {'DISPLAY': ':0', 20:47:07 INFO - 'HOME': '/home/cltbld', 20:47:07 INFO - 'LANG': 'en_US.UTF-8', 20:47:07 INFO - 'LANGUAGE': 'en_US:en', 20:47:07 INFO - 'LOGNAME': 'cltbld', 20:47:07 INFO - 'MAIL': '/var/mail/cltbld', 20:47:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:47:07 INFO - 'MOZ_NO_REMOTE': '1', 20:47:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:47:07 INFO - 'NO_EM_RESTART': '1', 20:47:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:47:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:47:07 INFO - 'PWD': '/builds/slave/test', 20:47:07 INFO - 'SHELL': '/bin/bash', 20:47:07 INFO - 'SHLVL': '1', 20:47:07 INFO - 'TERM': 'linux', 20:47:07 INFO - 'TMOUT': '86400', 20:47:07 INFO - 'USER': 'cltbld', 20:47:07 INFO - 'XDG_SESSION_COOKIE': 'ca6c2627acea03e666270f68000001e0-1504151176.325908-188269164', 20:47:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:47:07 INFO - '_': '/tools/buildbot/bin/python'} 20:47:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:47:07 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 20:47:07 INFO - Cleaning up... 20:47:07 INFO - Return code: 0 20:47:07 INFO - Running post-action listener: _resource_record_post_action 20:47:07 INFO - Running post-action listener: _start_resource_monitoring 20:47:07 INFO - Starting resource monitoring. 20:47:07 INFO - [mozharness: 2017-08-31 03:47:07.761001Z] Finished create-virtualenv step (success) 20:47:07 INFO - [mozharness: 2017-08-31 03:47:07.761563Z] Running install step. 20:47:07 INFO - Running pre-action listener: _resource_record_pre_action 20:47:07 INFO - Running main action method: install 20:47:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:47:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:47:07 INFO - Reading from file tmpfile_stdout 20:47:07 INFO - Detecting whether we're running mozinstall >=1.0... 20:47:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 20:47:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 20:47:08 INFO - Reading from file tmpfile_stdout 20:47:08 INFO - Output received: 20:47:08 INFO - Usage: mozinstall [options] installer 20:47:08 INFO - Options: 20:47:08 INFO - -h, --help show this help message and exit 20:47:08 INFO - -d DEST, --destination=DEST 20:47:08 INFO - Directory to install application into. [default: 20:47:08 INFO - "/builds/slave/test"] 20:47:08 INFO - --app=APP Application being installed. [default: firefox] 20:47:08 INFO - mkdir: /builds/slave/test/build/application 20:47:08 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'] 20:47:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 20:47:24 INFO - Reading from file tmpfile_stdout 20:47:24 INFO - Output received: 20:47:24 INFO - /builds/slave/test/build/application/firefox/firefox 20:47:24 INFO - Running post-action listener: _resource_record_post_action 20:47:24 INFO - [mozharness: 2017-08-31 03:47:24.588053Z] Finished install step (success) 20:47:24 INFO - [mozharness: 2017-08-31 03:47:24.588201Z] Running setup-mitmproxy step. 20:47:24 INFO - Running pre-action listener: _resource_record_pre_action 20:47:24 INFO - Running main action method: setup_mitmproxy 20:47:24 INFO - Skipping: mitmproxy is not required 20:47:24 INFO - Running post-action listener: _resource_record_post_action 20:47:24 INFO - [mozharness: 2017-08-31 03:47:24.589102Z] Finished setup-mitmproxy step (success) 20:47:24 INFO - [mozharness: 2017-08-31 03:47:24.589324Z] Running run-tests step. 20:47:24 INFO - Running pre-action listener: _resource_record_pre_action 20:47:24 INFO - Running pre-action listener: _set_gcov_prefix 20:47:24 INFO - Running main action method: run_tests 20:47:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 20:47:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 20:47:24 INFO - Python 2.7.3 20:47:24 INFO - Return code: 0 20:47:24 INFO - grabbing minidump binary from tooltool 20:47:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:47:24 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28b2580>, '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 0x28b3480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28b3910>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 20:47:24 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 20:47:24 INFO - Copy/paste: /tools/tooltool.py --url https://tooltool.mozilla-releng.net/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 20:47:24 INFO - Calling ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 20:47:24 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 20:47:24 INFO - Return code: 0 20:47:24 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 20:47:24 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 20:47:24 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 20:47:24 INFO - ENV: RUST_BACKTRACE is now full 20:47:24 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 20:47:24 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 20:47:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Cu5bJiXdSKGYWl3gR-gpGg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-050', '--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 20:47:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Cu5bJiXdSKGYWl3gR-gpGg/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-050 --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 20:47:24 INFO - Using env: {'DISPLAY': ':0', 20:47:24 INFO - 'HOME': '/home/cltbld', 20:47:24 INFO - 'LANG': 'en_US.UTF-8', 20:47:24 INFO - 'LANGUAGE': 'en_US:en', 20:47:24 INFO - 'LOGNAME': 'cltbld', 20:47:24 INFO - 'MAIL': '/var/mail/cltbld', 20:47:24 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 20:47:24 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 20:47:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:47:24 INFO - 'MOZ_NO_REMOTE': '1', 20:47:24 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 20:47:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:47:24 INFO - 'NO_EM_RESTART': '1', 20:47:24 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:47:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:47:24 INFO - 'PWD': '/builds/slave/test', 20:47:24 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 20:47:24 INFO - 'RUST_BACKTRACE': 'full', 20:47:24 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 20:47:24 INFO - 'SHELL': '/bin/bash', 20:47:24 INFO - 'SHLVL': '1', 20:47:24 INFO - 'STYLO_FORCE_ENABLED': '1', 20:47:24 INFO - 'STYLO_THREADS': '1', 20:47:24 INFO - 'TERM': 'linux', 20:47:24 INFO - 'TMOUT': '86400', 20:47:24 INFO - 'USER': 'cltbld', 20:47:24 INFO - 'XDG_SESSION_COOKIE': 'ca6c2627acea03e666270f68000001e0-1504151176.325908-188269164', 20:47:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:47:24 INFO - '_': '/tools/buildbot/bin/python'} 20:47:24 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Cu5bJiXdSKGYWl3gR-gpGg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-050', '--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 20:47:24 INFO - mozversion application_buildid: 20170831025255 20:47:24 INFO - mozversion application_changeset: 04b6be50a2526c7a26a63715f441c47e1aa1f9be 20:47:24 INFO - mozversion application_display_name: Nightly 20:47:24 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 20:47:24 INFO - mozversion application_name: Firefox 20:47:24 INFO - mozversion application_remotingname: firefox 20:47:24 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 20:47:24 INFO - mozversion application_vendor: Mozilla 20:47:24 INFO - mozversion application_version: 57.0a1 20:47:24 INFO - mozversion platform_buildid: 20170831025255 20:47:24 INFO - mozversion platform_changeset: 04b6be50a2526c7a26a63715f441c47e1aa1f9be 20:47:24 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 20:47:24 INFO - mozversion platform_version: 57.0a1 20:47:24 INFO - using testdate: 1504151244 20:47:24 INFO - actual date: 1504151244 20:47:24 INFO - starting webserver on 'localhost:54553' 20:47:24 INFO - SUITE-START | Running 2 tests 20:47:24 INFO - TEST-START | dromaeo_css 20:47:24 INFO - Initialising browser for dromaeo_css test... 20:47:24 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:54553/getInfo.html -profile /tmp/tmpsZgLl6/profile 20:47:24 INFO - TEST-INFO | started process 12658 (/builds/slave/test/build/application/firefox/firefox http://localhost:54553/getInfo.html) 20:47:30 INFO - TEST-INFO | 12658: exit 0 20:47:30 INFO - Browser initialized. 20:47:30 INFO - Running cycle 1/1 for dromaeo_css test... 20:47:30 INFO - Using env: {'DISPLAY': ':0', 20:47:30 INFO - 'HOME': '/home/cltbld', 20:47:30 INFO - 'JSGC_DISABLE_POISONING': '1', 20:47:30 INFO - 'LANG': 'en_US.UTF-8', 20:47:30 INFO - 'LANGUAGE': 'en_US:en', 20:47:30 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 20:47:30 INFO - 'LOGNAME': 'cltbld', 20:47:30 INFO - 'MAIL': '/var/mail/cltbld', 20:47:30 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 20:47:30 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 20:47:30 INFO - 'MOZ_CRASHREPORTER': '1', 20:47:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:47:30 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 20:47:30 INFO - 'MOZ_NO_REMOTE': '1', 20:47:30 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 20:47:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:47:30 INFO - 'NO_EM_RESTART': '1', 20:47:30 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:47:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:47:30 INFO - 'PWD': '/builds/slave/test', 20:47:30 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 20:47:30 INFO - 'RUST_BACKTRACE': 'full', 20:47:30 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 20:47:30 INFO - 'SHELL': '/bin/bash', 20:47:30 INFO - 'SHLVL': '1', 20:47:30 INFO - 'STYLO_FORCE_ENABLED': '1', 20:47:30 INFO - 'STYLO_THREADS': '1', 20:47:30 INFO - 'TERM': 'linux', 20:47:30 INFO - 'TMOUT': '86400', 20:47:30 INFO - 'USER': 'cltbld', 20:47:30 INFO - 'XDG_SESSION_COOKIE': 'ca6c2627acea03e666270f68000001e0-1504151176.325908-188269164', 20:47:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:47:30 INFO - '_': '/tools/buildbot/bin/python'} 20:47:30 INFO - TEST-INFO | started process 12845 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpsZgLl6/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 20:47:31 INFO - PID 12845 | 20:47:31 INFO - PID 12845 | (/builds/slave/test/build/application/firefox/firefox:12896): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 20:47:31 INFO - PID 12845 | 20:47:41 INFO - PID 12845 | RSS: Main: 172122112 20:47:41 INFO - PID 12845 | 20:50:40 INFO - PID 12845 | Cycle 1(1): loaded http://localhost:54553/tests/dromaeo/cssquery-dojo.html (next: http://localhost:54553/tests/dromaeo/cssquery-ext.html) 20:50:41 INFO - PID 12845 | RSS: Main: 173850624 20:50:41 INFO - PID 12845 | 20:53:43 INFO - PID 12845 | Cycle 1(1): loaded http://localhost:54553/tests/dromaeo/cssquery-ext.html (next: http://localhost:54553/tests/dromaeo/cssquery-jquery.html) 20:53:43 INFO - PID 12845 | RSS: Main: 170655744 20:53:43 INFO - PID 12845 | 20:56:45 INFO - PID 12845 | Cycle 1(1): loaded http://localhost:54553/tests/dromaeo/cssquery-jquery.html (next: http://localhost:54553/tests/dromaeo/cssquery-mootools.html) 20:56:46 INFO - PID 12845 | RSS: Main: 172978176 20:56:46 INFO - PID 12845 | 20:59:48 INFO - PID 12845 | Cycle 1(1): loaded http://localhost:54553/tests/dromaeo/cssquery-mootools.html (next: http://localhost:54553/tests/dromaeo/cssquery-prototype.html) 20:59:48 INFO - PID 12845 | RSS: Main: 171220992 20:59:48 INFO - PID 12845 | 21:02:52 INFO - PID 12845 | Cycle 1(1): loaded http://localhost:54553/tests/dromaeo/cssquery-prototype.html (next: http://localhost:54553/tests/dromaeo/cssquery-yui.html) 21:02:52 INFO - PID 12845 | RSS: Main: 172929024 21:02:52 INFO - PID 12845 | 21:05:54 INFO - PID 12845 | Cycle 1(1): loaded http://localhost:54553/tests/dromaeo/cssquery-yui.html (next: http://localhost:54553/tests/dromaeo/cssquery-dojo.html) 21:05:54 INFO - PID 12845 | RSS: Main: 175362048 21:05:54 INFO - PID 12845 | 21:05:54 INFO - PID 12845 | __start_tp_report 21:05:54 INFO - PID 12845 | _x_x_mozilla_page_load 21:05:54 INFO - PID 12845 | _x_x_mozilla_page_load_details 21:05:54 INFO - PID 12845 | |i|pagename|runs| 21:05:54 INFO - PID 12845 | |0;dojo.html;3323.169207698074;3351.9162020949475;3355.2953879685274;3381.1378098584855;3391.525186473894;6273.5294852886045;6339.239291285041;6408.45528130109;6432.035194720788;6474.125992990945;6355.809325720235;6435.51733619978;6439.130717353156;6451.25810531789;6467.838304042401;5881.764729410818;5883.529317654577;5906.320773111082;5921.526277897779;5925.703714814264;5893.057110862263;5909.940900591004;5992.0712289595185;6024.09638554216;6027.7287522061615;5832.066869300913;5894.646321220719;5895.587308888379;5900.675462849542;5963.910541341883;5743.597948143632;5770.336411312711;5836.445537673914;5839.503642190419;5865.56008299375;5377.4084850666395;5799.9130013049835;5841.415858414167;5939.762409503614;5967.343592204854;6419.903701444482;6460.903086453707;6463.192100987377;6536.11762412073;6545.410913017851;6843;6916.723331066752;6923.273056329063;6950.652467376662;6963.0251764752975;6982.650867456607;7000.964995174992;7004.2645522220455;7008.194057683329;7010.719571217145;6370.649614271213;6399.424051835311;6421.678916054178;6479.416852483299;6483.513736469737;7080.398166155832;7133.607651579213;7176.102987126609;7221.638918054181;7229.34935855765;6777.796666100025;6805.829854253683;6826.488013399015;6826.522143449911;6834.214065382445;6781.491388145909;6856.314368563104;6959.8956015659805;6961.895571566431;6964.512484126164;6810.182778066664;6816.965915170393;6846.315368463114;6929.02993580889;6930.826729331807;6947.340002699735;6982;7002.544834585736;7012.193597736224;7013.964930175316;5442.129259318569;5443.183522471581;5507.7246137692955;5518.724063796794;5518.91721624176;4645.74448405341;4699.201135806921;4754.072955773591;4789.616830653539;4812.494688057774;5898.056310990221;6005.699715014319;6075.3924607539775;6080.118382834465;6081.331053584102;6981.406580440719;7047;7077.893831592531;7079.752208672775;7081;6003.6397816131175;6013.5189184865985;6053.727582258809;6079.209702738615;6119.5410344224365;5820.82537523875;5843;5924.289085309704;5942.61373010753;5956.970215148896;6697.866042679022;6717.395434410926;6728.966355168192;6773.8645227095185;6807.183138023469;6947.096877405905;6965.65171741421;6985.895211571728;7048.400885924754;7102.289771022856;6568.080468734476;6676.365745254193;6698.062271282122;6764.594124352555;6766.627835469097;6815.2162501311;6846.520743548103;6872;6908.758193463204;6928.099347084847;6919.238883722887;6920.4809639276245;6923.861522769415;7012.474064445289;7012.929870701431;6650.168728908887;6653.733850646114;6709.228438729413;6754.358335958076;6771.62756048422;6766.221884483348;6773.864522709419;6787.524873258824;6792.422644075309;6800.897986530107;6618.139641846722;6629.801105966829;6701.128853249046;6706.731730730716;6713.56361836479;6720.529562930548;6746.359095885883;6747.730090796314;6752.662366881538;6856.828579285558;6463.192100987377;6531.216254049545;6535.836604084936;6536.117624120873;6539.182602174728 21:05:54 INFO - PID 12845 | |1;ext.html;820.9179082091791;855.8031652719874;880.7974165941833;884.8141890203059;1107.6067995861465;29636.40727185455;39945.60054399455;40621.99999999996;40660.79669601655;40732.18535629286;41854;42220.57779422205;42316.36525452121;42337;42548.787256063675;7200.603966781825;7673.386129109672;7806.726764563213;7817.140114587391;7850.568218747967;7648.158702542716;7784.727534536292;7790.883136752953;7791.337736292394;7800.946872172241;7730.4975176613625;7781.727639532617;7806.180351063142;7809;7814.648340824648;7773.067231932176;7777.10563285221;7781.105172905131;7789.844203115963;7795.220477952216;7704.345130663844;7762.301392874669;7772.883406748903;7776.144624091346;7780.688772449095;37864.24271514582;38176.23647527062;38219;38237.23525529474;38263.61736382656;3571.62497937718;3609.025563097975;3610.62088480711;3613.837377318027;3614.9277014459826;31401.37197256065;31417;31432.52851207234;31506.684933150835;31521.684783152105;5253.0544501989625;5278.762455689503;5334.199870019489;5344.171653393731;5346.572274218053;194471;194574;195290;195330.02334988376;195802;47821.521784781704;47822.28266576004;47863;47918.28122578164;48084;49972.50027499678;53427.46572534225;53646;53704.46295537073;53782;15412.306446209948;15462.304196311194;15538;15545.611359716098;15562.144082075594;10473.738156546147;10609.363438193734;12279.324637144864;12459.314737689514;12558.623241302776;14367.281635918162;14425.567232983027;14508.419663213564;14524.419023238952;14650.560483185522;5535.474129957647;5626.634268772516;5677.517411020027;5801.129830525455;5833.533317334603;1010.1767059846202;1023.4013102335068;1036.23836480187;1037.1961729659574;1043.5304113149114;22474.98862551189;22793.772062279495;22979.310620681405;23090.845457727046;23445.41386465352;1105.8783533811275;1124.6794663520857;1128.041165009735;1129.3506233915532;1139.157023802397;12292.877071229173;12323;12329.876701233052;12343.444544995496;12423.006159507218;242125;243704;243763;244458;244478;51085;51213.74393128011;51224.48775512272;51282;51360.486395136315;1410.2314238739707;1438.8057612222217;1445.104035498019;1462.7215394969883;1475.2181343887758;21916.89041554782;22256.666150008088;22320;22468.988895499413;22890.65664015008;36839.63160368362;36997.63002369942;37018.8149059253;37058.629413705516;37063.81468092642;204247;205439;205844;206235;206902;34391.31217375588;35501.467477988364;37334.43998339973;37421.625783741816;37457;45092;45422.31866522071;45570;45700.08599828051;45887.77056114698;33925.66074339225;34060.31879362449;34123.65876341205;34328.28358582111;34360.140996474285;33206.16984575405;33269.50095748614;33286.33427331488;33825.323493530486;34152.31695366029;30060.24849378783;30170.547441787952;30463.695363046085;30619.54070688897;30907.536386953765;32856.83571582127;32899.835500822344;33015.8349208262;33619;33654;23349;24323;24490.510189795747;24612.507749844546;25125.497490049733 21:05:54 INFO - PID 12845 | |2;jquery.html;562.8030189433699;563.9210510528526;567.86371270895;574.7700919632149;581.9592628516004;21660.675089873646;22572.54854902901;22587.435314117156;22607.660885086716;23459.648105278393;25233.116840910527;25916.35209119773;25962.74037259627;25990;25992.350191245227;6682.665866706681;6798.762043328485;6828.453723702117;6832.111825462683;6839.384455399013;5914.615549989237;6465.644389558573;6505.577137486048;6649.933500665004;6678.76624318149;6430.228372595272;6431.035344698286;6431.485481161496;6450.258220304655;6458.095866578683;6430.0676401921455;6434.195725534309;6436.93563064368;6443.226812782496;6453.225612926455;5988.131720900445;5990.161377407167;5991.700414979234;5994.820155395345;5997.070454079581;27950.301242468897;28574.428511429862;28850.134495965154;29457.11628651144;29474.70525294741;1960.549073713054;2004.629143608437;2011.8893460859647;2012.7182194492636;2014.8287395571394;19104.713429298572;19377.128029238716;19763.308284209983;20385.286514971904;20396.184152633876;2390.151496218852;2404;2451.4974430241937;2451.901923923041;2459.7294297627245;135531;143825;152598;152664;152738;14363.749284795644;14546.927265363605;14826.184559849096;14936.925315373353;14983.400663973536;22791.772082279298;22857.771422285896;22895.198668046538;22909.656355154686;22970.65544016841;9605.423674579548;10005.399676019462;10035.79928401428;10102.444365559817;10184.541695623715;9250.72247832566;9254.629814807467;9321.161095501437;9368.672096476726;9442.622295108118;8907.955460222658;9130.726078217664;9207.309451791143;9273.814523709489;9515;2644.2451718559305;2660.3349162709324;2762.654668166479;2789.2190186747744;2936.471435141695;963.1572374172599;973.250597040275;980.4509474694194;982.8722266105503;993.3344659078434;19247.90376048111;19631.90184049099;19798.980451172596;19932.30236941729;19972.50068748264;1044.09685621938;1044.3472829481575;1045.0959919669583;1052.9789404211872;1062.176812969939;10893.291936024134;11115.166362522843;11121.610743623934;11136.610218642309;11237.269577477438;460865;461513;463835;486036;488441;115330.42334788272;117726;119052.40473797749;119090.40454797671;119746;1010.3887148275188;1061.9044286014296;1066.5360568152878;1118.1669656106253;1158.8551431071116;20931.37205883826;21081.89459052695;22177.77822221757;22304.330870073925;22402.439939001655;55793.12438112903;56932.14601780894;58205.126923097036;59444.02779861076;60647;64915.02627460498;65327;65568.34431655813;65628.67185664042;66128.33871661415;14626.488072917396;14757.409703612164;14768.33542490569;14825.629359266104;14903.85096149068;23077.65383519282;23184.768152318262;23263.883680582167;23336.533269334857;23350.299491015296;18168.182431790337;18287.81712182861;18311.450656480327;18546.907265464128;18621.162047708156;16550.586235344213;16576.917115414344;16916.407925722542;17044.914775426045;17285.91357043257;18846.3403780867;18899;19105.044747762277;19111.426657200307;19181.136848841557;13775.931120344334;14681.92659036698;14786.186759728318;14815.25923703832;14831.92584037073;17982;18085.63828723392;18136.72794908104;18171.000594967405;18281.725774113664 21:05:54 INFO - PID 12845 | |3;mootools.html;1328.3291937571528;1372.499037851184;1379.9448022079123;1388.5626027801247;1399.0975820595713;12400.441980110894;12973.15674481158;15259.465918692847;15799.526014219564;15848.920755396235;15281.770773438408;15538.300776465057;15558.53324400267;15558.922205388957;15608.297626606802;2685.5434576122107;2722.904698335554;2799.6220510231165;2804.214819850445;2804.9719502805015;2752.6559180102486;2855.8714857831446;2863.5418333066714;2866.211791757273;2866.5843452699346;2775.3339198592394;2795.776337892974;2797.818141820775;2815.7325054119824;2816.9718302816914;2663.081236973241;2664.733526647339;2676.4245687177254;2690.0450340129364;2691.125384250117;2597.545429549821;2604.231751633273;2614.3333449970137;2615.9346016349555;2616.672915885514;15030.398784048624;15078.773818392732;15156.772648410173;15165.924170379189;15293.923530382277;8068.919310806876;8373.874391884126;8377.078521362646;8390.160983901562;8404.873926891101;5362.4637536246455;5466.590005749545;5552.167174923753;5629.268195134607;5660.745266463019;1794.9461516154536;1795.111419847171;1830.4142674344216;1836.0085553800932;1841.3279153109152;26879.462410751676;27070.1878943632;27074.45851082987;27098.187054388203;27101.322466938484;6683.5989840608745;6783.18601767791;6833.897491537631;7024.894626580605;7025.262347453546;17317.7402338965;17323.826761732475;17342.653146937242;17353.65292694139;17358.13209339528;7786.610669466504;8058.194180582012;8066.15305392937;8073.515589064675;8081.757547273592;6263.123162757218;6489.837754056092;7156.713731450684;7526.435517336222;7531.246875312425;5309.256704061435;5373.059714549938;5459.426760190123;5461.317335333083;5503.614746967673;1536.9385224591115;1568.8666463350744;1582.8021497312836;1588.6822635472952;1601.8157911840287;912.703371404283;934.8036912248504;937.4796987671771;938.8357037518407;946.3328353510758;8432.662693492315;8606.65573377058;8668.046514883357;8719.346049046348;8763.079876612992;980.6812785844629;999.8950110238466;999.8950110238466;1007.1439276614965;1010.8332125199407;4797.56821886032;5720.542356611461;5859.882802343929;5876.6180198286975;5994.490468310242;22283.331500055025;22721.545569088856;22827.885860570925;22838.771612283996;22886.42783930415;21401.892990534947;22208.555828883655;22261.220857269916;22285.21718262535;22595.661065084038;11316.94341528287;11653.941730291293;11669.183157179086;11678.591249306233;11679.357635329969;8713.738587842376;8857.247133993682;8873.423227490193;9050.592723327598;9062.728118156467;18391.172397241888;18709.90645046766;18848.52878678044;19007.23971041198;19041.619167616293;19191.040447977826;19248.422547323604;19278.903605481883;19297.4210773677;19301.4209573713;10678.145748339959;10797.460126993461;10805.351678899291;10891.836622450828;10897.564097436014;14181.503647372292;14390.06464579799;14397.208153551655;14406.495772647908;14407.783883241551;10663.200259980378;10722.571097156026;10772.084372828398;10794.73013174677;10956.233063685466;10372.792544149226;10587.788244235226;10664.146868250677;10901.236913415985;10947.781044378908;9923.354981926152;10099.14157296638;10116.03897629724;10122.79754404893;10231.181505479693;7358.595277259802;7387.4090072795125;7475.36459400957;7484.738034168778;7524;7289.963550182215;7323.194448610755;7346.265373462611;7461.253874612496;7555.357794587521 21:05:54 INFO - PID 12845 | |4;prototype.html;512.4644746240183;518.300294602287;534.6076308519374;536.9731513424329;542.3193891665959;6309.085182648513;6336.859365314247;6378.425941665249;6392.7442902283965;6502.154719886414;6161.630302181861;6190.969045154779;6192.938070619292;6295.387414223103;6313.463355614778;5218.373795144588;5228.163493840986;5279.973600131994;5291.28567643369;5305.098133317326;4916.385451818523;5064;5237.45006774289;5301.840944771663;5308.867278318035;4991.700497970133;5033.936086204657;5044.520770526794;5072.239164125393;5090.200389785622;4973.886098408162;5006.1489546776775;5057.595392368602;5069.366329208849;5078.873028174288;4513.684042117053;4536.387587675655;4555.908881822378;4557.74932378719;4577.382053422779;6197.411245931629;6295.937040629581;6305.432511073979;6342.746290148389;6361.01404282337;5789.797357092523;5802.796902108448;5803.448672376117;5840.649561026352;5882.852928676774;6550.312217217172;6581.407673309377;6628.237752658458;6647.567908085959;6690.665466726644;4894.192458244385;4946.72792996385;4953.628477864175;4957.479464656195;4966.950330496685;6760.18877734675;6776.6611669416825;6911.308869113099;7007.194172670157;7113.928860711379;5057.241413787961;5058.494150584912;5065.11360511909;5093.286939828353;5096.61775366849;5107.080725469451;5121.4622464641425;5123.97438012815;5128.820371314619;5177.611679124081;5641.717914104278;5658.971705141448;5717.942820571824;5726.198332233491;5755.366909639936;6671.43292820106;6686.1642294713165;6702.932970670328;6719.462443004548;6775.593464392152;1774.5297496163528;1797.9550511237062;1799.5681036551396;1804.6374986884819;1808.095952023988;1173.600975668277;1216.7444836584416;1224.3633310678576;1232.3037483821613;1240.1443004326986;5831.387704290988;5841.357450680422;5873.58884878063;5912.970435147797;5931.139984702194;6748.257691653914;6845.973104034435;6865.862682746317;6865.970104484268;6873.931260687329;5860.150278209721;5876.236089308362;5894.233749612608;5896.705164741746;5909.4293982117915;3570.553680789901;3599.6220396858475;3606.8376923038527;3612.1511444810976;3645.1251699591908;6599.703013364314;6621.735130594722;6673.799786006428;6674.966625166843;6708.899366509506;6548.377904099103;6853.7943861684225;6907.132006076613;7018.368346848808;7030.894536581955;6492.597738836566;6522.673866306665;6556.672166391661;6569.770058047945;6589.604623722592;6738.8652226956165;6778.2205046420295;6806.591604503746;6810.182778066664;6854.657267136723;6682.431993280626;6714.321419645089;6790;6823.215330237086;6843.863122737617;6544.9672751635935;6568.474522038321;6571.310012448624;6610.438112760276;6642.20293564756;5027.617405213596;5052.9747351263;5060.443351231435;5067.265246539305;5075.873103172303;6555.2133743951035;6619.966900165468;6624.072629831924;6823.761168359084;6852.897206541807;6436.774712885027;6521.5108866834225;6528.376750903104;6571.737130514917;6623.337666233531;6543.672816359068;6545.672716364067;6607.636579988147;6615.503837212132;6734;6473.902891456726;6488.416042556192;6546.69066186755;6630.9668451657435;6631.767888123893;6646.368594983469;6652.334766523309;6723.4285085766305;6731.293214212633;6738.696758645775;6460.353964603502;6475.870482590416;6493.610383377012;6494.188226471691;6516.967415163083 21:05:54 INFO - PID 12845 | |5;yui.html;914.3599480363746;924.3575714878162;929.7071422501907;936.7470782888618;942.8444306689397;21413.464663383424;23552.29343119705;23590.112790976746;23622.64566031511;23625.173118940842;20888.791112088875;21268.4682882928;21378.89310553445;21397.251096211636;21449.67825482619;979.3732011512637;983.1348413396202;1000.1298869983088;1001.919846412289;1002.8495725641176;1017.7252141921676;1023.9846402303934;1024.3956065921104;1026.3021145620976;1027.691692492253;1064.7284942339693;1066.8666416697913;1067.3169171730099;1068.6206396729165;1069.4759567811755;1058.5395353021422;1059.6291298045621;1060.48566445274;1061.0980666433509;1062.267035745341;776.9223077692242;785.4894318692839;785.8821176823465;788.9605519723991;788.9763307100797;19012;19448.902755486273;19686.606267874562;19702.70445943312;20141.09365078575;21131.366059018255;21537.77269222171;21552.353429397142;21648.891755541277;21716.457088572755;16206;16924.915375423167;17264.395746148824;17420.91289543557;17434.564135896704;3833.137544052594;3872.012636777632;3875.786831724254;3876.5541962674088;3877.4959255296912;124373;127218.36390818079;128696.7130328694;128775;129962.3501882494;17359.39242126545;17508.562285942953;17545.56136096582;17614;17814.821851781315;23968.64047039296;30854;30947.226319342197;30978.53532197019;31544.211394715316;9044.502552359683;9182.816343673088;9272.490013049211;9374.390664606779;9422.293328000427;4461.531539188403;4554.863354099382;4564.0871825635;4573.6569757268335;4591.127685739699;5231.215317702375;5263.289455923478;5359.758810853521;5378.354597448331;5388.380336261302;1076.6500887211687;1082.3668154129764;1093.8960798724108;1097.3964319624174;1097.4622435006788;701.8350687588413;712.2201189697273;719.1909102259957;737.4616529933179;742.3504433620582;11597.6520704379;11895.405229738479;12275.202111862878;12507.562235321902;12847.931514991256;637.37537213531;687.8968154776824;701.7508784381471;703.7466512055656;706.0080586775589;5278.472152784768;5397.892042159135;5620.718964051863;5683.886322273532;6178.227721534809;107716;111238;111608;115430.4228478869;117835;92509;93518.53240733752;97309.5134524337;103213;103214.48392757989;83496;84938;86277;87082.69375959366;89897;13024.348782561023;13094.214347139203;13213.339333033118;13272.734545309233;13442.529511467052;37880.621193788815;38212.42681359726;39189.80405097956;39589.80205098956;40172.598274016884;50040;51063.489365105874;51307.97384052373;52167;52609.47390526199;18386.908065460124;18455.984920828945;18602.81397186011;18766.6246675063;18840.246390144242;19826.306079287155;20176.798232017492;20215.696764548837;20223.4944126398;20362.898185508977;17068;17104.144792760064;17168.65662686714;17176.82823171752;17277.39529116475;17320.65358692794;17374.044427556604;17461.650766984843;17489.212985415434;17526.211320490864;17687.646247075245;17807.109644517983;17936.641267174844;17972.640547189243;18020.909895450437;13170.60488185356;13187.340632968506;13252.403641835948;13264;13295.335233238493;14136.858631413554;14298.642533936734;14353.138811671333;14357.282135893372;14368.712625747636 21:05:54 INFO - PID 12845 | __end_tp_report 21:05:54 INFO - PID 12845 | __start_cc_report 21:05:54 INFO - PID 12845 | _x_x_mozilla_cycle_collect,355 21:05:54 INFO - PID 12845 | __end_cc_report 21:05:54 INFO - PID 12845 | __startTimestamp1504152354734__endTimestamp 21:05:54 INFO - PID 12845 | 21:05:54 INFO - PID 12845 | ------- Summary: start ------- 21:05:54 INFO - PID 12845 | Number of tests: 6 21:05:54 INFO - PID 12845 | 21:05:54 INFO - PID 12845 | [#0] dojo.html Cycles:165 Average:6367.20 Median:6629.80 stddev:762.28 (11.5%) stddev-sans-first:726.25 21:05:54 INFO - PID 12845 | Values: 3323.2 3351.9 3355.3 3381.1 3391.5 6273.5 6339.2 6408.5 6432.0 6474.1 6355.8 6435.5 6439.1 6451.3 6467.8 5881.8 5883.5 5906.3 5921.5 5925.7 5893.1 5909.9 5992.1 6024.1 6027.7 5832.1 5894.6 5895.6 5900.7 5963.9 5743.6 5770.3 5836.4 5839.5 5865.6 5377.4 5799.9 5841.4 5939.8 5967.3 6419.9 6460.9 6463.2 6536.1 6545.4 6843.0 6916.7 6923.3 6950.7 6963.0 6982.7 7001.0 7004.3 7008.2 7010.7 6370.6 6399.4 6421.7 6479.4 6483.5 7080.4 7133.6 7176.1 7221.6 7229.3 6777.8 6805.8 6826.5 6826.5 6834.2 6781.5 6856.3 6959.9 6961.9 6964.5 6810.2 6817.0 6846.3 6929.0 6930.8 6947.3 6982.0 7002.5 7012.2 7014.0 5442.1 5443.2 5507.7 5518.7 5518.9 4645.7 4699.2 4754.1 4789.6 4812.5 5898.1 6005.7 6075.4 6080.1 6081.3 6981.4 7047.0 7077.9 7079.8 7081.0 6003.6 6013.5 6053.7 6079.2 6119.5 5820.8 5843.0 5924.3 5942.6 5957.0 6697.9 6717.4 6729.0 6773.9 6807.2 6947.1 6965.7 6985.9 7048.4 7102.3 6568.1 6676.4 6698.1 6764.6 6766.6 6815.2 6846.5 6872.0 6908.8 6928.1 6919.2 6920.5 6923.9 7012.5 7012.9 6650.2 6653.7 6709.2 6754.4 6771.6 6766.2 6773.9 6787.5 6792.4 6800.9 6618.1 6629.8 6701.1 6706.7 6713.6 6720.5 6746.4 6747.7 6752.7 6856.8 6463.2 6531.2 6535.8 6536.1 6539.2 21:05:54 INFO - PID 12845 | 21:05:54 INFO - PID 12845 | [#1] ext.html Cycles:180 Average:38296.67 Median:34344.21 stddev:55996.86 (163.0%) stddev-sans-first:56083.25 21:05:54 INFO - PID 12845 | Values: 820.9 855.8 880.8 884.8 1107.6 29636.4 39945.6 40622.0 40660.8 40732.2 41854.0 42220.6 42316.4 42337.0 42548.8 7200.6 7673.4 7806.7 7817.1 7850.6 7648.2 7784.7 7790.9 7791.3 7800.9 7730.5 7781.7 7806.2 7809.0 7814.6 7773.1 7777.1 7781.1 7789.8 7795.2 7704.3 7762.3 7772.9 7776.1 7780.7 37864.2 38176.2 38219.0 38237.2 38263.6 3571.6 3609.0 3610.6 3613.8 3614.9 31401.4 31417.0 31432.5 31506.7 31521.7 5253.1 5278.8 5334.2 5344.2 5346.6 194471.0 194574.0 195290.0 195330.0 195802.0 47821.5 47822.3 47863.0 47918.3 48084.0 49972.5 53427.5 53646.0 53704.5 53782.0 15412.3 15462.3 15538.0 15545.6 15562.1 10473.7 10609.4 12279.3 12459.3 12558.6 14367.3 14425.6 14508.4 14524.4 14650.6 5535.5 5626.6 5677.5 5801.1 5833.5 1010.2 1023.4 1036.2 1037.2 1043.5 22475.0 22793.8 22979.3 23090.8 23445.4 1105.9 1124.7 1128.0 1129.4 1139.2 12292.9 12323.0 12329.9 12343.4 12423.0 242125.0 243704.0 243763.0 244458.0 244478.0 51085.0 51213.7 51224.5 51282.0 51360.5 1410.2 1438.8 1445.1 1462.7 1475.2 21916.9 22256.7 22320.0 22469.0 22890.7 36839.6 36997.6 37018.8 37058.6 37063.8 204247.0 205439.0 205844.0 206235.0 206902.0 34391.3 35501.5 37334.4 37421.6 37457.0 45092.0 45422.3 45570.0 45700.1 45887.8 33925.7 34060.3 34123.7 34328.3 34360.1 33206.2 33269.5 33286.3 33825.3 34152.3 30060.2 30170.5 30463.7 30619.5 30907.5 32856.8 32899.8 33015.8 33619.0 33654.0 23349.0 24323.0 24490.5 24612.5 25125.5 21:05:54 INFO - PID 12845 | 21:05:54 INFO - PID 12845 | [#2] jquery.html Cycles:180 Average:34592.15 Median:22902.43 stddev:80215.05 (350.2%) stddev-sans-first:80399.38 21:05:54 INFO - PID 12845 | Values: 562.8 563.9 567.9 574.8 582.0 21660.7 22572.5 22587.4 22607.7 23459.6 25233.1 25916.4 25962.7 25990.0 25992.4 6682.7 6798.8 6828.5 6832.1 6839.4 5914.6 6465.6 6505.6 6649.9 6678.8 6430.2 6431.0 6431.5 6450.3 6458.1 6430.1 6434.2 6436.9 6443.2 6453.2 5988.1 5990.2 5991.7 5994.8 5997.1 27950.3 28574.4 28850.1 29457.1 29474.7 1960.5 2004.6 2011.9 2012.7 2014.8 19104.7 19377.1 19763.3 20385.3 20396.2 2390.2 2404.0 2451.5 2451.9 2459.7 135531.0 143825.0 152598.0 152664.0 152738.0 14363.7 14546.9 14826.2 14936.9 14983.4 22791.8 22857.8 22895.2 22909.7 22970.7 9605.4 10005.4 10035.8 10102.4 10184.5 9250.7 9254.6 9321.2 9368.7 9442.6 8908.0 9130.7 9207.3 9273.8 9515.0 2644.2 2660.3 2762.7 2789.2 2936.5 963.2 973.3 980.5 982.9 993.3 19247.9 19631.9 19799.0 19932.3 19972.5 1044.1 1044.3 1045.1 1053.0 1062.2 10893.3 11115.2 11121.6 11136.6 11237.3 460865.0 461513.0 463835.0 486036.0 488441.0 115330.4 117726.0 119052.4 119090.4 119746.0 1010.4 1061.9 1066.5 1118.2 1158.9 20931.4 21081.9 22177.8 22304.3 22402.4 55793.1 56932.1 58205.1 59444.0 60647.0 64915.0 65327.0 65568.3 65628.7 66128.3 14626.5 14757.4 14768.3 14825.6 14903.9 23077.7 23184.8 23263.9 23336.5 23350.3 18168.2 18287.8 18311.5 18546.9 18621.2 16550.6 16576.9 16916.4 17044.9 17285.9 18846.3 18899.0 19105.0 19111.4 19181.1 13775.9 14681.9 14786.2 14815.3 14831.9 17982.0 18085.6 18136.7 18171.0 18281.7 21:05:54 INFO - PID 12845 | 21:05:54 INFO - PID 12845 | [#3] mootools.html Cycles:180 Average:9454.32 Median:2639.88 stddev:6789.37 (257.2%) stddev-sans-first:6780.97 21:05:54 INFO - PID 12845 | Values: 1328.3 1372.5 1379.9 1388.6 1399.1 12400.4 12973.2 15259.5 15799.5 15848.9 15281.8 15538.3 15558.5 15558.9 15608.3 2685.5 2722.9 2799.6 2804.2 2805.0 2752.7 2855.9 2863.5 2866.2 2866.6 2775.3 2795.8 2797.8 2815.7 2817.0 2663.1 2664.7 2676.4 2690.0 2691.1 2597.5 2604.2 2614.3 2615.9 2616.7 15030.4 15078.8 15156.8 15165.9 15293.9 8068.9 8373.9 8377.1 8390.2 8404.9 5362.5 5466.6 5552.2 5629.3 5660.7 1794.9 1795.1 1830.4 1836.0 1841.3 26879.5 27070.2 27074.5 27098.2 27101.3 6683.6 6783.2 6833.9 7024.9 7025.3 17317.7 17323.8 17342.7 17353.7 17358.1 7786.6 8058.2 8066.2 8073.5 8081.8 6263.1 6489.8 7156.7 7526.4 7531.2 5309.3 5373.1 5459.4 5461.3 5503.6 1536.9 1568.9 1582.8 1588.7 1601.8 912.7 934.8 937.5 938.8 946.3 8432.7 8606.7 8668.0 8719.3 8763.1 980.7 999.9 999.9 1007.1 1010.8 4797.6 5720.5 5859.9 5876.6 5994.5 22283.3 22721.5 22827.9 22838.8 22886.4 21401.9 22208.6 22261.2 22285.2 22595.7 11316.9 11653.9 11669.2 11678.6 11679.4 8713.7 8857.2 8873.4 9050.6 9062.7 18391.2 18709.9 18848.5 19007.2 19041.6 19191.0 19248.4 19278.9 19297.4 19301.4 10678.1 10797.5 10805.4 10891.8 10897.6 14181.5 14390.1 14397.2 14406.5 14407.8 10663.2 10722.6 10772.1 10794.7 10956.2 10372.8 10587.8 10664.1 10901.2 10947.8 9923.4 10099.1 10116.0 10122.8 10231.2 7358.6 7387.4 7475.4 7484.7 7524.0 7290.0 7323.2 7346.3 7461.3 7555.4 21:05:54 INFO - PID 12845 | 21:05:54 INFO - PID 12845 | [#4] prototype.html Cycles:180 Average:5597.77 Median:6300.68 stddev:1562.66 (24.8%) stddev-sans-first:1519.72 21:05:54 INFO - PID 12845 | Values: 512.5 518.3 534.6 537.0 542.3 6309.1 6336.9 6378.4 6392.7 6502.2 6161.6 6191.0 6192.9 6295.4 6313.5 5218.4 5228.2 5280.0 5291.3 5305.1 4916.4 5064.0 5237.5 5301.8 5308.9 4991.7 5033.9 5044.5 5072.2 5090.2 4973.9 5006.1 5057.6 5069.4 5078.9 4513.7 4536.4 4555.9 4557.7 4577.4 6197.4 6295.9 6305.4 6342.7 6361.0 5789.8 5802.8 5803.4 5840.6 5882.9 6550.3 6581.4 6628.2 6647.6 6690.7 4894.2 4946.7 4953.6 4957.5 4967.0 6760.2 6776.7 6911.3 7007.2 7113.9 5057.2 5058.5 5065.1 5093.3 5096.6 5107.1 5121.5 5124.0 5128.8 5177.6 5641.7 5659.0 5717.9 5726.2 5755.4 6671.4 6686.2 6702.9 6719.5 6775.6 1774.5 1798.0 1799.6 1804.6 1808.1 1173.6 1216.7 1224.4 1232.3 1240.1 5831.4 5841.4 5873.6 5913.0 5931.1 6748.3 6846.0 6865.9 6866.0 6873.9 5860.2 5876.2 5894.2 5896.7 5909.4 3570.6 3599.6 3606.8 3612.2 3645.1 6599.7 6621.7 6673.8 6675.0 6708.9 6548.4 6853.8 6907.1 7018.4 7030.9 6492.6 6522.7 6556.7 6569.8 6589.6 6738.9 6778.2 6806.6 6810.2 6854.7 6682.4 6714.3 6790.0 6823.2 6843.9 6545.0 6568.5 6571.3 6610.4 6642.2 5027.6 5053.0 5060.4 5067.3 5075.9 6555.2 6620.0 6624.1 6823.8 6852.9 6436.8 6521.5 6528.4 6571.7 6623.3 6543.7 6545.7 6607.6 6615.5 6734.0 6473.9 6488.4 6546.7 6631.0 6631.8 6646.4 6652.3 6723.4 6731.3 6738.7 6460.4 6475.9 6493.6 6494.2 6517.0 21:05:54 INFO - PID 12845 | 21:05:54 INFO - PID 12845 | [#5] yui.html Cycles:180 Average:23509.73 Median:20158.95 stddev:31843.59 (158.0%) stddev-sans-first:31887.72 21:05:54 INFO - PID 12845 | Values: 914.4 924.4 929.7 936.7 942.8 21413.5 23552.3 23590.1 23622.6 23625.2 20888.8 21268.5 21378.9 21397.3 21449.7 979.4 983.1 1000.1 1001.9 1002.8 1017.7 1024.0 1024.4 1026.3 1027.7 1064.7 1066.9 1067.3 1068.6 1069.5 1058.5 1059.6 1060.5 1061.1 1062.3 776.9 785.5 785.9 789.0 789.0 19012.0 19448.9 19686.6 19702.7 20141.1 21131.4 21537.8 21552.4 21648.9 21716.5 16206.0 16924.9 17264.4 17420.9 17434.6 3833.1 3872.0 3875.8 3876.6 3877.5 124373.0 127218.4 128696.7 128775.0 129962.4 17359.4 17508.6 17545.6 17614.0 17814.8 23968.6 30854.0 30947.2 30978.5 31544.2 9044.5 9182.8 9272.5 9374.4 9422.3 4461.5 4554.9 4564.1 4573.7 4591.1 5231.2 5263.3 5359.8 5378.4 5388.4 1076.7 1082.4 1093.9 1097.4 1097.5 701.8 712.2 719.2 737.5 742.4 11597.7 11895.4 12275.2 12507.6 12847.9 637.4 687.9 701.8 703.7 706.0 5278.5 5397.9 5620.7 5683.9 6178.2 107716.0 111238.0 111608.0 115430.4 117835.0 92509.0 93518.5 97309.5 103213.0 103214.5 83496.0 84938.0 86277.0 87082.7 89897.0 13024.3 13094.2 13213.3 13272.7 13442.5 37880.6 38212.4 39189.8 39589.8 40172.6 50040.0 51063.5 51308.0 52167.0 52609.5 18386.9 18456.0 18602.8 18766.6 18840.2 19826.3 20176.8 20215.7 20223.5 20362.9 17068.0 17104.1 17168.7 17176.8 17277.4 17320.7 17374.0 17461.7 17489.2 17526.2 17687.6 17807.1 17936.6 17972.6 18020.9 13170.6 13187.3 13252.4 13264.0 13295.3 14136.9 14298.6 14353.1 14357.3 14368.7 21:05:54 INFO - PID 12845 | -------- Summary: end -------- 21:05:54 INFO - PID 12845 | 21:05:55 INFO - TEST-INFO | 12845: exit 0 21:05:55 INFO - TEST-OK | dromaeo_css | took 1110517ms 21:05:55 INFO - TEST-START | kraken 21:05:55 INFO - Initialising browser for kraken test... 21:05:55 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:54553/getInfo.html -profile /tmp/tmpvkc8ax/profile 21:05:55 INFO - TEST-INFO | started process 13222 (/builds/slave/test/build/application/firefox/firefox http://localhost:54553/getInfo.html) 21:06:01 INFO - TEST-INFO | 13222: exit 0 21:06:01 INFO - Browser initialized. 21:06:01 INFO - Running cycle 1/1 for kraken test... 21:06:01 INFO - Using env: {'DISPLAY': ':0', 21:06:01 INFO - 'HOME': '/home/cltbld', 21:06:01 INFO - 'JSGC_DISABLE_POISONING': '1', 21:06:01 INFO - 'LANG': 'en_US.UTF-8', 21:06:01 INFO - 'LANGUAGE': 'en_US:en', 21:06:01 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 21:06:01 INFO - 'LOGNAME': 'cltbld', 21:06:01 INFO - 'MAIL': '/var/mail/cltbld', 21:06:01 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 21:06:01 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 21:06:01 INFO - 'MOZ_CRASHREPORTER': '1', 21:06:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:06:01 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 21:06:01 INFO - 'MOZ_NO_REMOTE': '1', 21:06:01 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 21:06:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:06:01 INFO - 'NO_EM_RESTART': '1', 21:06:01 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:06:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:06:01 INFO - 'PWD': '/builds/slave/test', 21:06:01 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 21:06:01 INFO - 'RUST_BACKTRACE': 'full', 21:06:01 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 21:06:01 INFO - 'SHELL': '/bin/bash', 21:06:01 INFO - 'SHLVL': '1', 21:06:01 INFO - 'STYLO_FORCE_ENABLED': '1', 21:06:01 INFO - 'STYLO_THREADS': '1', 21:06:01 INFO - 'TERM': 'linux', 21:06:01 INFO - 'TMOUT': '86400', 21:06:01 INFO - 'USER': 'cltbld', 21:06:01 INFO - 'XDG_SESSION_COOKIE': 'ca6c2627acea03e666270f68000001e0-1504151176.325908-188269164', 21:06:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:06:01 INFO - '_': '/tools/buildbot/bin/python'} 21:06:01 INFO - TEST-INFO | started process 13411 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpvkc8ax/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 21:06:02 INFO - PID 13411 | 21:06:02 INFO - PID 13411 | (/builds/slave/test/build/application/firefox/firefox:13463): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 21:06:02 INFO - PID 13411 | 21:06:12 INFO - PID 13411 | RSS: Main: 167190528 21:06:12 INFO - PID 13411 | 21:06:15 INFO - PID 13411 | Cycle 1(1): loaded http://localhost:54553/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:54553/tests/kraken/driver.html?testName=audio-beat-detection) 21:06:15 INFO - PID 13411 | RSS: Main: 173428736 21:06:15 INFO - PID 13411 | 21:06:18 INFO - PID 13411 | Cycle 1(1): loaded http://localhost:54553/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:54553/tests/kraken/driver.html?testName=audio-dft) 21:06:19 INFO - PID 13411 | RSS: Main: 177704960 21:06:19 INFO - PID 13411 | 21:06:22 INFO - PID 13411 | Cycle 1(1): loaded http://localhost:54553/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:54553/tests/kraken/driver.html?testName=audio-fft) 21:06:22 INFO - PID 13411 | RSS: Main: 169967616 21:06:22 INFO - PID 13411 | 21:06:25 INFO - PID 13411 | Cycle 1(1): loaded http://localhost:54553/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:54553/tests/kraken/driver.html?testName=audio-oscillator) 21:06:25 INFO - PID 13411 | RSS: Main: 170745856 21:06:25 INFO - PID 13411 | 21:06:27 INFO - PID 13411 | Cycle 1(1): loaded http://localhost:54553/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:54553/tests/kraken/driver.html?testName=imaging-gaussian-blur) 21:06:28 INFO - PID 13411 | RSS: Main: 170663936 21:06:28 INFO - PID 13411 | 21:06:31 INFO - PID 13411 | Cycle 1(1): loaded http://localhost:54553/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:54553/tests/kraken/driver.html?testName=imaging-darkroom) 21:06:32 INFO - PID 13411 | RSS: Main: 171499520 21:06:32 INFO - PID 13411 | 21:06:35 INFO - PID 13411 | Cycle 1(1): loaded http://localhost:54553/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:54553/tests/kraken/driver.html?testName=imaging-desaturate) 21:06:36 INFO - PID 13411 | RSS: Main: 172220416 21:06:36 INFO - PID 13411 | 21:06:39 INFO - PID 13411 | Cycle 1(1): loaded http://localhost:54553/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:54553/tests/kraken/driver.html?testName=json-parse-financial) 21:06:39 INFO - PID 13411 | RSS: Main: 171909120 21:06:39 INFO - PID 13411 | 21:06:41 INFO - PID 13411 | Cycle 1(1): loaded http://localhost:54553/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:54553/tests/kraken/driver.html?testName=json-stringify-tinderbox) 21:06:42 INFO - PID 13411 | RSS: Main: 172150784 21:06:42 INFO - PID 13411 | 21:06:43 INFO - PID 13411 | Cycle 1(1): loaded http://localhost:54553/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:54553/tests/kraken/driver.html?testName=stanford-crypto-aes) 21:06:44 INFO - PID 13411 | RSS: Main: 172310528 21:06:44 INFO - PID 13411 | 21:06:46 INFO - PID 13411 | Cycle 1(1): loaded http://localhost:54553/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:54553/tests/kraken/driver.html?testName=stanford-crypto-ccm) 21:06:47 INFO - PID 13411 | RSS: Main: 171925504 21:06:47 INFO - PID 13411 | 21:06:49 INFO - PID 13411 | Cycle 1(1): loaded http://localhost:54553/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:54553/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 21:06:50 INFO - PID 13411 | RSS: Main: 172519424 21:06:50 INFO - PID 13411 | 21:06:53 INFO - PID 13411 | Cycle 1(1): loaded http://localhost:54553/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:54553/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 21:06:54 INFO - PID 13411 | RSS: Main: 172736512 21:06:54 INFO - PID 13411 | 21:06:55 INFO - PID 13411 | Cycle 1(1): loaded http://localhost:54553/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:54553/tests/kraken/driver.html?testName=ai-astar) 21:06:56 INFO - PID 13411 | RSS: Main: 174243840 21:06:56 INFO - PID 13411 | 21:06:56 INFO - PID 13411 | __start_tp_report 21:06:56 INFO - PID 13411 | _x_x_mozilla_page_load 21:06:56 INFO - PID 13411 | _x_x_mozilla_page_load_details 21:06:56 INFO - PID 13411 | |i|pagename|runs| 21:06:56 INFO - PID 13411 | |0;ai-astar;100;109;108;107;107;117;108;98;110;110 21:06:56 INFO - PID 13411 | |1;audio-beat-detection;138;115;140;190;117;147;148;117;170;120 21:06:56 INFO - PID 13411 | |2;audio-dft;166;163;164;161;165;164;163;164;164;163 21:06:56 INFO - PID 13411 | |3;audio-fft;75;76;75;155;78;75;73;88;97;73 21:06:56 INFO - PID 13411 | |4;audio-oscillator;79;79;138;79;79;80;138;79;79;129 21:06:56 INFO - PID 13411 | |5;imaging-gaussian-blur;114;114;113;114;114;114;114;113;114;114 21:06:56 INFO - PID 13411 | |6;imaging-darkroom;148;147;148;148;149;148;148;148;149;147 21:06:56 INFO - PID 13411 | |7;imaging-desaturate;94;95;94;95;94;93;94;96;94;93 21:06:56 INFO - PID 13411 | |8;json-parse-financial;53;53;52;52;54;53;53;52;53;52 21:06:56 INFO - PID 13411 | |9;json-stringify-tinderbox;39;39;39;38;42;43;39;39;39;39 21:06:56 INFO - PID 13411 | |10;stanford-crypto-aes;75;79;75;79;77;88;75;74;73;74 21:06:56 INFO - PID 13411 | |11;stanford-crypto-ccm;129;122;122;121;126;119;122;127;131;158 21:06:56 INFO - PID 13411 | |12;stanford-crypto-pbkdf2;178;174;172;169;169;172;171;168;167;176 21:06:56 INFO - PID 13411 | |13;stanford-crypto-sha256-iterative;66;70;64;65;66;65;63;64;66;75 21:06:56 INFO - PID 13411 | __end_tp_report 21:06:56 INFO - PID 13411 | __start_cc_report 21:06:56 INFO - PID 13411 | _x_x_mozilla_cycle_collect,937 21:06:56 INFO - PID 13411 | __end_cc_report 21:06:56 INFO - PID 13411 | __startTimestamp1504152416075__endTimestamp 21:06:56 INFO - PID 13411 | 21:06:56 INFO - PID 13411 | ------- Summary: start ------- 21:06:56 INFO - PID 13411 | Number of tests: 14 21:06:56 INFO - PID 13411 | 21:06:56 INFO - PID 13411 | [#0] ai-astar Cycles:10 Average:107.40 Median:109.50 stddev:5.30 (4.8%) stddev-sans-first:4.89 21:06:56 INFO - PID 13411 | Values: 100.0 109.0 108.0 107.0 107.0 117.0 108.0 98.0 110.0 110.0 21:06:56 INFO - PID 13411 | 21:06:56 INFO - PID 13411 | [#1] audio-beat-detection Cycles:10 Average:140.20 Median:143.50 stddev:24.94 (17.4%) stddev-sans-first:26.44 21:06:56 INFO - PID 13411 | Values: 138.0 115.0 140.0 190.0 117.0 147.0 148.0 117.0 170.0 120.0 21:06:56 INFO - PID 13411 | 21:06:56 INFO - PID 13411 | [#2] audio-dft Cycles:10 Average:163.70 Median:164.00 stddev:1.34 (0.8%) stddev-sans-first:1.13 21:06:56 INFO - PID 13411 | Values: 166.0 163.0 164.0 161.0 165.0 164.0 163.0 164.0 164.0 163.0 21:06:56 INFO - PID 13411 | 21:06:56 INFO - PID 13411 | [#3] audio-fft Cycles:10 Average:86.50 Median:75.50 stddev:25.27 (33.5%) stddev-sans-first:26.46 21:06:56 INFO - PID 13411 | Values: 75.0 76.0 75.0 155.0 78.0 75.0 73.0 88.0 97.0 73.0 21:06:56 INFO - PID 13411 | 21:06:56 INFO - PID 13411 | [#4] audio-oscillator Cycles:10 Average:95.90 Median:79.00 stddev:27.09 (34.3%) stddev-sans-first:28.04 21:06:56 INFO - PID 13411 | Values: 79.0 79.0 138.0 79.0 79.0 80.0 138.0 79.0 79.0 129.0 21:06:56 INFO - PID 13411 | 21:06:56 INFO - PID 13411 | [#5] imaging-gaussian-blur Cycles:10 Average:113.80 Median:114.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 21:06:56 INFO - PID 13411 | Values: 114.0 114.0 113.0 114.0 114.0 114.0 114.0 113.0 114.0 114.0 21:06:56 INFO - PID 13411 | 21:06:56 INFO - PID 13411 | [#6] imaging-darkroom Cycles:10 Average:148.00 Median:148.00 stddev:0.67 (0.5%) stddev-sans-first:0.71 21:06:56 INFO - PID 13411 | Values: 148.0 147.0 148.0 148.0 149.0 148.0 148.0 148.0 149.0 147.0 21:06:56 INFO - PID 13411 | 21:06:56 INFO - PID 13411 | [#7] imaging-desaturate Cycles:10 Average:94.20 Median:94.00 stddev:0.92 (1.0%) stddev-sans-first:0.97 21:06:56 INFO - PID 13411 | Values: 94.0 95.0 94.0 95.0 94.0 93.0 94.0 96.0 94.0 93.0 21:06:56 INFO - PID 13411 | 21:06:56 INFO - PID 13411 | [#8] json-parse-financial Cycles:10 Average:52.70 Median:53.00 stddev:0.67 (1.3%) stddev-sans-first:0.71 21:06:56 INFO - PID 13411 | Values: 53.0 53.0 52.0 52.0 54.0 53.0 53.0 52.0 53.0 52.0 21:06:56 INFO - PID 13411 | 21:06:56 INFO - PID 13411 | [#9] json-stringify-tinderbox Cycles:10 Average:39.60 Median:39.00 stddev:1.58 (4.0%) stddev-sans-first:1.66 21:06:56 INFO - PID 13411 | Values: 39.0 39.0 39.0 38.0 42.0 43.0 39.0 39.0 39.0 39.0 21:06:56 INFO - PID 13411 | 21:06:56 INFO - PID 13411 | [#10] stanford-crypto-aes Cycles:10 Average:76.90 Median:76.00 stddev:4.41 (5.8%) stddev-sans-first:4.62 21:06:56 INFO - PID 13411 | Values: 75.0 79.0 75.0 79.0 77.0 88.0 75.0 74.0 73.0 74.0 21:06:56 INFO - PID 13411 | 21:06:56 INFO - PID 13411 | [#11] stanford-crypto-ccm Cycles:10 Average:127.70 Median:126.50 stddev:11.31 (8.9%) stddev-sans-first:11.99 21:06:56 INFO - PID 13411 | Values: 129.0 122.0 122.0 121.0 126.0 119.0 122.0 127.0 131.0 158.0 21:06:56 INFO - PID 13411 | 21:06:56 INFO - PID 13411 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:171.60 Median:172.00 stddev:3.57 (2.1%) stddev-sans-first:2.93 21:06:56 INFO - PID 13411 | Values: 178.0 174.0 172.0 169.0 169.0 172.0 171.0 168.0 167.0 176.0 21:06:56 INFO - PID 13411 | 21:06:56 INFO - PID 13411 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.40 Median:66.00 stddev:3.57 (5.4%) stddev-sans-first:3.78 21:06:56 INFO - PID 13411 | Values: 66.0 70.0 64.0 65.0 66.0 65.0 63.0 64.0 66.0 75.0 21:06:56 INFO - PID 13411 | -------- Summary: end -------- 21:06:56 INFO - PID 13411 | 21:06:56 INFO - PID 13411 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 21:06:56 INFO - TEST-INFO | 13411: exit 0 21:06:56 INFO - TEST-OK | kraken | took 61364ms 21:06:56 INFO - SUITE-END | took 1171s 21:06:57 INFO - Completed test suite (00:19:32) 21:06:57 INFO - javascript benchmark 21:06:57 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8656.201897451187, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3323.169207698074, 3351.9162020949475, 3355.2953879685274, 3381.1378098584855, 3391.525186473894, 6273.5294852886045, 6339.239291285041, 6408.45528130109, 6432.035194720788, 6474.125992990945, 6355.809325720235, 6435.51733619978, 6439.130717353156, 6451.25810531789, 6467.838304042401, 5881.764729410818, 5883.529317654577, 5906.320773111082, 5921.526277897779, 5925.703714814264, 5893.057110862263, 5909.940900591004, 5992.0712289595185, 6024.09638554216, 6027.7287522061615, 5832.066869300913, 5894.646321220719, 5895.587308888379, 5900.675462849542, 5963.910541341883, 5743.597948143632, 5770.336411312711, 5836.445537673914, 5839.503642190419, 5865.56008299375, 5377.4084850666395, 5799.9130013049835, 5841.415858414167, 5939.762409503614, 5967.343592204854, 6419.903701444482, 6460.903086453707, 6463.192100987377, 6536.11762412073, 6545.410913017851, 6843.0, 6916.723331066752, 6923.273056329063, 6950.652467376662, 6963.0251764752975, 6982.650867456607, 7000.964995174992, 7004.2645522220455, 7008.194057683329, 7010.719571217145, 6370.649614271213, 6399.424051835311, 6421.678916054178, 6479.416852483299, 6483.513736469737, 7080.398166155832, 7133.607651579213, 7176.102987126609, 7221.638918054181, 7229.34935855765, 6777.796666100025, 6805.829854253683, 6826.488013399015, 6826.522143449911, 6834.214065382445, 6781.491388145909, 6856.314368563104, 6959.8956015659805, 6961.895571566431, 6964.512484126164, 6810.182778066664, 6816.965915170393, 6846.315368463114, 6929.02993580889, 6930.826729331807, 6947.340002699735, 6982.0, 7002.544834585736, 7012.193597736224, 7013.964930175316, 5442.129259318569, 5443.183522471581, 5507.7246137692955, 5518.724063796794, 5518.91721624176, 4645.74448405341, 4699.201135806921, 4754.072955773591, 4789.616830653539, 4812.494688057774, 5898.056310990221, 6005.699715014319, 6075.3924607539775, 6080.118382834465, 6081.331053584102, 6981.406580440719, 7047.0, 7077.893831592531, 7079.752208672775, 7081.0, 6003.6397816131175, 6013.5189184865985, 6053.727582258809, 6079.209702738615, 6119.5410344224365, 5820.82537523875, 5843.0, 5924.289085309704, 5942.61373010753, 5956.970215148896, 6697.866042679022, 6717.395434410926, 6728.966355168192, 6773.8645227095185, 6807.183138023469, 6947.096877405905, 6965.65171741421, 6985.895211571728, 7048.400885924754, 7102.289771022856, 6568.080468734476, 6676.365745254193, 6698.062271282122, 6764.594124352555, 6766.627835469097, 6815.2162501311, 6846.520743548103, 6872.0, 6908.758193463204, 6928.099347084847, 6919.238883722887, 6920.4809639276245, 6923.861522769415, 7012.474064445289, 7012.929870701431, 6650.168728908887, 6653.733850646114, 6709.228438729413, 6754.358335958076, 6771.62756048422, 6766.221884483348, 6773.864522709419, 6787.524873258824, 6792.422644075309, 6800.897986530107, 6618.139641846722, 6629.801105966829, 6701.128853249046, 6706.731730730716, 6713.56361836479, 6720.529562930548, 6746.359095885883, 6747.730090796314, 6752.662366881538, 6856.828579285558, 6463.192100987377, 6531.216254049545, 6535.836604084936, 6536.117624120873, 6539.182602174728], "value": 6310.621083897842, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [820.9179082091791, 855.8031652719874, 880.7974165941833, 884.8141890203059, 1107.6067995861465, 29636.40727185455, 39945.60054399455, 40621.99999999996, 40660.79669601655, 40732.18535629286, 41854.0, 42220.57779422205, 42316.36525452121, 42337.0, 42548.787256063675, 7200.603966781825, 7673.386129109672, 7806.726764563213, 7817.140114587391, 7850.568218747967, 7648.158702542716, 7784.727534536292, 7790.883136752953, 7791.337736292394, 7800.946872172241, 7730.4975176613625, 7781.727639532617, 7806.180351063142, 7809.0, 7814.648340824648, 7773.067231932176, 7777.10563285221, 7781.105172905131, 7789.844203115963, 7795.220477952216, 7704.345130663844, 7762.301392874669, 7772.883406748903, 7776.144624091346, 7780.688772449095, 37864.24271514582, 38176.23647527062, 38219.0, 38237.23525529474, 38263.61736382656, 3571.62497937718, 3609.025563097975, 3610.62088480711, 3613.837377318027, 3614.9277014459826, 31401.37197256065, 31417.0, 31432.52851207234, 31506.684933150835, 31521.684783152105, 5253.0544501989625, 5278.762455689503, 5334.199870019489, 5344.171653393731, 5346.572274218053, 194471.0, 194574.0, 195290.0, 195330.02334988376, 195802.0, 47821.521784781704, 47822.28266576004, 47863.0, 47918.28122578164, 48084.0, 49972.50027499678, 53427.46572534225, 53646.0, 53704.46295537073, 53782.0, 15412.306446209948, 15462.304196311194, 15538.0, 15545.611359716098, 15562.144082075594, 10473.738156546147, 10609.363438193734, 12279.324637144864, 12459.314737689514, 12558.623241302776, 14367.281635918162, 14425.567232983027, 14508.419663213564, 14524.419023238952, 14650.560483185522, 5535.474129957647, 5626.634268772516, 5677.517411020027, 5801.129830525455, 5833.533317334603, 1010.1767059846202, 1023.4013102335068, 1036.23836480187, 1037.1961729659574, 1043.5304113149114, 22474.98862551189, 22793.772062279495, 22979.310620681405, 23090.845457727046, 23445.41386465352, 1105.8783533811275, 1124.6794663520857, 1128.041165009735, 1129.3506233915532, 1139.157023802397, 12292.877071229173, 12323.0, 12329.876701233052, 12343.444544995496, 12423.006159507218, 242125.0, 243704.0, 243763.0, 244458.0, 244478.0, 51085.0, 51213.74393128011, 51224.48775512272, 51282.0, 51360.486395136315, 1410.2314238739707, 1438.8057612222217, 1445.104035498019, 1462.7215394969883, 1475.2181343887758, 21916.89041554782, 22256.666150008088, 22320.0, 22468.988895499413, 22890.65664015008, 36839.63160368362, 36997.63002369942, 37018.8149059253, 37058.629413705516, 37063.81468092642, 204247.0, 205439.0, 205844.0, 206235.0, 206902.0, 34391.31217375588, 35501.467477988364, 37334.43998339973, 37421.625783741816, 37457.0, 45092.0, 45422.31866522071, 45570.0, 45700.08599828051, 45887.77056114698, 33925.66074339225, 34060.31879362449, 34123.65876341205, 34328.28358582111, 34360.140996474285, 33206.16984575405, 33269.50095748614, 33286.33427331488, 33825.323493530486, 34152.31695366029, 30060.24849378783, 30170.547441787952, 30463.695363046085, 30619.54070688897, 30907.536386953765, 32856.83571582127, 32899.835500822344, 33015.8349208262, 33619.0, 33654.0, 23349.0, 24323.0, 24490.510189795747, 24612.507749844546, 25125.497490049733], "value": 17247.3073694683, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [562.8030189433699, 563.9210510528526, 567.86371270895, 574.7700919632149, 581.9592628516004, 21660.675089873646, 22572.54854902901, 22587.435314117156, 22607.660885086716, 23459.648105278393, 25233.116840910527, 25916.35209119773, 25962.74037259627, 25990.0, 25992.350191245227, 6682.665866706681, 6798.762043328485, 6828.453723702117, 6832.111825462683, 6839.384455399013, 5914.615549989237, 6465.644389558573, 6505.577137486048, 6649.933500665004, 6678.76624318149, 6430.228372595272, 6431.035344698286, 6431.485481161496, 6450.258220304655, 6458.095866578683, 6430.0676401921455, 6434.195725534309, 6436.93563064368, 6443.226812782496, 6453.225612926455, 5988.131720900445, 5990.161377407167, 5991.700414979234, 5994.820155395345, 5997.070454079581, 27950.301242468897, 28574.428511429862, 28850.134495965154, 29457.11628651144, 29474.70525294741, 1960.549073713054, 2004.629143608437, 2011.8893460859647, 2012.7182194492636, 2014.8287395571394, 19104.713429298572, 19377.128029238716, 19763.308284209983, 20385.286514971904, 20396.184152633876, 2390.151496218852, 2404.0, 2451.4974430241937, 2451.901923923041, 2459.7294297627245, 135531.0, 143825.0, 152598.0, 152664.0, 152738.0, 14363.749284795644, 14546.927265363605, 14826.184559849096, 14936.925315373353, 14983.400663973536, 22791.772082279298, 22857.771422285896, 22895.198668046538, 22909.656355154686, 22970.65544016841, 9605.423674579548, 10005.399676019462, 10035.79928401428, 10102.444365559817, 10184.541695623715, 9250.72247832566, 9254.629814807467, 9321.161095501437, 9368.672096476726, 9442.622295108118, 8907.955460222658, 9130.726078217664, 9207.309451791143, 9273.814523709489, 9515.0, 2644.2451718559305, 2660.3349162709324, 2762.654668166479, 2789.2190186747744, 2936.471435141695, 963.1572374172599, 973.250597040275, 980.4509474694194, 982.8722266105503, 993.3344659078434, 19247.90376048111, 19631.90184049099, 19798.980451172596, 19932.30236941729, 19972.50068748264, 1044.09685621938, 1044.3472829481575, 1045.0959919669583, 1052.9789404211872, 1062.176812969939, 10893.291936024134, 11115.166362522843, 11121.610743623934, 11136.610218642309, 11237.269577477438, 460865.0, 461513.0, 463835.0, 486036.0, 488441.0, 115330.42334788272, 117726.0, 119052.40473797749, 119090.40454797671, 119746.0, 1010.3887148275188, 1061.9044286014296, 1066.5360568152878, 1118.1669656106253, 1158.8551431071116, 20931.37205883826, 21081.89459052695, 22177.77822221757, 22304.330870073925, 22402.439939001655, 55793.12438112903, 56932.14601780894, 58205.126923097036, 59444.02779861076, 60647.0, 64915.02627460498, 65327.0, 65568.34431655813, 65628.67185664042, 66128.33871661415, 14626.488072917396, 14757.409703612164, 14768.33542490569, 14825.629359266104, 14903.85096149068, 23077.65383519282, 23184.768152318262, 23263.883680582167, 23336.533269334857, 23350.299491015296, 18168.182431790337, 18287.81712182861, 18311.450656480327, 18546.907265464128, 18621.162047708156, 16550.586235344213, 16576.917115414344, 16916.407925722542, 17044.914775426045, 17285.91357043257, 18846.3403780867, 18899.0, 19105.044747762277, 19111.426657200307, 19181.136848841557, 13775.931120344334, 14681.92659036698, 14786.186759728318, 14815.25923703832, 14831.92584037073, 17982.0, 18085.63828723392, 18136.72794908104, 18171.000594967405, 18281.725774113664], "value": 12004.927971768193, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1328.3291937571528, 1372.499037851184, 1379.9448022079123, 1388.5626027801247, 1399.0975820595713, 12400.441980110894, 12973.15674481158, 15259.465918692847, 15799.526014219564, 15848.920755396235, 15281.770773438408, 15538.300776465057, 15558.53324400267, 15558.922205388957, 15608.297626606802, 2685.5434576122107, 2722.904698335554, 2799.6220510231165, 2804.214819850445, 2804.9719502805015, 2752.6559180102486, 2855.8714857831446, 2863.5418333066714, 2866.211791757273, 2866.5843452699346, 2775.3339198592394, 2795.776337892974, 2797.818141820775, 2815.7325054119824, 2816.9718302816914, 2663.081236973241, 2664.733526647339, 2676.4245687177254, 2690.0450340129364, 2691.125384250117, 2597.545429549821, 2604.231751633273, 2614.3333449970137, 2615.9346016349555, 2616.672915885514, 15030.398784048624, 15078.773818392732, 15156.772648410173, 15165.924170379189, 15293.923530382277, 8068.919310806876, 8373.874391884126, 8377.078521362646, 8390.160983901562, 8404.873926891101, 5362.4637536246455, 5466.590005749545, 5552.167174923753, 5629.268195134607, 5660.745266463019, 1794.9461516154536, 1795.111419847171, 1830.4142674344216, 1836.0085553800932, 1841.3279153109152, 26879.462410751676, 27070.1878943632, 27074.45851082987, 27098.187054388203, 27101.322466938484, 6683.5989840608745, 6783.18601767791, 6833.897491537631, 7024.894626580605, 7025.262347453546, 17317.7402338965, 17323.826761732475, 17342.653146937242, 17353.65292694139, 17358.13209339528, 7786.610669466504, 8058.194180582012, 8066.15305392937, 8073.515589064675, 8081.757547273592, 6263.123162757218, 6489.837754056092, 7156.713731450684, 7526.435517336222, 7531.246875312425, 5309.256704061435, 5373.059714549938, 5459.426760190123, 5461.317335333083, 5503.614746967673, 1536.9385224591115, 1568.8666463350744, 1582.8021497312836, 1588.6822635472952, 1601.8157911840287, 912.703371404283, 934.8036912248504, 937.4796987671771, 938.8357037518407, 946.3328353510758, 8432.662693492315, 8606.65573377058, 8668.046514883357, 8719.346049046348, 8763.079876612992, 980.6812785844629, 999.8950110238466, 999.8950110238466, 1007.1439276614965, 1010.8332125199407, 4797.56821886032, 5720.542356611461, 5859.882802343929, 5876.6180198286975, 5994.490468310242, 22283.331500055025, 22721.545569088856, 22827.885860570925, 22838.771612283996, 22886.42783930415, 21401.892990534947, 22208.555828883655, 22261.220857269916, 22285.21718262535, 22595.661065084038, 11316.94341528287, 11653.941730291293, 11669.183157179086, 11678.591249306233, 11679.357635329969, 8713.738587842376, 8857.247133993682, 8873.423227490193, 9050.592723327598, 9062.728118156467, 18391.172397241888, 18709.90645046766, 18848.52878678044, 19007.23971041198, 19041.619167616293, 19191.040447977826, 19248.422547323604, 19278.903605481883, 19297.4210773677, 19301.4209573713, 10678.145748339959, 10797.460126993461, 10805.351678899291, 10891.836622450828, 10897.564097436014, 14181.503647372292, 14390.06464579799, 14397.208153551655, 14406.495772647908, 14407.783883241551, 10663.200259980378, 10722.571097156026, 10772.084372828398, 10794.73013174677, 10956.233063685466, 10372.792544149226, 10587.788244235226, 10664.146868250677, 10901.236913415985, 10947.781044378908, 9923.354981926152, 10099.14157296638, 10116.03897629724, 10122.79754404893, 10231.181505479693, 7358.595277259802, 7387.4090072795125, 7475.36459400957, 7484.738034168778, 7524.0, 7289.963550182215, 7323.194448610755, 7346.265373462611, 7461.253874612496, 7555.357794587521], "value": 6769.332244447821, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [512.4644746240183, 518.300294602287, 534.6076308519374, 536.9731513424329, 542.3193891665959, 6309.085182648513, 6336.859365314247, 6378.425941665249, 6392.7442902283965, 6502.154719886414, 6161.630302181861, 6190.969045154779, 6192.938070619292, 6295.387414223103, 6313.463355614778, 5218.373795144588, 5228.163493840986, 5279.973600131994, 5291.28567643369, 5305.098133317326, 4916.385451818523, 5064.0, 5237.45006774289, 5301.840944771663, 5308.867278318035, 4991.700497970133, 5033.936086204657, 5044.520770526794, 5072.239164125393, 5090.200389785622, 4973.886098408162, 5006.1489546776775, 5057.595392368602, 5069.366329208849, 5078.873028174288, 4513.684042117053, 4536.387587675655, 4555.908881822378, 4557.74932378719, 4577.382053422779, 6197.411245931629, 6295.937040629581, 6305.432511073979, 6342.746290148389, 6361.01404282337, 5789.797357092523, 5802.796902108448, 5803.448672376117, 5840.649561026352, 5882.852928676774, 6550.312217217172, 6581.407673309377, 6628.237752658458, 6647.567908085959, 6690.665466726644, 4894.192458244385, 4946.72792996385, 4953.628477864175, 4957.479464656195, 4966.950330496685, 6760.18877734675, 6776.6611669416825, 6911.308869113099, 7007.194172670157, 7113.928860711379, 5057.241413787961, 5058.494150584912, 5065.11360511909, 5093.286939828353, 5096.61775366849, 5107.080725469451, 5121.4622464641425, 5123.97438012815, 5128.820371314619, 5177.611679124081, 5641.717914104278, 5658.971705141448, 5717.942820571824, 5726.198332233491, 5755.366909639936, 6671.43292820106, 6686.1642294713165, 6702.932970670328, 6719.462443004548, 6775.593464392152, 1774.5297496163528, 1797.9550511237062, 1799.5681036551396, 1804.6374986884819, 1808.095952023988, 1173.600975668277, 1216.7444836584416, 1224.3633310678576, 1232.3037483821613, 1240.1443004326986, 5831.387704290988, 5841.357450680422, 5873.58884878063, 5912.970435147797, 5931.139984702194, 6748.257691653914, 6845.973104034435, 6865.862682746317, 6865.970104484268, 6873.931260687329, 5860.150278209721, 5876.236089308362, 5894.233749612608, 5896.705164741746, 5909.4293982117915, 3570.553680789901, 3599.6220396858475, 3606.8376923038527, 3612.1511444810976, 3645.1251699591908, 6599.703013364314, 6621.735130594722, 6673.799786006428, 6674.966625166843, 6708.899366509506, 6548.377904099103, 6853.7943861684225, 6907.132006076613, 7018.368346848808, 7030.894536581955, 6492.597738836566, 6522.673866306665, 6556.672166391661, 6569.770058047945, 6589.604623722592, 6738.8652226956165, 6778.2205046420295, 6806.591604503746, 6810.182778066664, 6854.657267136723, 6682.431993280626, 6714.321419645089, 6790.0, 6823.215330237086, 6843.863122737617, 6544.9672751635935, 6568.474522038321, 6571.310012448624, 6610.438112760276, 6642.20293564756, 5027.617405213596, 5052.9747351263, 5060.443351231435, 5067.265246539305, 5075.873103172303, 6555.2133743951035, 6619.966900165468, 6624.072629831924, 6823.761168359084, 6852.897206541807, 6436.774712885027, 6521.5108866834225, 6528.376750903104, 6571.737130514917, 6623.337666233531, 6543.672816359068, 6545.672716364067, 6607.636579988147, 6615.503837212132, 6734.0, 6473.902891456726, 6488.416042556192, 6546.69066186755, 6630.9668451657435, 6631.767888123893, 6646.368594983469, 6652.334766523309, 6723.4285085766305, 6731.293214212633, 6738.696758645775, 6460.353964603502, 6475.870482590416, 6493.610383377012, 6494.188226471691, 6516.967415163083], "value": 5137.786388884477, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [914.3599480363746, 924.3575714878162, 929.7071422501907, 936.7470782888618, 942.8444306689397, 21413.464663383424, 23552.29343119705, 23590.112790976746, 23622.64566031511, 23625.173118940842, 20888.791112088875, 21268.4682882928, 21378.89310553445, 21397.251096211636, 21449.67825482619, 979.3732011512637, 983.1348413396202, 1000.1298869983088, 1001.919846412289, 1002.8495725641176, 1017.7252141921676, 1023.9846402303934, 1024.3956065921104, 1026.3021145620976, 1027.691692492253, 1064.7284942339693, 1066.8666416697913, 1067.3169171730099, 1068.6206396729165, 1069.4759567811755, 1058.5395353021422, 1059.6291298045621, 1060.48566445274, 1061.0980666433509, 1062.267035745341, 776.9223077692242, 785.4894318692839, 785.8821176823465, 788.9605519723991, 788.9763307100797, 19012.0, 19448.902755486273, 19686.606267874562, 19702.70445943312, 20141.09365078575, 21131.366059018255, 21537.77269222171, 21552.353429397142, 21648.891755541277, 21716.457088572755, 16206.0, 16924.915375423167, 17264.395746148824, 17420.91289543557, 17434.564135896704, 3833.137544052594, 3872.012636777632, 3875.786831724254, 3876.5541962674088, 3877.4959255296912, 124373.0, 127218.36390818079, 128696.7130328694, 128775.0, 129962.3501882494, 17359.39242126545, 17508.562285942953, 17545.56136096582, 17614.0, 17814.821851781315, 23968.64047039296, 30854.0, 30947.226319342197, 30978.53532197019, 31544.211394715316, 9044.502552359683, 9182.816343673088, 9272.490013049211, 9374.390664606779, 9422.293328000427, 4461.531539188403, 4554.863354099382, 4564.0871825635, 4573.6569757268335, 4591.127685739699, 5231.215317702375, 5263.289455923478, 5359.758810853521, 5378.354597448331, 5388.380336261302, 1076.6500887211687, 1082.3668154129764, 1093.8960798724108, 1097.3964319624174, 1097.4622435006788, 701.8350687588413, 712.2201189697273, 719.1909102259957, 737.4616529933179, 742.3504433620582, 11597.6520704379, 11895.405229738479, 12275.202111862878, 12507.562235321902, 12847.931514991256, 637.37537213531, 687.8968154776824, 701.7508784381471, 703.7466512055656, 706.0080586775589, 5278.472152784768, 5397.892042159135, 5620.718964051863, 5683.886322273532, 6178.227721534809, 107716.0, 111238.0, 111608.0, 115430.4228478869, 117835.0, 92509.0, 93518.53240733752, 97309.5134524337, 103213.0, 103214.48392757989, 83496.0, 84938.0, 86277.0, 87082.69375959366, 89897.0, 13024.348782561023, 13094.214347139203, 13213.339333033118, 13272.734545309233, 13442.529511467052, 37880.621193788815, 38212.42681359726, 39189.80405097956, 39589.80205098956, 40172.598274016884, 50040.0, 51063.489365105874, 51307.97384052373, 52167.0, 52609.47390526199, 18386.908065460124, 18455.984920828945, 18602.81397186011, 18766.6246675063, 18840.246390144242, 19826.306079287155, 20176.798232017492, 20215.696764548837, 20223.4944126398, 20362.898185508977, 17068.0, 17104.144792760064, 17168.65662686714, 17176.82823171752, 17277.39529116475, 17320.65358692794, 17374.044427556604, 17461.650766984843, 17489.212985415434, 17526.211320490864, 17687.646247075245, 17807.109644517983, 17936.641267174844, 17972.640547189243, 18020.909895450437, 13170.60488185356, 13187.340632968506, 13252.403641835948, 13264.0, 13295.335233238493, 14136.858631413554, 14298.642533936734, 14353.138811671333, 14357.282135893372, 14368.712625747636], "value": 9256.881006994996, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1484.6000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 109.0, 108.0, 107.0, 107.0, 117.0, 108.0, 98.0, 110.0, 110.0], "value": 107.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 115.0, 140.0, 190.0, 117.0, 147.0, 148.0, 117.0, 170.0, 120.0], "value": 140.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 163.0, 164.0, 161.0, 165.0, 164.0, 163.0, 164.0, 164.0, 163.0], "value": 163.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 75.0, 155.0, 78.0, 75.0, 73.0, 88.0, 97.0, 73.0], "value": 86.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 79.0, 138.0, 79.0, 79.0, 80.0, 138.0, 79.0, 79.0, 129.0], "value": 95.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 113.0, 114.0, 114.0, 114.0, 114.0, 113.0, 114.0, 114.0], "value": 113.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 147.0, 148.0, 148.0, 149.0, 148.0, 148.0, 148.0, 149.0, 147.0], "value": 148.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 94.0, 95.0, 94.0, 93.0, 94.0, 96.0, 94.0, 93.0], "value": 94.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 53.0, 52.0, 52.0, 54.0, 53.0, 53.0, 52.0, 53.0, 52.0], "value": 52.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [39.0, 39.0, 39.0, 38.0, 42.0, 43.0, 39.0, 39.0, 39.0, 39.0], "value": 39.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 79.0, 75.0, 79.0, 77.0, 88.0, 75.0, 74.0, 73.0, 74.0], "value": 76.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [129.0, 122.0, 122.0, 121.0, 126.0, 119.0, 122.0, 127.0, 131.0, 158.0], "value": 127.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [178.0, 174.0, 172.0, 169.0, 169.0, 172.0, 171.0, 168.0, 167.0, 176.0], "value": 171.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 70.0, 64.0, 65.0, 66.0, 65.0, 63.0, 64.0, 66.0, 75.0], "value": 66.4, "unit": "score"}]}]} 21:06:57 INFO - Return code: 0 21:06:57 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 21:06:57 INFO - # TBPL SUCCESS # 21:06:57 INFO - Running post-action listener: _package_coverage_data 21:06:57 INFO - Running post-action listener: _resource_record_post_action 21:06:57 INFO - [mozharness: 2017-08-31 04:06:57.380261Z] Finished run-tests step (success) 21:06:57 INFO - Running post-run listener: _resource_record_post_run 21:06:57 INFO - Total resource usage - Wall time: 1189s; CPU: 13.0%; Read bytes: 135168; Write bytes: 392765440; Read time: 20; Write time: 192592 21:06:57 INFO - TinderboxPrint: CPU usage
13.1% 21:06:57 INFO - TinderboxPrint: I/O read bytes / time
135,168 / 20 21:06:57 INFO - TinderboxPrint: I/O write bytes / time
392,765,440 / 192,592 21:06:57 INFO - TinderboxPrint: CPU idle
8,238.1 (86.9%) 21:06:57 INFO - TinderboxPrint: CPU user
1,193.2 (12.6%) 21:06:57 INFO - TinderboxPrint: Swap in / out
0 / 0 21:06:57 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 106659840; Read time: 0; Write time: 60800 21:06:57 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 21:06:57 INFO - run-tests - Wall time: 1173s; CPU: 13.0%; Read bytes: 135168; Write bytes: 286105600; Read time: 20; Write time: 131792 21:06:58 INFO - Running post-run listener: _upload_blobber_files 21:06:58 INFO - Blob upload gear active. 21:06:58 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 21:06:58 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 21:06:58 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'] 21:06:58 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 21:06:58 INFO - (blobuploader) - INFO - Open directory for files ... 21:06:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 21:06:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:06:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:06:59 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 21:06:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:06:59 INFO - (blobuploader) - INFO - Done attempting. 21:06:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 21:06:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:06:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:07:02 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 21:07:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:07:02 INFO - (blobuploader) - INFO - Done attempting. 21:07:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 21:07:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:07:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:07:03 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 21:07:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:07:03 INFO - (blobuploader) - INFO - Done attempting. 21:07:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 21:07:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:07:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:07:04 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 21:07:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:07:04 INFO - (blobuploader) - INFO - Done attempting. 21:07:04 INFO - (blobuploader) - INFO - Iteration through files over. 21:07:04 INFO - Return code: 0 21:07:04 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 21:07:04 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 21:07:04 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8773d593c4097e055b7b2a9a8490bce952c3210affe1fc2f97984cea82a7f577e767d9ae9caef2ee5c6d8f2c73ed32037554c2fc71b6a2b5fe480b9307f92ae5", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7e0a1bc20880a1cfe37f4184cf35e1d3d9d80d7aca0ad0be67d11e12ddc33417032432a06dd6ff9e1c256ff7fb65d2e951b65053623bbd06f3e617f0dc672f3b", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a9068ff9c76886da0860ab6a6f36a4354a74db6f1adc94e21e7268d187a9314ccfd72f3b0708e7aa5d5e0fb303649ba8b7562c7f25e8675b17c4c38b192991cc", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f0be319db78607ebec268cb87636ea821dc443e2530ffe5259c6fb4475690d64940949e541601ec8737268cd1a2e6e81e5e9834cd6af6282b297378e35a5fcd1"} 21:07:04 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 21:07:04 INFO - Writing to file /builds/slave/test/properties/blobber_files 21:07:04 INFO - Contents: 21:07:04 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8773d593c4097e055b7b2a9a8490bce952c3210affe1fc2f97984cea82a7f577e767d9ae9caef2ee5c6d8f2c73ed32037554c2fc71b6a2b5fe480b9307f92ae5", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7e0a1bc20880a1cfe37f4184cf35e1d3d9d80d7aca0ad0be67d11e12ddc33417032432a06dd6ff9e1c256ff7fb65d2e951b65053623bbd06f3e617f0dc672f3b", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a9068ff9c76886da0860ab6a6f36a4354a74db6f1adc94e21e7268d187a9314ccfd72f3b0708e7aa5d5e0fb303649ba8b7562c7f25e8675b17c4c38b192991cc", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f0be319db78607ebec268cb87636ea821dc443e2530ffe5259c6fb4475690d64940949e541601ec8737268cd1a2e6e81e5e9834cd6af6282b297378e35a5fcd1"} 21:07:04 INFO - Running post-run listener: copy_logs_to_upload_dir 21:07:04 INFO - Copying logs to upload dir... 21:07:04 INFO - mkdir: /builds/slave/test/build/upload/logs 21:07:04 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1246.658495 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 46 secs) (at 2017-08-30 21:07:04.672122) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-30 21:07:04.676596) ========= 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=ca6c2627acea03e666270f68000001e0-1504151176.325908-188269164 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8773d593c4097e055b7b2a9a8490bce952c3210affe1fc2f97984cea82a7f577e767d9ae9caef2ee5c6d8f2c73ed32037554c2fc71b6a2b5fe480b9307f92ae5", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7e0a1bc20880a1cfe37f4184cf35e1d3d9d80d7aca0ad0be67d11e12ddc33417032432a06dd6ff9e1c256ff7fb65d2e951b65053623bbd06f3e617f0dc672f3b", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a9068ff9c76886da0860ab6a6f36a4354a74db6f1adc94e21e7268d187a9314ccfd72f3b0708e7aa5d5e0fb303649ba8b7562c7f25e8675b17c4c38b192991cc", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f0be319db78607ebec268cb87636ea821dc443e2530ffe5259c6fb4475690d64940949e541601ec8737268cd1a2e6e81e5e9834cd6af6282b297378e35a5fcd1"} build_url:https://queue.taskcluster.net/v1/task/Cu5bJiXdSKGYWl3gR-gpGg/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011204 build_url: 'https://queue.taskcluster.net/v1/task/Cu5bJiXdSKGYWl3gR-gpGg/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8773d593c4097e055b7b2a9a8490bce952c3210affe1fc2f97984cea82a7f577e767d9ae9caef2ee5c6d8f2c73ed32037554c2fc71b6a2b5fe480b9307f92ae5", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7e0a1bc20880a1cfe37f4184cf35e1d3d9d80d7aca0ad0be67d11e12ddc33417032432a06dd6ff9e1c256ff7fb65d2e951b65053623bbd06f3e617f0dc672f3b", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a9068ff9c76886da0860ab6a6f36a4354a74db6f1adc94e21e7268d187a9314ccfd72f3b0708e7aa5d5e0fb303649ba8b7562c7f25e8675b17c4c38b192991cc", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f0be319db78607ebec268cb87636ea821dc443e2530ffe5259c6fb4475690d64940949e541601ec8737268cd1a2e6e81e5e9834cd6af6282b297378e35a5fcd1"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-30 21:07:04.705966) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-30 21:07:04.706314) ========= 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=ca6c2627acea03e666270f68000001e0-1504151176.325908-188269164 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004493 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-30 21:07:04.789114) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-30 21:07:04.789502) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-30 21:07:04.789862) ========= ========= Total master_lag: 0.20 =========