builder: mozilla-central_ubuntu64_hw_styloseq_test-dromaeojs-e10s slave: talos-linux64-ix-053 starttime: 1503710049.12 results: success (0) revision: 31465a03c03d1eec31cd4dd5d6b803724dcb29cd ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-08-25 18:14:09.119672) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-08-25 18:14:09.120107) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-25 18:14:09.120376) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-25 18:14:09.136162) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-25 18:14:09.136498) ========= 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=920470bf432669ad7e3a5387000001ca-1503710048.144063-1391538418 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-08-25 18:14:09-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 45.8M=0s 2017-08-25 18:14:09 (45.8 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.346882 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-25 18:14:09.498483) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-25 18:14:09.501842) ========= 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=920470bf432669ad7e3a5387000001ca-1503710048.144063-1391538418 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020537 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-25 18:14:09.551218) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-25 18:14:09.554615) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 31465a03c03d1eec31cd4dd5d6b803724dcb29cd --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 31465a03c03d1eec31cd4dd5d6b803724dcb29cd --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=920470bf432669ad7e3a5387000001ca-1503710048.144063-1391538418 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-08-25 18:14:09,592 truncating revision to first 12 chars 2017-08-25 18:14:09,592 Setting DEBUG logging. 2017-08-25 18:14:09,592 attempt 1/10 2017-08-25 18:14:09,592 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/31465a03c03d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-08-25 18:14:10,045 unpacking tar archive at: mozilla-central-31465a03c03d/testing/mozharness/ program finished with exit code 0 elapsedTime=0.810976 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-25 18:14:10.380060) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-25 18:14:10.380356) ========= script_repo_revision: 31465a03c03d1eec31cd4dd5d6b803724dcb29cd ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-25 18:14:10.383726) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-25 18:14:10.383989) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-25 18:14:10.395757) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 53 secs) (at 2017-08-25 18:14:10.396015) ========= /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=920470bf432669ad7e3a5387000001ca-1503710048.144063-1391538418 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 18:14:10 INFO - MultiFileLogger online at 20170825 18:14:10 in /builds/slave/test 18:14:10 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 18:14:10 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:14:10 INFO - {'append_to_log': False, 18:14:10 INFO - 'base_work_dir': '/builds/slave/test', 18:14:10 INFO - 'blob_upload_branch': 'Firefox', 18:14:10 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:14:10 INFO - 'branch': 'Firefox', 18:14:10 INFO - 'buildbot_json_path': 'buildprops.json', 18:14:10 INFO - 'code_coverage': False, 18:14:10 INFO - 'config_files': ('talos/linux_config.py',), 18:14:10 INFO - 'default_actions': ('clobber', 18:14:10 INFO - 'read-buildbot-config', 18:14:10 INFO - 'download-and-extract', 18:14:10 INFO - 'populate-webroot', 18:14:10 INFO - 'create-virtualenv', 18:14:10 INFO - 'install', 18:14:10 INFO - 'setup-mitmproxy', 18:14:10 INFO - 'run-tests'), 18:14:10 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:14:10 INFO - 'disable_ccov_upload': False, 18:14:10 INFO - 'download_minidump_stackwalk': True, 18:14:10 INFO - 'download_symbols': 'ondemand', 18:14:10 INFO - 'enable_stylo': False, 18:14:10 INFO - 'enable_webrender': False, 18:14:10 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 18:14:10 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:14:10 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:14:10 INFO - 'gecko_profile': False, 18:14:10 INFO - 'gecko_profile_interval': 0, 18:14:10 INFO - 'installer_path': 'installer.exe', 18:14:10 INFO - 'log_level': 'info', 18:14:10 INFO - 'log_name': 'talos', 18:14:10 INFO - 'log_to_console': True, 18:14:10 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 18:14:10 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 18:14:10 INFO - 'opt_config_files': (), 18:14:10 INFO - 'pip_index': False, 18:14:10 INFO - 'suite': 'dromaeojs-e10s', 18:14:10 INFO - 'system_bits': '32', 18:14:10 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:14:10 INFO - 'title': 'talos-linux64-ix-053', 18:14:10 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:14:10 INFO - 'use_talos_json': True, 18:14:10 INFO - 'verify': 'False', 18:14:10 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:14:10 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:14:10 INFO - 'work_dir': 'build'} 18:14:10 INFO - [mozharness: 2017-08-26 01:14:10.572707Z] Running clobber step. 18:14:10 INFO - Running pre-action listener: _resource_record_pre_action 18:14:10 INFO - Running main action method: clobber 18:14:10 INFO - rmtree: /builds/slave/test/build 18:14:10 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:14:11 INFO - Running post-action listener: _resource_record_post_action 18:14:11 INFO - [mozharness: 2017-08-26 01:14:11.592196Z] Finished clobber step (success) 18:14:11 INFO - [mozharness: 2017-08-26 01:14:11.592293Z] Running read-buildbot-config step. 18:14:11 INFO - Running pre-action listener: _resource_record_pre_action 18:14:11 INFO - Running main action method: read_buildbot_config 18:14:11 INFO - Using buildbot properties: 18:14:11 INFO - { 18:14:11 INFO - "project": "", 18:14:11 INFO - "product": "firefox", 18:14:11 INFO - "who": "kwierso@gmail.com", 18:14:11 INFO - "installer_path": "public/build/target.tar.bz2", 18:14:11 INFO - "builddir": "mozilla-central_ubuntu64_hw_styloseq_test-dromaeojs-e10s", 18:14:11 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 18:14:11 INFO - "buildername": "Ubuntu HW 12.04 x64 stylo-sequential mozilla-central talos dromaeojs-e10s", 18:14:11 INFO - "stage_platform": "linux64-stylosequential", 18:14:11 INFO - "basedir": "/builds/slave/test", 18:14:11 INFO - "buildnumber": 49, 18:14:11 INFO - "platform": "ubuntu64_hw_styloseq", 18:14:11 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 18:14:11 INFO - "slavebuilddir": "test", 18:14:11 INFO - "taskId": "V4sf7700SmCtVV_UOpzZeA", 18:14:11 INFO - "branch": "mozilla-central", 18:14:11 INFO - "script_repo_revision": "production", 18:14:11 INFO - "revision": "31465a03c03d1eec31cd4dd5d6b803724dcb29cd", 18:14:11 INFO - "slavename": "talos-linux64-ix-053", 18:14:11 INFO - "repo_path": "mozilla-central" 18:14:11 INFO - } 18:14:11 INFO - Finding installer, test and symbols from parent task. 18:14:11 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/V4sf7700SmCtVV_UOpzZeA'}, attempt #1 18:14:12 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/V4sf7700SmCtVV_UOpzZeA'}, attempt #1 18:14:12 INFO - Task dependencies: DhX5PAXHThCBgtR6MhReYw 18:14:12 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DhX5PAXHThCBgtR6MhReYw'}, attempt #1 18:14:12 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/DhX5PAXHThCBgtR6MhReYw/artifacts/public/build/target.tar.bz2 18:14:12 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/DhX5PAXHThCBgtR6MhReYw/artifacts/public/build/target.test_packages.json 18:14:12 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/DhX5PAXHThCBgtR6MhReYw/artifacts/public/build/target.crashreporter-symbols.zip 18:14:12 INFO - Running post-action listener: _resource_record_post_action 18:14:12 INFO - [mozharness: 2017-08-26 01:14:12.949420Z] Finished read-buildbot-config step (success) 18:14:12 INFO - [mozharness: 2017-08-26 01:14:12.949727Z] Running download-and-extract step. 18:14:12 INFO - Running pre-action listener: _resource_record_pre_action 18:14:12 INFO - Running main action method: download_and_extract 18:14:12 INFO - mkdir: /builds/slave/test/build/tests 18:14:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:14:13 INFO - trying https://queue.taskcluster.net/v1/task/DhX5PAXHThCBgtR6MhReYw/artifacts/public/build/target.test_packages.json 18:14:13 INFO - Downloading https://queue.taskcluster.net/v1/task/DhX5PAXHThCBgtR6MhReYw/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 18:14:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DhX5PAXHThCBgtR6MhReYw/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 18:14:13 INFO - Downloaded 989 bytes. 18:14:13 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 18:14:13 INFO - Using the following test package requirements: 18:14:13 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 18:14:13 INFO - u'common': [u'target.common.tests.zip'], 18:14:13 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 18:14:13 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 18:14:13 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 18:14:13 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 18:14:13 INFO - u'mozbase': [u'target.common.tests.zip'], 18:14:13 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 18:14:13 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 18:14:13 INFO - u'web-platform': [u'target.common.tests.zip', 18:14:13 INFO - u'target.web-platform.tests.tar.gz'], 18:14:13 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 18:14:13 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 18:14:13 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DhX5PAXHThCBgtR6MhReYw/artifacts/public/build/target.common.tests.zip 18:14:13 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DhX5PAXHThCBgtR6MhReYw/artifacts/public/build/target.common.tests.zip'}, attempt #1 18:14:13 INFO - Fetch https://queue.taskcluster.net/v1/task/DhX5PAXHThCBgtR6MhReYw/artifacts/public/build/target.common.tests.zip into memory 18:14:14 INFO - Content-Length response header: 54979128 18:14:14 INFO - Bytes received: 54979128 18:14:25 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 18:14:25 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DhX5PAXHThCBgtR6MhReYw/artifacts/public/build/target.common.tests.zip 18:14:25 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DhX5PAXHThCBgtR6MhReYw/artifacts/public/build/target.common.tests.zip'}, attempt #1 18:14:25 INFO - Fetch https://queue.taskcluster.net/v1/task/DhX5PAXHThCBgtR6MhReYw/artifacts/public/build/target.common.tests.zip into memory 18:14:26 INFO - Content-Length response header: 54979128 18:14:26 INFO - Bytes received: 54979128 18:14:38 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DhX5PAXHThCBgtR6MhReYw/artifacts/public/build/target.talos.tests.zip 18:14:38 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DhX5PAXHThCBgtR6MhReYw/artifacts/public/build/target.talos.tests.zip'}, attempt #1 18:14:38 INFO - Fetch https://queue.taskcluster.net/v1/task/DhX5PAXHThCBgtR6MhReYw/artifacts/public/build/target.talos.tests.zip into memory 18:14:39 INFO - Content-Length response header: 14496611 18:14:39 INFO - Bytes received: 14496611 18:14:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:14:39 INFO - trying https://queue.taskcluster.net/v1/task/DhX5PAXHThCBgtR6MhReYw/artifacts/public/build/target.tar.bz2 18:14:39 INFO - Downloading https://queue.taskcluster.net/v1/task/DhX5PAXHThCBgtR6MhReYw/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 18:14:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DhX5PAXHThCBgtR6MhReYw/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 18:14:40 INFO - Downloaded 61145976 bytes. 18:14:40 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/DhX5PAXHThCBgtR6MhReYw/artifacts/public/build/target.tar.bz2 18:14:40 INFO - mkdir: /builds/slave/test/properties 18:14:40 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:14:40 INFO - Writing to file /builds/slave/test/properties/build_url 18:14:40 INFO - Contents: 18:14:40 INFO - build_url:https://queue.taskcluster.net/v1/task/DhX5PAXHThCBgtR6MhReYw/artifacts/public/build/target.tar.bz2 18:14:40 INFO - Running post-action listener: _resource_record_post_action 18:14:40 INFO - Running post-action listener: find_tests_for_verification 18:14:40 INFO - Running post-action listener: set_extra_try_arguments 18:14:40 INFO - [mozharness: 2017-08-26 01:14:40.465445Z] Finished download-and-extract step (success) 18:14:40 INFO - [mozharness: 2017-08-26 01:14:40.465543Z] Running populate-webroot step. 18:14:40 INFO - Running pre-action listener: _resource_record_pre_action 18:14:40 INFO - Running main action method: populate_webroot 18:14:40 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:14:40 INFO - 'chromez-stylo-e10s': {'talos_options': ['--stylo'], 18:14:40 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:14:40 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:14:40 INFO - 'dromaeojs-stylo-e10s': {'talos_options': ['--stylo'], 18:14:40 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:14:40 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 18:14:40 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:14:40 INFO - 'g1-stylo-e10s': {'pagesets_name': 'tp5n.zip', 18:14:40 INFO - 'talos_options': ['--stylo'], 18:14:40 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:14:40 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 18:14:40 INFO - 'tests': ['damp', 'tps']}, 18:14:40 INFO - 'g2-stylo-e10s': {'pagesets_name': 'tp5n.zip', 18:14:40 INFO - 'talos_options': ['--stylo'], 18:14:40 INFO - 'tests': ['damp', 'tps']}, 18:14:40 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:14:40 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 18:14:40 INFO - 'g4-stylo-e10s': {'talos_options': ['--stylo'], 18:14:40 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 18:14:40 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 18:14:40 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 18:14:40 INFO - 'g5-stylo-e10s': {'pagesets_name': 'tp5n.zip', 18:14:40 INFO - 'talos_options': ['--stylo'], 18:14:40 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 18:14:40 INFO - 'other-e10s': {'tests': ['a11yr', 18:14:40 INFO - 'ts_paint', 18:14:40 INFO - 'tpaint', 18:14:40 INFO - 'sessionrestore', 18:14:40 INFO - 'sessionrestore_many_windows', 18:14:40 INFO - 'sessionrestore_no_auto_restore', 18:14:40 INFO - 'tabpaint']}, 18:14:40 INFO - 'other-stylo-e10s': {'talos_options': ['--stylo'], 18:14:40 INFO - 'tests': ['a11yr', 18:14:40 INFO - 'ts_paint', 18:14:40 INFO - 'tpaint', 18:14:40 INFO - 'sessionrestore', 18:14:40 INFO - 'sessionrestore_many_windows', 18:14:40 INFO - 'sessionrestore_no_auto_restore', 18:14:40 INFO - 'tabpaint']}, 18:14:40 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 18:14:40 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 18:14:40 INFO - 'perf-reftest-singletons-stylo-e10s': {'talos_options': ['--stylo'], 18:14:40 INFO - 'tests': ['bloom_basic_singleton']}, 18:14:40 INFO - 'perf-reftest-stylo-e10s': {'talos_options': ['--stylo'], 18:14:40 INFO - 'tests': ['bloom_basic']}, 18:14:40 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:14:40 INFO - 'tsvgr_opacity', 18:14:40 INFO - 'tart', 18:14:40 INFO - 'tscrollx', 18:14:40 INFO - 'tsvg_static']}, 18:14:40 INFO - 'svgr-stylo-e10s': {'talos_options': ['--stylo'], 18:14:40 INFO - 'tests': ['tsvgx', 18:14:40 INFO - 'tsvgr_opacity', 18:14:40 INFO - 'tart', 18:14:40 INFO - 'tscrollx', 18:14:40 INFO - 'tsvg_static']}, 18:14:40 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 18:14:40 INFO - 'tp5o-stylo-e10s': {'pagesets_name': 'tp5n.zip', 18:14:40 INFO - 'talos_options': ['--stylo'], 18:14:40 INFO - 'tests': ['tp5o']}, 18:14:40 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 18:14:40 INFO - 'talos_options': ['--mitmproxy', 18:14:40 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 18:14:40 INFO - '--firstNonBlankPaint'], 18:14:40 INFO - 'tests': ['tp6_google', 18:14:40 INFO - 'tp6_youtube', 18:14:40 INFO - 'tp6_amazon', 18:14:40 INFO - 'tp6_facebook']}, 18:14:40 INFO - 'tp6-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 18:14:40 INFO - 'talos_options': ['--stylo', 18:14:40 INFO - '--mitmproxy', 18:14:40 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 18:14:40 INFO - '--firstNonBlankPaint'], 18:14:40 INFO - 'tests': ['tp6_google', 18:14:40 INFO - 'tp6_youtube', 18:14:40 INFO - 'tp6_amazon', 18:14:40 INFO - 'tp6_facebook']}, 18:14:40 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 18:14:40 INFO - 'talos_options': ['--stylo', 18:14:40 INFO - '--stylo-threads=1', 18:14:40 INFO - '--mitmproxy', 18:14:40 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 18:14:40 INFO - '--firstNonBlankPaint'], 18:14:40 INFO - 'tests': ['tp6_google', 18:14:40 INFO - 'tp6_youtube', 18:14:40 INFO - 'tp6_amazon', 18:14:40 INFO - 'tp6_facebook']}, 18:14:40 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 18:14:40 INFO - 'talos_options': ['--xperf_path', 18:14:40 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:14:40 INFO - 'tests': ['tp5n']}, 18:14:40 INFO - 'xperf-stylo-e10s': {'pagesets_name': 'tp5n.zip', 18:14:40 INFO - 'talos_options': ['--stylo', 18:14:40 INFO - '--xperf_path', 18:14:40 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:14:40 INFO - 'tests': ['tp5n']}}} 18:14:40 INFO - Running post-action listener: _resource_record_post_action 18:14:40 INFO - [mozharness: 2017-08-26 01:14:40.479688Z] Finished populate-webroot step (success) 18:14:40 INFO - [mozharness: 2017-08-26 01:14:40.479777Z] Running create-virtualenv step. 18:14:40 INFO - Running pre-action listener: _resource_record_pre_action 18:14:40 INFO - Running main action method: create_virtualenv 18:14:40 INFO - Creating virtualenv /builds/slave/test/build/venv 18:14:40 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 18:14:40 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 18:14:40 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 18:14:40 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:14:40 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:14:41 INFO - Installing distribute.............................................................................................................................................................................................done. 18:14:41 INFO - Installing pip...............done. 18:14:41 INFO - Return code: 0 18:14:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 18:14:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 18:14:41 INFO - Reading from file tmpfile_stdout 18:14:41 INFO - Output received: 18:14:41 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 18:14:41 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:14:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:14:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:14:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:14:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:14:41 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 0x1d171f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ee2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2085f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2083130>, '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 0x2084030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20844c0>, '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': '920470bf432669ad7e3a5387000001ca-1503710048.144063-1391538418', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:14:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 18:14:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 18:14:41 INFO - Using env: {'DISPLAY': ':0', 18:14:41 INFO - 'HOME': '/home/cltbld', 18:14:41 INFO - 'LANG': 'en_US.UTF-8', 18:14:41 INFO - 'LANGUAGE': 'en_US:en', 18:14:41 INFO - 'LOGNAME': 'cltbld', 18:14:41 INFO - 'MAIL': '/var/mail/cltbld', 18:14:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:14:41 INFO - 'MOZ_NO_REMOTE': '1', 18:14:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:14:41 INFO - 'NO_EM_RESTART': '1', 18:14:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:14:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:14:41 INFO - 'PWD': '/builds/slave/test', 18:14:41 INFO - 'SHELL': '/bin/bash', 18:14:41 INFO - 'SHLVL': '1', 18:14:41 INFO - 'TERM': 'linux', 18:14:41 INFO - 'TMOUT': '86400', 18:14:41 INFO - 'USER': 'cltbld', 18:14:41 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1503710048.144063-1391538418', 18:14:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:14:41 INFO - '_': '/tools/buildbot/bin/python'} 18:14:42 INFO - Ignoring indexes: http://pypi.python.org/simple/ 18:14:42 INFO - Downloading/unpacking pip>=1.5 18:14:42 INFO - Running setup.py egg_info for package pip 18:14:42 INFO - warning: no files found matching 'pip/cacert.pem' 18:14:42 INFO - warning: no files found matching '*.html' under directory 'docs' 18:14:42 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 18:14:42 INFO - no previously-included directories found matching 'docs/_build/_sources' 18:14:42 INFO - Installing collected packages: pip 18:14:42 INFO - Found existing installation: pip 1.1 18:14:42 INFO - Uninstalling pip: 18:14:42 INFO - Successfully uninstalled pip 18:14:42 INFO - Running setup.py install for pip 18:14:42 INFO - warning: no files found matching 'pip/cacert.pem' 18:14:42 INFO - warning: no files found matching '*.html' under directory 'docs' 18:14:42 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 18:14:42 INFO - no previously-included directories found matching 'docs/_build/_sources' 18:14:42 INFO - Installing pip script to /builds/slave/test/build/venv/bin 18:14:42 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 18:14:42 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 18:14:42 INFO - Successfully installed pip 18:14:42 INFO - Cleaning up... 18:14:42 INFO - Return code: 0 18:14:42 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:14:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:14:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:14:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:14:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:14:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1d171f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ee2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2085f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2083130>, '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 0x2084030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20844c0>, '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': '920470bf432669ad7e3a5387000001ca-1503710048.144063-1391538418', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:14:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 18:14:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 18:14:42 INFO - Using env: {'DISPLAY': ':0', 18:14:42 INFO - 'HOME': '/home/cltbld', 18:14:42 INFO - 'LANG': 'en_US.UTF-8', 18:14:42 INFO - 'LANGUAGE': 'en_US:en', 18:14:42 INFO - 'LOGNAME': 'cltbld', 18:14:42 INFO - 'MAIL': '/var/mail/cltbld', 18:14:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:14:42 INFO - 'MOZ_NO_REMOTE': '1', 18:14:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:14:42 INFO - 'NO_EM_RESTART': '1', 18:14:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:14:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:14:42 INFO - 'PWD': '/builds/slave/test', 18:14:42 INFO - 'SHELL': '/bin/bash', 18:14:42 INFO - 'SHLVL': '1', 18:14:42 INFO - 'TERM': 'linux', 18:14:42 INFO - 'TMOUT': '86400', 18:14:42 INFO - 'USER': 'cltbld', 18:14:42 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1503710048.144063-1391538418', 18:14:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:14:42 INFO - '_': '/tools/buildbot/bin/python'} 18:14:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:14:42 INFO - Downloading/unpacking psutil>=3.1.1 18:14:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:14:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:14:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:14:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:14:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:14:44 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:14:44 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:14:44 INFO - Installing collected packages: psutil 18:14:44 INFO - Running setup.py install for psutil 18:14:44 INFO - building 'psutil._psutil_linux' extension 18:14:44 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 18:14:44 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 18:14:44 INFO - building 'psutil._psutil_posix' extension 18:14:44 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 18:14:44 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 18:14:44 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:14:44 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:14:44 INFO - Successfully installed psutil 18:14:44 INFO - Cleaning up... 18:14:44 INFO - Return code: 0 18:14:44 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:14:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:14:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:14:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:14:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:14:44 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 0x1d171f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ee2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2085f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2083130>, '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 0x2084030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20844c0>, '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': '920470bf432669ad7e3a5387000001ca-1503710048.144063-1391538418', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:14:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 18:14:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 18:14:44 INFO - Using env: {'DISPLAY': ':0', 18:14:44 INFO - 'HOME': '/home/cltbld', 18:14:44 INFO - 'LANG': 'en_US.UTF-8', 18:14:44 INFO - 'LANGUAGE': 'en_US:en', 18:14:44 INFO - 'LOGNAME': 'cltbld', 18:14:44 INFO - 'MAIL': '/var/mail/cltbld', 18:14:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:14:44 INFO - 'MOZ_NO_REMOTE': '1', 18:14:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:14:44 INFO - 'NO_EM_RESTART': '1', 18:14:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:14:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:14:44 INFO - 'PWD': '/builds/slave/test', 18:14:44 INFO - 'SHELL': '/bin/bash', 18:14:44 INFO - 'SHLVL': '1', 18:14:44 INFO - 'TERM': 'linux', 18:14:44 INFO - 'TMOUT': '86400', 18:14:44 INFO - 'USER': 'cltbld', 18:14:44 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1503710048.144063-1391538418', 18:14:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:14:44 INFO - '_': '/tools/buildbot/bin/python'} 18:14:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:14:44 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:14:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:14:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:14:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:14:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:14:45 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:14:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:14:45 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:14:45 INFO - Installing collected packages: mozsystemmonitor 18:14:45 INFO - Running setup.py install for mozsystemmonitor 18:14:46 INFO - Successfully installed mozsystemmonitor 18:14:46 INFO - Cleaning up... 18:14:46 INFO - Return code: 0 18:14:46 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:14:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:14:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:14:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:14:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:14:46 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 0x1d171f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ee2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2085f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2083130>, '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 0x2084030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20844c0>, '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': '920470bf432669ad7e3a5387000001ca-1503710048.144063-1391538418', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:14:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 18:14:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 18:14:46 INFO - Using env: {'DISPLAY': ':0', 18:14:46 INFO - 'HOME': '/home/cltbld', 18:14:46 INFO - 'LANG': 'en_US.UTF-8', 18:14:46 INFO - 'LANGUAGE': 'en_US:en', 18:14:46 INFO - 'LOGNAME': 'cltbld', 18:14:46 INFO - 'MAIL': '/var/mail/cltbld', 18:14:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:14:46 INFO - 'MOZ_NO_REMOTE': '1', 18:14:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:14:46 INFO - 'NO_EM_RESTART': '1', 18:14:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:14:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:14:46 INFO - 'PWD': '/builds/slave/test', 18:14:46 INFO - 'SHELL': '/bin/bash', 18:14:46 INFO - 'SHLVL': '1', 18:14:46 INFO - 'TERM': 'linux', 18:14:46 INFO - 'TMOUT': '86400', 18:14:46 INFO - 'USER': 'cltbld', 18:14:46 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1503710048.144063-1391538418', 18:14:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:14:46 INFO - '_': '/tools/buildbot/bin/python'} 18:14:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:14:46 INFO - Downloading/unpacking jsonschema==2.5.1 18:14:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:14:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:14:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:14:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:14:47 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:14:47 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:14:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:14:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:14:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:14:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:14:47 INFO - Downloading functools32-3.2.3-2.tar.gz 18:14:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:14:47 INFO - warning: no files found matching '*.txt' 18:14:47 INFO - no previously-included directories found matching 'build' 18:14:47 INFO - no previously-included directories found matching 'dist' 18:14:47 INFO - no previously-included directories found matching '.git*' 18:14:47 INFO - Installing collected packages: jsonschema, functools32 18:14:47 INFO - Running setup.py install for functools32 18:14:47 INFO - warning: no files found matching '*.txt' 18:14:47 INFO - no previously-included directories found matching 'build' 18:14:47 INFO - no previously-included directories found matching 'dist' 18:14:47 INFO - no previously-included directories found matching '.git*' 18:14:47 INFO - Successfully installed jsonschema functools32 18:14:47 INFO - Cleaning up... 18:14:47 INFO - Return code: 0 18:14:47 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:14: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')]} 18:14:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:14: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')]} 18:14:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:14:47 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 0x1d171f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ee2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2085f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2083130>, '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 0x2084030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20844c0>, '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': '920470bf432669ad7e3a5387000001ca-1503710048.144063-1391538418', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:14:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 18:14:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 18:14:47 INFO - Using env: {'DISPLAY': ':0', 18:14:47 INFO - 'HOME': '/home/cltbld', 18:14:47 INFO - 'LANG': 'en_US.UTF-8', 18:14:47 INFO - 'LANGUAGE': 'en_US:en', 18:14:47 INFO - 'LOGNAME': 'cltbld', 18:14:47 INFO - 'MAIL': '/var/mail/cltbld', 18:14:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:14:47 INFO - 'MOZ_NO_REMOTE': '1', 18:14:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:14:47 INFO - 'NO_EM_RESTART': '1', 18:14:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:14:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:14:47 INFO - 'PWD': '/builds/slave/test', 18:14:47 INFO - 'SHELL': '/bin/bash', 18:14:47 INFO - 'SHLVL': '1', 18:14:47 INFO - 'TERM': 'linux', 18:14:47 INFO - 'TMOUT': '86400', 18:14:47 INFO - 'USER': 'cltbld', 18:14:47 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1503710048.144063-1391538418', 18:14:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:14:47 INFO - '_': '/tools/buildbot/bin/python'} 18:14:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:14:48 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:14:48 INFO - Cleaning up... 18:14:48 INFO - Return code: 0 18:14:48 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:14:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:14:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:14:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:14:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:14:48 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 0x1d171f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ee2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2085f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2083130>, '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 0x2084030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20844c0>, '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': '920470bf432669ad7e3a5387000001ca-1503710048.144063-1391538418', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:14:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 18:14:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 18:14:48 INFO - Using env: {'DISPLAY': ':0', 18:14:48 INFO - 'HOME': '/home/cltbld', 18:14:48 INFO - 'LANG': 'en_US.UTF-8', 18:14:48 INFO - 'LANGUAGE': 'en_US:en', 18:14:48 INFO - 'LOGNAME': 'cltbld', 18:14:48 INFO - 'MAIL': '/var/mail/cltbld', 18:14:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:14:48 INFO - 'MOZ_NO_REMOTE': '1', 18:14:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:14:48 INFO - 'NO_EM_RESTART': '1', 18:14:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:14:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:14:48 INFO - 'PWD': '/builds/slave/test', 18:14:48 INFO - 'SHELL': '/bin/bash', 18:14:48 INFO - 'SHLVL': '1', 18:14:48 INFO - 'TERM': 'linux', 18:14:48 INFO - 'TMOUT': '86400', 18:14:48 INFO - 'USER': 'cltbld', 18:14:48 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1503710048.144063-1391538418', 18:14:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:14:48 INFO - '_': '/tools/buildbot/bin/python'} 18:14:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:14:48 INFO - Downloading/unpacking blobuploader==1.2.4 18:14:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:14:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:14:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:14:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:14:49 INFO - Downloading blobuploader-1.2.4.tar.gz 18:14:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:14:49 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:14:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:14:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:14:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:14:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:14:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:14:49 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:14:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:14:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:14:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:14:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:14:49 INFO - Downloading docopt-0.6.1.tar.gz 18:14:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:14:49 INFO - Installing collected packages: blobuploader, requests, docopt 18:14:49 INFO - Running setup.py install for blobuploader 18:14:50 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:14:50 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:14:50 INFO - Running setup.py install for requests 18:14:50 INFO - Running setup.py install for docopt 18:14:50 INFO - Successfully installed blobuploader requests docopt 18:14:50 INFO - Cleaning up... 18:14:50 INFO - Return code: 0 18:14:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:14:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:14:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:14:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:14:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:14:50 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 0x1d171f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ee2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2085f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2083130>, '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 0x2084030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20844c0>, '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': '920470bf432669ad7e3a5387000001ca-1503710048.144063-1391538418', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:14:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 18:14:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:14:50 INFO - Using env: {'DISPLAY': ':0', 18:14:50 INFO - 'HOME': '/home/cltbld', 18:14:50 INFO - 'LANG': 'en_US.UTF-8', 18:14:50 INFO - 'LANGUAGE': 'en_US:en', 18:14:50 INFO - 'LOGNAME': 'cltbld', 18:14:50 INFO - 'MAIL': '/var/mail/cltbld', 18:14:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:14:50 INFO - 'MOZ_NO_REMOTE': '1', 18:14:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:14:50 INFO - 'NO_EM_RESTART': '1', 18:14:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:14:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:14:50 INFO - 'PWD': '/builds/slave/test', 18:14:50 INFO - 'SHELL': '/bin/bash', 18:14:50 INFO - 'SHLVL': '1', 18:14:50 INFO - 'TERM': 'linux', 18:14:50 INFO - 'TMOUT': '86400', 18:14:50 INFO - 'USER': 'cltbld', 18:14:50 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1503710048.144063-1391538418', 18:14:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:14:50 INFO - '_': '/tools/buildbot/bin/python'} 18:14:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:14:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:14:50 INFO - Running setup.py (path:/tmp/pip-fRxsFH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:14:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:14:50 INFO - Running setup.py (path:/tmp/pip-6GUiUD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:14:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:14:50 INFO - Running setup.py (path:/tmp/pip-_n6Azh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:14:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:14:50 INFO - Running setup.py (path:/tmp/pip-gIFkYH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:14:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:14:51 INFO - Running setup.py (path:/tmp/pip-bgAbVh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:14:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:14:51 INFO - Running setup.py (path:/tmp/pip-qmhWne-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:14:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:14:51 INFO - Running setup.py (path:/tmp/pip-RDCm65-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:14:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:14:51 INFO - Running setup.py (path:/tmp/pip-UMFFE0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:14:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:14:51 INFO - Running setup.py (path:/tmp/pip-PGLf3r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:14:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:14:51 INFO - Running setup.py (path:/tmp/pip-4ihszZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:14:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:14:51 INFO - Running setup.py (path:/tmp/pip-HydN36-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:14:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:14:51 INFO - Running setup.py (path:/tmp/pip-RFeuSQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:14:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:14:51 INFO - Running setup.py (path:/tmp/pip-H1UNYv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:14:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:14:51 INFO - Running setup.py (path:/tmp/pip-GK2eo5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:14:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:14:51 INFO - Running setup.py (path:/tmp/pip-T_7CDh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:14:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:14:51 INFO - Running setup.py (path:/tmp/pip-p2PuoN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:14:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:14:51 INFO - Running setup.py (path:/tmp/pip-kD_gXP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:14:51 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:14:51 INFO - Running setup.py install for manifestparser 18:14:52 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:14:52 INFO - Running setup.py install for mozcrash 18:14:52 INFO - Running setup.py install for mozdebug 18:14:52 INFO - Running setup.py install for mozdevice 18:14:52 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:14:52 INFO - Running setup.py install for mozfile 18:14:52 INFO - Running setup.py install for mozhttpd 18:14:52 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:14:52 INFO - Running setup.py install for mozinfo 18:14:52 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:14:52 INFO - Running setup.py install for mozInstall 18:14:52 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:14:52 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:14:52 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:14:52 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:14:52 INFO - Running setup.py install for mozleak 18:14:52 INFO - Running setup.py install for mozlog 18:14:52 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:14:52 INFO - Running setup.py install for moznetwork 18:14:53 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:14:53 INFO - Running setup.py install for mozprocess 18:14:53 INFO - Running setup.py install for mozprofile 18:14:53 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:14:53 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:14:53 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:14:53 INFO - Running setup.py install for mozrunner 18:14:53 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:14:53 INFO - Running setup.py install for mozscreenshot 18:14:53 INFO - Running setup.py install for moztest 18:14:53 INFO - Running setup.py install for mozversion 18:14:53 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:14:53 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:14:53 INFO - Cleaning up... 18:14:53 INFO - Return code: 0 18:14:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:14: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')]} 18:14:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:14: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')]} 18:14:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:14:53 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 0x1d171f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ee2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2085f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2083130>, '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 0x2084030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20844c0>, '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': '920470bf432669ad7e3a5387000001ca-1503710048.144063-1391538418', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:14:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 18:14:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:14:53 INFO - Using env: {'DISPLAY': ':0', 18:14:53 INFO - 'HOME': '/home/cltbld', 18:14:53 INFO - 'LANG': 'en_US.UTF-8', 18:14:53 INFO - 'LANGUAGE': 'en_US:en', 18:14:53 INFO - 'LOGNAME': 'cltbld', 18:14:53 INFO - 'MAIL': '/var/mail/cltbld', 18:14:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:14:53 INFO - 'MOZ_NO_REMOTE': '1', 18:14:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:14:53 INFO - 'NO_EM_RESTART': '1', 18:14:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:14:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:14:53 INFO - 'PWD': '/builds/slave/test', 18:14:53 INFO - 'SHELL': '/bin/bash', 18:14:53 INFO - 'SHLVL': '1', 18:14:53 INFO - 'TERM': 'linux', 18:14:53 INFO - 'TMOUT': '86400', 18:14:53 INFO - 'USER': 'cltbld', 18:14:53 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1503710048.144063-1391538418', 18:14:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:14:53 INFO - '_': '/tools/buildbot/bin/python'} 18:14:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:14:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:14:53 INFO - Running setup.py (path:/tmp/pip-e0TP_e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:14:53 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 18:14:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:14:53 INFO - Running setup.py (path:/tmp/pip-rgledr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:14:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:14:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:14:54 INFO - Running setup.py (path:/tmp/pip-UEUGhD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:14:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 18:14:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:14:54 INFO - Running setup.py (path:/tmp/pip-j2El6N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:14:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:14:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:14:54 INFO - Running setup.py (path:/tmp/pip-aupuwx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:14:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 18:14:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:14:54 INFO - Running setup.py (path:/tmp/pip-jBVyc4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:14:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 18:14:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:14:54 INFO - Running setup.py (path:/tmp/pip-3XUtSI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:14:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 18:14:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:14:54 INFO - Running setup.py (path:/tmp/pip-OxKfo8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:14:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.13 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 18:14:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:14:54 INFO - Running setup.py (path:/tmp/pip-yBBjcP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:14:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 18:14:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:14:54 INFO - Running setup.py (path:/tmp/pip-9pUatE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:14:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.5 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:14:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:14:54 INFO - Running setup.py (path:/tmp/pip-TierhK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:14:54 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 18:14:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:14:54 INFO - Running setup.py (path:/tmp/pip-MzWhah-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:14:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 18:14:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:14:54 INFO - Running setup.py (path:/tmp/pip-ERkCLH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:14:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 18:14:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:14:54 INFO - Running setup.py (path:/tmp/pip-P0V5PU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:14:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 18:14:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:14:54 INFO - Running setup.py (path:/tmp/pip-8qxiLv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:14:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 18:14:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:14:55 INFO - Running setup.py (path:/tmp/pip-3ziQQy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:14:55 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 18:14:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:14:55 INFO - Running setup.py (path:/tmp/pip-fBvM_o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:14:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 18:14:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:14:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:14:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 18:14:55 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:14:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:14:55 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.13->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 18:14:55 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:14: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 18:14: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 18:14: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 18:14: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 18:14:56 INFO - Downloading blessings-1.6.tar.gz 18:14:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:14:56 INFO - Installing collected packages: blessings 18:14:56 INFO - Running setup.py install for blessings 18:14:56 INFO - Successfully installed blessings 18:14:56 INFO - Cleaning up... 18:14:56 INFO - Return code: 0 18:14:56 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:14:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:14:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:14:56 INFO - Reading from file tmpfile_stdout 18:14:56 INFO - Current package versions: 18:14:56 INFO - argparse == 1.2.1 18:14:56 INFO - blessings == 1.6 18:14:56 INFO - blobuploader == 1.2.4 18:14:56 INFO - docopt == 0.6.1 18:14:56 INFO - functools32 == 3.2.3-2 18:14:56 INFO - jsonschema == 2.5.1 18:14:56 INFO - manifestparser == 1.1 18:14:56 INFO - mozInstall == 1.13 18:14:56 INFO - mozcrash == 1.0 18:14:56 INFO - mozdebug == 0.1 18:14:56 INFO - mozdevice == 0.51 18:14:56 INFO - mozfile == 1.2 18:14:56 INFO - mozhttpd == 0.7 18:14:56 INFO - mozinfo == 0.10 18:14:56 INFO - mozleak == 0.1 18:14:56 INFO - mozlog == 3.5 18:14:56 INFO - moznetwork == 0.27 18:14:56 INFO - mozprocess == 0.25 18:14:56 INFO - mozprofile == 0.28 18:14:56 INFO - mozrunner == 6.13 18:14:56 INFO - mozscreenshot == 0.1 18:14:56 INFO - mozsystemmonitor == 0.3 18:14:56 INFO - moztest == 0.8 18:14:56 INFO - mozversion == 1.4 18:14:56 INFO - psutil == 3.1.1 18:14:56 INFO - requests == 1.2.3 18:14:56 INFO - wsgiref == 0.1.2 18:14:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:14: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')]} 18:14:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:14: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')]} 18:14:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:14:56 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 0x1d171f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ee2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2085f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2083130>, '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 0x2084030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20844c0>, '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': '920470bf432669ad7e3a5387000001ca-1503710048.144063-1391538418', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:14:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 18:14:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:14:56 INFO - Using env: {'DISPLAY': ':0', 18:14:56 INFO - 'HOME': '/home/cltbld', 18:14:56 INFO - 'LANG': 'en_US.UTF-8', 18:14:56 INFO - 'LANGUAGE': 'en_US:en', 18:14:56 INFO - 'LOGNAME': 'cltbld', 18:14:56 INFO - 'MAIL': '/var/mail/cltbld', 18:14:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:14:56 INFO - 'MOZ_NO_REMOTE': '1', 18:14:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:14:56 INFO - 'NO_EM_RESTART': '1', 18:14:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:14:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:14:56 INFO - 'PWD': '/builds/slave/test', 18:14:56 INFO - 'SHELL': '/bin/bash', 18:14:56 INFO - 'SHLVL': '1', 18:14:56 INFO - 'TERM': 'linux', 18:14:56 INFO - 'TMOUT': '86400', 18:14:56 INFO - 'USER': 'cltbld', 18:14:56 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1503710048.144063-1391538418', 18:14:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:14:56 INFO - '_': '/tools/buildbot/bin/python'} 18:14:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:14:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 18:14:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 18:14:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 18:14:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 18:14:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 18:14:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 18:14:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 18:14:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 18:14:56 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 18:14:56 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 18:14: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 18:14: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 18:14: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 18:14: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 18:14:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 18:14:58 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 18:14:58 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 18:14:58 INFO - Installing collected packages: simplejson 18:14:58 INFO - Running setup.py install for simplejson 18:14:58 INFO - building 'simplejson._speedups' extension 18:14:58 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c simplejson/_speedups.c -o build/temp.linux-x86_64-2.7/simplejson/_speedups.o 18:14:58 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/simplejson/_speedups.o -o build/lib.linux-x86_64-2.7/simplejson/_speedups.so 18:14:59 INFO - Successfully installed simplejson 18:14:59 INFO - Cleaning up... 18:14:59 INFO - Return code: 0 18:14:59 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:14:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:14:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:14:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:14:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:14:59 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d171f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ee2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2085f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2083130>, '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 0x2084030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20844c0>, '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': '920470bf432669ad7e3a5387000001ca-1503710048.144063-1391538418', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:14:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 18:14:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 18:14:59 INFO - Using env: {'DISPLAY': ':0', 18:14:59 INFO - 'HOME': '/home/cltbld', 18:14:59 INFO - 'LANG': 'en_US.UTF-8', 18:14:59 INFO - 'LANGUAGE': 'en_US:en', 18:14:59 INFO - 'LOGNAME': 'cltbld', 18:14:59 INFO - 'MAIL': '/var/mail/cltbld', 18:14:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:14:59 INFO - 'MOZ_NO_REMOTE': '1', 18:14:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:14:59 INFO - 'NO_EM_RESTART': '1', 18:14:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:14:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:14:59 INFO - 'PWD': '/builds/slave/test', 18:14:59 INFO - 'SHELL': '/bin/bash', 18:14:59 INFO - 'SHLVL': '1', 18:14:59 INFO - 'TERM': 'linux', 18:14:59 INFO - 'TMOUT': '86400', 18:14:59 INFO - 'USER': 'cltbld', 18:14:59 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1503710048.144063-1391538418', 18:14:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:14:59 INFO - '_': '/tools/buildbot/bin/python'} 18:14:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:14:59 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:14:59 INFO - Cleaning up... 18:14:59 INFO - Return code: 0 18:14:59 INFO - Running post-action listener: _resource_record_post_action 18:14:59 INFO - Running post-action listener: _start_resource_monitoring 18:14:59 INFO - Starting resource monitoring. 18:14:59 INFO - [mozharness: 2017-08-26 01:14:59.377789Z] Finished create-virtualenv step (success) 18:14:59 INFO - [mozharness: 2017-08-26 01:14:59.378311Z] Running install step. 18:14:59 INFO - Running pre-action listener: _resource_record_pre_action 18:14:59 INFO - Running main action method: install 18:14:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:14:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:14:59 INFO - Reading from file tmpfile_stdout 18:14:59 INFO - Detecting whether we're running mozinstall >=1.0... 18:14:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:14:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:14:59 INFO - Reading from file tmpfile_stdout 18:14:59 INFO - Output received: 18:14:59 INFO - Usage: mozinstall [options] installer 18:14:59 INFO - Options: 18:14:59 INFO - -h, --help show this help message and exit 18:14:59 INFO - -d DEST, --destination=DEST 18:14:59 INFO - Directory to install application into. [default: 18:14:59 INFO - "/builds/slave/test"] 18:14:59 INFO - --app=APP Application being installed. [default: firefox] 18:14:59 INFO - mkdir: /builds/slave/test/build/application 18:14:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 18:14:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 18:15:17 INFO - Reading from file tmpfile_stdout 18:15:17 INFO - Output received: 18:15:17 INFO - /builds/slave/test/build/application/firefox/firefox 18:15:17 INFO - Running post-action listener: _resource_record_post_action 18:15:17 INFO - [mozharness: 2017-08-26 01:15:17.414989Z] Finished install step (success) 18:15:17 INFO - [mozharness: 2017-08-26 01:15:17.415240Z] Running setup-mitmproxy step. 18:15:17 INFO - Running pre-action listener: _resource_record_pre_action 18:15:17 INFO - Running main action method: setup_mitmproxy 18:15:17 INFO - Skipping: mitmproxy is not required 18:15:17 INFO - Running post-action listener: _resource_record_post_action 18:15:17 INFO - [mozharness: 2017-08-26 01:15:17.416486Z] Finished setup-mitmproxy step (success) 18:15:17 INFO - [mozharness: 2017-08-26 01:15:17.416716Z] Running run-tests step. 18:15:17 INFO - Running pre-action listener: _resource_record_pre_action 18:15:17 INFO - Running pre-action listener: _set_gcov_prefix 18:15:17 INFO - Running main action method: run_tests 18:15:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:15:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:15:17 INFO - Python 2.7.3 18:15:17 INFO - Return code: 0 18:15:17 INFO - grabbing minidump binary from tooltool 18:15:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:15:17 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 0x2083130>, '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 0x2084030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20844c0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:15:17 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 18:15:17 INFO - Copy/paste: /tools/tooltool.py --url https://tooltool.mozilla-releng.net/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 18:15:17 INFO - Calling ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 18:15:17 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:15:17 INFO - Return code: 0 18:15:17 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 18:15:17 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:15:17 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:15:17 INFO - ENV: RUST_BACKTRACE is now full 18:15:17 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 18:15:17 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:15:17 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/DhX5PAXHThCBgtR6MhReYw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-053', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 18:15:17 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/DhX5PAXHThCBgtR6MhReYw/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-053 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 18:15:17 INFO - Using env: {'DISPLAY': ':0', 18:15:17 INFO - 'HOME': '/home/cltbld', 18:15:17 INFO - 'LANG': 'en_US.UTF-8', 18:15:17 INFO - 'LANGUAGE': 'en_US:en', 18:15:17 INFO - 'LOGNAME': 'cltbld', 18:15:17 INFO - 'MAIL': '/var/mail/cltbld', 18:15:17 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:15:17 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:15:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:15:17 INFO - 'MOZ_NO_REMOTE': '1', 18:15:17 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:15:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:15:17 INFO - 'NO_EM_RESTART': '1', 18:15:17 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:15:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:15:17 INFO - 'PWD': '/builds/slave/test', 18:15:17 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:15:17 INFO - 'RUST_BACKTRACE': 'full', 18:15:17 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 18:15:17 INFO - 'SHELL': '/bin/bash', 18:15:17 INFO - 'SHLVL': '1', 18:15:17 INFO - 'STYLO_FORCE_ENABLED': '1', 18:15:17 INFO - 'TERM': 'linux', 18:15:17 INFO - 'TMOUT': '86400', 18:15:17 INFO - 'USER': 'cltbld', 18:15:17 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1503710048.144063-1391538418', 18:15:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:15:17 INFO - '_': '/tools/buildbot/bin/python'} 18:15:17 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/DhX5PAXHThCBgtR6MhReYw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-053', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 18:15:17 INFO - mozversion application_buildid: 20170826002533 18:15:17 INFO - mozversion application_changeset: 31465a03c03d1eec31cd4dd5d6b803724dcb29cd 18:15:17 INFO - mozversion application_display_name: Nightly 18:15:17 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:15:17 INFO - mozversion application_name: Firefox 18:15:17 INFO - mozversion application_remotingname: firefox 18:15:17 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 18:15:17 INFO - mozversion application_vendor: Mozilla 18:15:17 INFO - mozversion application_version: 57.0a1 18:15:17 INFO - mozversion platform_buildid: 20170826002533 18:15:17 INFO - mozversion platform_changeset: 31465a03c03d1eec31cd4dd5d6b803724dcb29cd 18:15:17 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 18:15:17 INFO - mozversion platform_version: 57.0a1 18:15:17 INFO - using testdate: 1503710117 18:15:17 INFO - actual date: 1503710117 18:15:17 INFO - starting webserver on 'localhost:45432' 18:15:17 INFO - SUITE-START | Running 2 tests 18:15:17 INFO - TEST-START | dromaeo_css 18:15:17 INFO - Initialising browser for dromaeo_css test... 18:15:17 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:45432/getInfo.html -profile /tmp/tmpv7sMoX/profile 18:15:17 INFO - TEST-INFO | started process 22186 (/builds/slave/test/build/application/firefox/firefox http://localhost:45432/getInfo.html) 18:15:23 INFO - TEST-INFO | 22186: exit 0 18:15:23 INFO - Browser initialized. 18:15:23 INFO - Running cycle 1/1 for dromaeo_css test... 18:15:23 INFO - Using env: {'DISPLAY': ':0', 18:15:23 INFO - 'HOME': '/home/cltbld', 18:15:23 INFO - 'JSGC_DISABLE_POISONING': '1', 18:15:23 INFO - 'LANG': 'en_US.UTF-8', 18:15:23 INFO - 'LANGUAGE': 'en_US:en', 18:15:23 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 18:15:23 INFO - 'LOGNAME': 'cltbld', 18:15:23 INFO - 'MAIL': '/var/mail/cltbld', 18:15:23 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:15:23 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:15:23 INFO - 'MOZ_CRASHREPORTER': '1', 18:15:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:15:23 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 18:15:23 INFO - 'MOZ_NO_REMOTE': '1', 18:15:23 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:15:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:15:23 INFO - 'NO_EM_RESTART': '1', 18:15:23 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:15:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:15:23 INFO - 'PWD': '/builds/slave/test', 18:15:23 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:15:23 INFO - 'RUST_BACKTRACE': 'full', 18:15:23 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 18:15:23 INFO - 'SHELL': '/bin/bash', 18:15:23 INFO - 'SHLVL': '1', 18:15:23 INFO - 'STYLO_FORCE_ENABLED': '1', 18:15:23 INFO - 'TERM': 'linux', 18:15:23 INFO - 'TMOUT': '86400', 18:15:23 INFO - 'USER': 'cltbld', 18:15:23 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1503710048.144063-1391538418', 18:15:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:15:23 INFO - '_': '/tools/buildbot/bin/python'} 18:15:23 INFO - TEST-INFO | started process 22369 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpv7sMoX/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 18:15:24 INFO - PID 22369 | 18:15:24 INFO - PID 22369 | (/builds/slave/test/build/application/firefox/firefox:22419): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 18:15:24 INFO - PID 22369 | 18:15:25 INFO - PID 22369 | 18:15:25 INFO - PID 22369 | (/builds/slave/test/build/application/firefox/firefox:22480): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 18:15:25 INFO - PID 22369 | 18:15:25 INFO - PID 22369 | __metrics Screen width/height:1600/1200 18:15:25 INFO - PID 22369 | colorDepth:24 18:15:25 INFO - PID 22369 | Browser inner width/height: 1024/768 18:15:25 INFO - PID 22369 | __metrics 18:15:35 INFO - PID 22369 | RSS: Main: 174780416 18:15:35 INFO - PID 22369 | 18:18:32 INFO - PID 22369 | Cycle 1(1): loaded http://localhost:45432/tests/dromaeo/cssquery-dojo.html (next: http://localhost:45432/tests/dromaeo/cssquery-ext.html) 18:18:34 INFO - PID 22369 | RSS: Main: 178429952 18:18:34 INFO - PID 22369 | 18:21:36 INFO - PID 22369 | Cycle 1(1): loaded http://localhost:45432/tests/dromaeo/cssquery-ext.html (next: http://localhost:45432/tests/dromaeo/cssquery-jquery.html) 18:21:37 INFO - PID 22369 | RSS: Main: 174551040 18:21:37 INFO - PID 22369 | 18:24:38 INFO - PID 22369 | Cycle 1(1): loaded http://localhost:45432/tests/dromaeo/cssquery-jquery.html (next: http://localhost:45432/tests/dromaeo/cssquery-mootools.html) 18:24:39 INFO - PID 22369 | RSS: Main: 174710784 18:24:39 INFO - PID 22369 | 18:27:41 INFO - PID 22369 | Cycle 1(1): loaded http://localhost:45432/tests/dromaeo/cssquery-mootools.html (next: http://localhost:45432/tests/dromaeo/cssquery-prototype.html) 18:27:41 INFO - PID 22369 | RSS: Main: 175374336 18:27:41 INFO - PID 22369 | 18:30:45 INFO - PID 22369 | Cycle 1(1): loaded http://localhost:45432/tests/dromaeo/cssquery-prototype.html (next: http://localhost:45432/tests/dromaeo/cssquery-yui.html) 18:30:46 INFO - PID 22369 | RSS: Main: 179146752 18:30:46 INFO - PID 22369 | 18:33:47 INFO - PID 22369 | Cycle 1(1): loaded http://localhost:45432/tests/dromaeo/cssquery-yui.html (next: http://localhost:45432/tests/dromaeo/cssquery-dojo.html) 18:33:48 INFO - PID 22369 | RSS: Main: 179761152 18:33:48 INFO - PID 22369 | 18:33:48 INFO - PID 22369 | __start_tp_report 18:33:48 INFO - PID 22369 | _x_x_mozilla_page_load 18:33:48 INFO - PID 22369 | _x_x_mozilla_page_load_details 18:33:48 INFO - PID 22369 | |i|pagename|runs| 18:33:48 INFO - PID 22369 | |0;dojo.html;3325.850336734848;3360.0927749507628;3360.3111362170766;3370.5618269624947;3381.746369022324;6251.71867265973;6327.683615819208;6348.238211414631;6356.269029061659;6393.968030159843;6145.969270153643;6273.529485288599;6296.937030629692;6305.180326557541;6353.491720662348;5835.883282334372;5905.970470147643;5917.911231331534;5951;5959.940400596003;177.53255148213137;5791.971040144815;5844.941550584483;5880.206172166767;5952.494038006775;5783.652980821164;5809.448102430262;5818.1272809078555;5914.792982245601;5924.200232968537;5815.941840581625;5820.883582328372;5893.616914900518;5894.292684877799;5905.616134951214;5805.245318108661;5822.214001109881;5874.441928016831;5874.823755287348;5890.587658863881;6363.204599425072;6368.522360822957;6399.26408463028;6404.071409645622;6409.4551963083195;6597.86804263912;6770.458363330971;6787.626680532569;6810.863782724317;6913.516053876231;6757.864842703168;6768.56004359715;6778.22050464198;6779.966100169517;6783.3216678332265;6191.442770150663;6302;6319.3680631936895;6319.58922670025;6326.051092336141;7054.118235220598;7070.646467676698;7073.08049953513;7107;7117.110361204849;6708.2956289589865;6714.496412769062;6797.864042719118;6808.727650894008;6808.8297792554595;6808.11494505721;6843.076184715099;6899.793006209822;6919.792406227822;6928.757493487704;6641.601503909781;6730.394264516217;6747.089142965637;6795;6821.249662537117;6844.726210951506;6851.897221541681;6894.172699276019;6906.896396554056;6922.723091076301;5295.841124766263;5329.520343169133;5330.147176451749;5330.413654498002;5347.41178470368;4654.138984287918;4659.534046595313;4687.835925742583;4713.67004309695;4725.385699858996;5948.078047902582;5997.220361352996;6017.338092809788;6023.548233882389;6024.728887200086;6804.251532331439;6819.488538359643;6881.346272104142;6913.2049814272;6924.61914594692;5856.092305692625;5892.970535147297;5943.435373639497;5986.880262394815;6012.188354572077;5814.912776308359;5833.358330583633;5855.619384739978;5889.234399528034;5892.734826932712;6695.497837662195;6695.665216739241;6699.39705426514;6706.362895524917;6710.966445167743;6841.452683785385;6843.281455447106;6846.246912839583;6933.7226510939;6945.722171113301;6491.4806815455595;6568.934310657023;6577.243616983917;6667.066610674607;6674.332566743286;6640;6724.697388617622;6731.158605174353;6731.4951378647775;6776.864462710817;6897.896531552131;6911.550749201286;6983.022376867141;6989.1962424321855;6992.895106573304;6625.072489851521;6635.0710900473005;6671.499637527108;6704.363085507064;6759.628220447923;6497.707603157964;6556.901646475211;6557.114789503356;6636.535442519171;6720.395164435225;6455.2576453708425;6533.346665333429;6534.901976470262;6537.738490460329;6544.738210471528;6622.900656490253;6655.068290439439;6719.899201512079;6740;6749.42629876466;6472.935270647234;6522.184726909137;6536.575122617015;6541.901871472027;6556.967215164084 18:33:48 INFO - PID 22369 | |1;ext.html;686.8420263339432;768.8193274580473;815.6574238819695;838.2372041442287;1120.9327440353584;30788.23029424263;31362.05913822583;40564.391534126975;41767.79116104423;41809.58190418095;40386.192276154536;40460.190796184055;40494.7975260124;40701.79649101751;40733.18533629326;7607.7717668469895;7774.766756997271;7813.21867813217;7821.100573434042;7822.452428330019;7653.15815260321;7768.339691126261;7774.339181169579;7801.3758899288205;7802.2197780222095;7750.069991601016;7793.454458187929;7815.2966233039015;7850.175731548191;7856.882146767803;7762.30139287464;7764.767056988299;7811.960940195319;7836.294733473957;7840.137584865717;7637.579933103732;7686.730964416274;7687.154413014564;7696.7306144284685;7714.035745531809;36311.63688363109;36434.45348319777;36449;36717.44923826145;36819.263614727824;3487.128217945514;3497.5977762557122;3512.771669841478;3526.876559320437;3540.309639620275;29908.401831963456;29967.250818729488;29975;30056.699433005608;30110.24724381886;5122.1804511278015;5158.380994280673;5190.558802501791;5191.896162076775;5244.895102097975;183623;184311;185136;185723;186370;46551.30173047407;46582.301265481045;46694.53305466902;46697;46755;49216;49240;49267.26099108516;49403.258951115764;49404.50595493999;13600.251986140856;13834.515791947233;15052.924735376253;15386.538403847902;15396.692066158614;12323.383830808423;12534;12541.93729031349;12560.623181304576;12564.81152782709;13547.458101675822;13619.86380136186;13641.795373069412;13696.315184240748;13858.099223550436;5569.498745112878;5601.63589366698;5722.599418040697;5722.7424765885635;5770.16332631766;1004.3973615830444;1020.5611587017505;1024.4826362686795;1025.1286406554368;1028.4240825137947;22625.88687056587;23052.884735576215;23097.653535196987;23187.652185217234;23239;1090.5441235949936;1092.956281748721;1107.1751545098953;1113.3264375053138;1118.72031992002;12008.819867701992;12142.635720928387;15847.841521584867;15887.52337429879;16280.185990700418;233279;233573;233963;234906;234954;49385;49630.50369496259;49782.99999999927;49892.25161622579;49992;1383.4189640351076;1408.5985494134122;1411.2238268952035;1415.8867290616727;1431.1556181852966;27123.186304410898;27409.17772466829;27477.45045099127;27674.86162569174;27741.029063982664;38242.808785955895;38501.80749096237;38502;38576.22847543089;38594.2281154381;203370;203418;203742;204306;204420;33914.32171356608;35151.64848351586;37124.814375929025;37154.44268335923;37192.256154877294;43856.78071609622;45432.09135817331;45520.31719524276;45573.77213113913;45574.54425455703;33364.1658958528;33434.33131337408;33565.496517551765;33901.83049084739;34067.318653627284;33472;33473.83263083669;33530.497042543895;33667.83166084154;33814.830925845214;37046;37474.437883432314;37542.24915501729;37577.4363384544;37737.43393849149;31950.840245798623;32032.039038828872;32172;32457.188570285933;32473.188170295933;23343.649845252676;23672.763272367745;23823;24082.39794005164;24253.636195457435 18:33:48 INFO - PID 22369 | |2;jquery.html;553.1205383440329;555.1395337227297;560.162556977319;560.9411011843756;561.646162917362;21451.249206277782;22291.665625015616;22434.32697019092;22459.887700561496;23084.538309233805;22300.88849555754;22980.425489362773;23037.539249215006;23046.423839404022;23061.76938230617;6480.837979050526;6605.108310378086;6618;6618.8676226475445;6631.104800851896;5907.143464197688;6449.097126402307;6453.193350831146;6456.548041637087;6480.060391243291;6422.807315780511;6434.9678251608675;6437.806865794034;6438.839029024265;6442.098106265126;6438.581492202992;6445.1943507061615;6446.033095035758;6460.773872914472;6463.709133088999;5886.08765641322;5932.436418540232;5935.614185077956;5961.37405572413;5986.97006514969;27279.45441091187;28078.157655270374;28564.57153142684;28610.141695748953;28695.28261793451;1959.421970518686;1994.172418446343;1994.3518356534184;2003.669394549912;2005.2380095563738;18710.438686839418;18726.719099213522;19236.422907312804;19260.711089333672;19548.902255488774;2443.0472115874823;2444.449998750267;2501.2996361018945;2502.1117503286255;2510.635957786129;132764;135992;136026.31986840002;136501;136625;14258.146440962608;14347.354369053417;14389.56831295042;14671.266436678123;14689;22553.548929021326;22585.322440326818;22603.547929041328;22636.886815566148;22652.546949060925;9731.90268097324;10024.147947424404;10030.799384012278;10068.748281293027;10138.188944884392;7969;8305;9245.907540924505;9343.205827504738;9388.436693798394;8449.493030418194;9051.366404351764;9086.681966131153;9118.544072796467;9190.448573085503;2604.793258886884;2634.77604403624;2642.973570264311;2654.137405343271;2733.9474302393724;952.4808979106404;979.647326962293;982.7690492734201;995.8257304971601;995.9701208963743;19825.30611428593;19847.702284465744;20179.192832286542;20302.898485507478;20405.163535281896;1028.2082796246848;1039.4750650921237;1039.4802598700649;1044.4777611194402;1051.542578978147;10751.086157676686;10901.945490272656;11016.11871050314;11093;11099.334039957628;458083;465644;470716;476122;479679;107006.46496767466;107721.46139269411;110287;110716.4464177674;110975;1131.9094472442184;1145.6563031090639;1166.177844619558;1167.9416029198505;1168.2756690851727;17438;17501.82498175002;17539.473815785546;17628.647427051645;17902.91048544749;54824;55264.17103743528;55832.16251756308;56782.432175679365;57717.42282577288;64958;65699.34300656932;66095;66515.66742166452;66911;11870.821937671115;12509.186902851285;13967.511137110152;14282.785758213427;14451;22596.096156154228;22622.773772262066;22745.886270568542;22806.087756489556;22869.542609147393;17509.474715758548;18275.36036238725;18360.265589376275;18389;18426.078696064877;15784.368625254861;15846.207689615243;16889.155542223085;17260.741088883427;17485.388011419538;18741.71887421717;18830.905845470686;18936.715949260495;18941;19015.42953711391;14308.213048282445;14480.565583032527;14574.635634109232;14594.343254553358;14611.92694036523;18150.5462363442;18158;18376.448706538824;18462.907685461487;18573.164207610418 18:33:48 INFO - PID 22369 | |3;mootools.html;1233.457278797329;1338.4110991163886;1341.5572860955879;1356.1727346318744;1357.3484727330874;14198.57404277872;14939.327730252138;14944.402223911044;14951.92524037381;14962.25188740564;14272.500462483815;15394.61513462164;15405.306761195745;15418.152001639904;15433.92283038586;2730.877110530021;2814.535601625729;2815.957760633592;2816.676082250547;2817.3942602340494;2625.0681008242086;2816.4226333601564;2816.4367126574666;2816.633837601109;2819.2951762059483;2740.0272903119403;2759.434315965222;2785.791065670073;2792.2321361625613;2792.9301767455772;2640.7623313901745;2720.1975417251956;2751.2709132080013;2792.4415116976484;2824.1104052223586;2730.7678847298002;2738.4796888591104;2739.1782465260344;2740.328619488218;2742.9177124686294;14643.63390915225;14714.63213419673;14877.18175500347;14911.328990195467;14942.103473791607;7596.544207347577;7815.335696465807;7872.488288261245;7896.842063158762;7900.683972641087;5525.392206857283;5607.327120745495;5641.238432811559;5781.277340332458;5800;1918.808119188084;1939.398786376228;1944.805519448058;1977.5155087003632;1989.910454029572;24588.754112458824;24802.5039499209;24955.126570570126;25097.874510627513;25991.610125848318;6364.077208804697;6561.967190164114;6615.206175259;6749.831254218684;6751.56114852533;15993.28030238642;16011;16026.519204423887;16034.599135021484;16066.277017534005;7609.695612175451;7695.192004839523;7719.459637825298;7759.068911730628;7760.883586746203;7106.928930710626;7214.314640109181;7341.70633174667;7343.485955983137;7346.485745997729;5005.124103281911;5160.612954028463;5183.766730497136;5209.921851172236;5209.947900521022;1463.2244910197612;1480.2450750117519;1489.2181604657426;1494.7608382658939;1509.4792296657636;833.2500749325656;855.2986551028097;862.2369203255164;866.8309679612541;877.5349064995562;7080.433565314763;7130.322619351154;7162.212156662763;7190.1731300901065;7232.42140628759;666.1506579111672;681.4412182010802;690.4890381117937;690.7513295213719;701.6281370873445;5602.635828671205;5866.384029676822;5898.203742494794;5960.821175364746;5995.970020149872;21083.894580527307;21360.679589806165;21482.89258553697;21553.676694849277;21562.245321413677;19996.90001549983;20827.06278217484;21245.89377053105;21279;21366.14535418597;9535.809283814286;9792.657256995972;10812.35125892449;11157.107431405468;11157.107431405468;8512.233898949124;8721.869171962553;8830.072842351718;8870.379073465054;9260.166585007382;16836.07401592924;17424.30302787925;17481.388151414638;17757.378491752726;17868.374606888698;17196.742048869026;17575.82424175742;17795.644087117926;18042.458726238234;18059.367922122663;11567.190296679151;11825.46785394642;11880.108991825475;11885.405729713653;11886.34625095628;13520.79718804199;13648.249346285653;13768.655783605089;13784.448622055006;13799.724005520035;10902.400367979837;10990.890091098987;11104.944475277895;11113.833292500458;11339.829902551302;10857.67426977192;10872.61945831892;11021.504032318404;11041.558337666725;11150.219484635998;9980.60077596888;9986.35088719231;10058.094771470604;10060.144887684628;10067.647632332833;7627.771166865003;7644.655990480553;7671.8849217262905;7686.769396918102;7772.222777722182;7419.146798118286;7440.702371905063;7541.660625271986;7557.811054723676;7698.153203147761 18:33:48 INFO - PID 22369 | |4;prototype.html;492.1952607486758;512.3825789923144;522.736018310753;523.1916688715934;526.2290744059951;6352.968235158829;6424.293327733952;6427.553800394909;6440.838979025527;6476.611403315805;6261.405166509186;6399;6403.58376705515;6407.327230640785;6408.743650253996;4975.552200301972;5087.974560127194;5094.414142373637;5203.401608814987;5286.65636733613;5130.2300706256465;5139.717315547643;5140.768665410047;5148;5155.690658560479;5049.621278404116;5051.267566202899;5052.26742122392;5074.112030394685;5076.593872490211;5022.799088036474;5057.645964782467;5087.771050302746;5118.6416950813455;5131.461196574381;4546.727196368195;4551.590356867898;4556.521565235669;4556.724117247176;4561.954380456186;6256.78101266458;6276.623402595859;6358.427741503287;6358.618482891041;6415.93584064158;5727.82105931208;5757.905997860393;5790.826275211751;5793.65238085712;5797.739101740432;6652.733890644368;6682.632455214961;6684.966575167142;6706.378555579981;6714.932850671479;4918.803247870081;4937.604991600663;4949.554540091373;4954.603631709455;4955.702657840541;6813.591184528944;6857.348551887562;6971.8605627887155;7026.086608740882;7181;4951.925721114186;4955.306257124005;5036.891883785562;5053.545180933734;5068.746562671926;5055.469175736568;5087.456271864068;5090.345637667773;5109.3613298337705;5148.352527191263;5785.768569257267;5817.2257461474655;5817.3019237690905;5820.9544366665605;5821.446962538552;6705.564138330993;6730.528862979642;6746.291639377795;6790.8302292442095;6792.150981127359;1763.092007616053;1788.9534622245872;1792.7042038063835;1797.4517772079641;1804.2602532961423;1182.7634473105413;1196.2284326609288;1200.9279443233434;1212.3392750950752;1240.9813852792215;5986.161937328778;6004.699765011732;6034.668093254825;6043.576949613573;6085.726142323606;6866.862662746717;6895.586264824126;6902.896456553156;6961.338672826073;6982.47631427645;5947.405259474018;5963.671998040149;5963.821085367446;6025.18659980897;6042.486388656926;3664.9633503665154;3667.3215455140885;3678.1540245743627;3683.3185860615336;3713.2918857325394;6659.966700166565;6661.500387470959;6681.498887583451;6685.39831415175;6743.494237932175;6638.535302528777;7057.929420705727;7159.92840071603;7184.82037949045;7234.63826808668;6512.473830687627;6521.380468855451;6618.305077966744;6619.702113404908;6623.602583844889;6778.8644227116165;6835.384815366641;6863.759768408082;6864.897026544706;6940.687669054803;6785.796426107225;6799.864002720017;6830.556013859084;6883.483738719516;6919.792406227822;6596;6616.768413105518;6633.933660663524;6651.301613330531;6691.531592788653;5016.849494515171;5027.924581131213;5051.924221136759;5065.468125846881;5089.847304580869;6632.403083722488;6756.4257038150345;6767.695453704693;6977.8255543609785;7004.719811207495;6571.704273307617;6627.204735431586;6676.298988606127;6713.328667133248;6775.1531058617675;6562.2125344957;6598.967005164944;6634.767783127568;6746.123004009447;6771.187457505131;6545.574537655038;6549.934500654933;6575;6580.670966451754;6659.766908158192;6634.445738390657;6650.268470468148;6685.130932978682;6761.93238067613;6780.559263647847;6427.839304017437;6490.675466226764;6499.28507864144;6550.279469258282;6646.4350530205475 18:33:48 INFO - PID 22369 | |5;yui.html;870.2820173356978;894.3247847874853;899.6761165980242;902.4449963272589;909.8316811389893;18350.90824545877;18743.90628046861;19388.32140875068;19471.610567788637;19491.805081949176;19733.802661973376;19750.308739194094;19786.505337366536;19826.90086549569;19985.600287994268;881.7266647339313;947.5499137909507;950.0357137505438;952.333366643349;953.7472569769017;959.1751094059103;961.2406199102701;962.9888616952207;968.6851773173712;969.0842154164318;939.9154076133146;983.3264214013388;985.8126955878396;1021.3616489693941;1023.4422239879284;977.301229620821;978.0854900667898;978.6085565773676;978.7602037500786;978.8189185000798;720.5568575326189;721.39763297646;722.7867779005206;723.0419693905544;723.1177962885324;18302;18398.89606623607;18542.721859172125;18607.255709771594;18782.248710051583;21257.255996040218;21279.787202127936;21588.24441144568;21894.89052554743;21925;17023.489295321036;17056;17147;17222.224999875034;17223.655526889394;3747.400415933465;3751.624837516254;3762.49206357141;3766.6986641068647;3771.679407250387;120734;121658;121921.99999999911;122236;125207;16171.272292746855;16282.674346513004;16334.01995880251;16825.747613785803;16831.158442078093;29927.700722992926;30572.84713576418;30878.84560577183;30909.536356954664;30942.22644433907;7650.540967541965;8051.637676304464;8361.456505327134;8582.399232053822;8619.6983105591;4048.0891799344913;4202.768847713405;4206.369044643328;4215.704900656925;4217.578242175758;5252.159654455345;5380.3274590676165;5388.137897936312;5388.218708287276;5670.659760414388;1057.1384321777728;1072.8766191887876;1080.719013056611;1081.7728277061747;1082.5561519776854;685.9622720750407;700.6111608057577;703.1351437731619;703.1632357494565;710.1903829634222;11654.475548600334;12173.634790956285;12376.257424554555;12615.747685046248;13502.122362046435;699.3181647893264;700.0968750312143;708.4544900426746;711.2425267090532;716.8279612892868;5710.628809127394;5725.685087320154;5783.826485205451;5812.157237200667;5894.705264736746;113139;114931.42534287443;115301;115320;116235.41882290704;91372;92577.07422925819;93138;93981.5300923491;95492;78421;81437;81791.5910420456;84036;84793.57603212068;10694.946525267635;10796.298240614335;10888.564457421615;11013.449327533752;11172.441377931233;39853.80073099616;40375;40546.59453405428;40560.36357636744;42634.78682606691;53024.46975530349;53427.465725343805;54222;54842.451575483734;56003.43996559982;15824.44614438489;15924.28340724647;16017.599560011093;16147.434839780552;16267.674646507241;17399.82600173982;17729.822701772817;19238.71141932844;20684.793152068287;20832.166713331902;18260.178291976626;18334.72497912559;18469.168887399832;18503.81496185021;18600.441986740418;18884.244630214638;19082.236710531422;19146.329878454744;19213.615727685647;19439.514012149808;17694.557636059202;17939;17961.640767184843;17999.820001799813;18103;13614.523491677744;13873.722525549634;14195.645108871948;14320.355583998537;14413.063150895157;14282.577232120493;14920;14939;15201.847981520043;15225.238738063274 18:33:48 INFO - PID 22369 | __end_tp_report 18:33:48 INFO - PID 22369 | __start_cc_report 18:33:48 INFO - PID 22369 | _x_x_mozilla_cycle_collect,347 18:33:48 INFO - PID 22369 | __end_cc_report 18:33:48 INFO - PID 22369 | __startTimestamp1503711228008__endTimestamp 18:33:48 INFO - PID 22369 | 18:33:48 INFO - PID 22369 | ------- Summary: start ------- 18:33:48 INFO - PID 22369 | Number of tests: 6 18:33:48 INFO - PID 22369 | 18:33:48 INFO - PID 22369 | [#0] dojo.html Cycles:165 Average:6252.44 Median:6536.58 stddev:875.96 (13.4%) stddev-sans-first:848.02 18:33:48 INFO - PID 22369 | Values: 3325.9 3360.1 3360.3 3370.6 3381.7 6251.7 6327.7 6348.2 6356.3 6394.0 6146.0 6273.5 6296.9 6305.2 6353.5 5835.9 5906.0 5917.9 5951.0 5959.9 177.5 5792.0 5844.9 5880.2 5952.5 5783.7 5809.4 5818.1 5914.8 5924.2 5815.9 5820.9 5893.6 5894.3 5905.6 5805.2 5822.2 5874.4 5874.8 5890.6 6363.2 6368.5 6399.3 6404.1 6409.5 6597.9 6770.5 6787.6 6810.9 6913.5 6757.9 6768.6 6778.2 6780.0 6783.3 6191.4 6302.0 6319.4 6319.6 6326.1 7054.1 7070.6 7073.1 7107.0 7117.1 6708.3 6714.5 6797.9 6808.7 6808.8 6808.1 6843.1 6899.8 6919.8 6928.8 6641.6 6730.4 6747.1 6795.0 6821.2 6844.7 6851.9 6894.2 6906.9 6922.7 5295.8 5329.5 5330.1 5330.4 5347.4 4654.1 4659.5 4687.8 4713.7 4725.4 5948.1 5997.2 6017.3 6023.5 6024.7 6804.3 6819.5 6881.3 6913.2 6924.6 5856.1 5893.0 5943.4 5986.9 6012.2 5814.9 5833.4 5855.6 5889.2 5892.7 6695.5 6695.7 6699.4 6706.4 6711.0 6841.5 6843.3 6846.2 6933.7 6945.7 6491.5 6568.9 6577.2 6667.1 6674.3 6640.0 6724.7 6731.2 6731.5 6776.9 6897.9 6911.6 6983.0 6989.2 6992.9 6625.1 6635.1 6671.5 6704.4 6759.6 6497.7 6556.9 6557.1 6636.5 6720.4 6455.3 6533.3 6534.9 6537.7 6544.7 6622.9 6655.1 6719.9 6740.0 6749.4 6472.9 6522.2 6536.6 6541.9 6557.0 18:33:48 INFO - PID 22369 | 18:33:48 INFO - PID 22369 | [#1] ext.html Cycles:180 Average:37695.06 Median:3505.18 stddev:53994.97 (1540.4%) stddev-sans-first:54074.93 18:33:48 INFO - PID 22369 | Values: 686.8 768.8 815.7 838.2 1120.9 30788.2 31362.1 40564.4 41767.8 41809.6 40386.2 40460.2 40494.8 40701.8 40733.2 7607.8 7774.8 7813.2 7821.1 7822.5 7653.2 7768.3 7774.3 7801.4 7802.2 7750.1 7793.5 7815.3 7850.2 7856.9 7762.3 7764.8 7812.0 7836.3 7840.1 7637.6 7686.7 7687.2 7696.7 7714.0 36311.6 36434.5 36449.0 36717.4 36819.3 3487.1 3497.6 3512.8 3526.9 3540.3 29908.4 29967.3 29975.0 30056.7 30110.2 5122.2 5158.4 5190.6 5191.9 5244.9 183623.0 184311.0 185136.0 185723.0 186370.0 46551.3 46582.3 46694.5 46697.0 46755.0 49216.0 49240.0 49267.3 49403.3 49404.5 13600.3 13834.5 15052.9 15386.5 15396.7 12323.4 12534.0 12541.9 12560.6 12564.8 13547.5 13619.9 13641.8 13696.3 13858.1 5569.5 5601.6 5722.6 5722.7 5770.2 1004.4 1020.6 1024.5 1025.1 1028.4 22625.9 23052.9 23097.7 23187.7 23239.0 1090.5 1093.0 1107.2 1113.3 1118.7 12008.8 12142.6 15847.8 15887.5 16280.2 233279.0 233573.0 233963.0 234906.0 234954.0 49385.0 49630.5 49783.0 49892.3 49992.0 1383.4 1408.6 1411.2 1415.9 1431.2 27123.2 27409.2 27477.5 27674.9 27741.0 38242.8 38501.8 38502.0 38576.2 38594.2 203370.0 203418.0 203742.0 204306.0 204420.0 33914.3 35151.6 37124.8 37154.4 37192.3 43856.8 45432.1 45520.3 45573.8 45574.5 33364.2 33434.3 33565.5 33901.8 34067.3 33472.0 33473.8 33530.5 33667.8 33814.8 37046.0 37474.4 37542.2 37577.4 37737.4 31950.8 32032.0 32172.0 32457.2 32473.2 23343.6 23672.8 23823.0 24082.4 24253.6 18:33:48 INFO - PID 22369 | 18:33:48 INFO - PID 22369 | [#2] jquery.html Cycles:180 Average:33572.92 Median:22590.71 stddev:79226.76 (350.7%) stddev-sans-first:79410.22 18:33:48 INFO - PID 22369 | Values: 553.1 555.1 560.2 560.9 561.6 21451.2 22291.7 22434.3 22459.9 23084.5 22300.9 22980.4 23037.5 23046.4 23061.8 6480.8 6605.1 6618.0 6618.9 6631.1 5907.1 6449.1 6453.2 6456.5 6480.1 6422.8 6435.0 6437.8 6438.8 6442.1 6438.6 6445.2 6446.0 6460.8 6463.7 5886.1 5932.4 5935.6 5961.4 5987.0 27279.5 28078.2 28564.6 28610.1 28695.3 1959.4 1994.2 1994.4 2003.7 2005.2 18710.4 18726.7 19236.4 19260.7 19548.9 2443.0 2444.4 2501.3 2502.1 2510.6 132764.0 135992.0 136026.3 136501.0 136625.0 14258.1 14347.4 14389.6 14671.3 14689.0 22553.5 22585.3 22603.5 22636.9 22652.5 9731.9 10024.1 10030.8 10068.7 10138.2 7969.0 8305.0 9245.9 9343.2 9388.4 8449.5 9051.4 9086.7 9118.5 9190.4 2604.8 2634.8 2643.0 2654.1 2733.9 952.5 979.6 982.8 995.8 996.0 19825.3 19847.7 20179.2 20302.9 20405.2 1028.2 1039.5 1039.5 1044.5 1051.5 10751.1 10901.9 11016.1 11093.0 11099.3 458083.0 465644.0 470716.0 476122.0 479679.0 107006.5 107721.5 110287.0 110716.4 110975.0 1131.9 1145.7 1166.2 1167.9 1168.3 17438.0 17501.8 17539.5 17628.6 17902.9 54824.0 55264.2 55832.2 56782.4 57717.4 64958.0 65699.3 66095.0 66515.7 66911.0 11870.8 12509.2 13967.5 14282.8 14451.0 22596.1 22622.8 22745.9 22806.1 22869.5 17509.5 18275.4 18360.3 18389.0 18426.1 15784.4 15846.2 16889.2 17260.7 17485.4 18741.7 18830.9 18936.7 18941.0 19015.4 14308.2 14480.6 14574.6 14594.3 14611.9 18150.5 18158.0 18376.4 18462.9 18573.2 18:33:48 INFO - PID 22369 | 18:33:48 INFO - PID 22369 | [#3] mootools.html Cycles:180 Average:9140.03 Median:2734.68 stddev:6365.74 (232.8%) stddev-sans-first:6355.88 18:33:48 INFO - PID 22369 | Values: 1233.5 1338.4 1341.6 1356.2 1357.3 14198.6 14939.3 14944.4 14951.9 14962.3 14272.5 15394.6 15405.3 15418.2 15433.9 2730.9 2814.5 2816.0 2816.7 2817.4 2625.1 2816.4 2816.4 2816.6 2819.3 2740.0 2759.4 2785.8 2792.2 2792.9 2640.8 2720.2 2751.3 2792.4 2824.1 2730.8 2738.5 2739.2 2740.3 2742.9 14643.6 14714.6 14877.2 14911.3 14942.1 7596.5 7815.3 7872.5 7896.8 7900.7 5525.4 5607.3 5641.2 5781.3 5800.0 1918.8 1939.4 1944.8 1977.5 1989.9 24588.8 24802.5 24955.1 25097.9 25991.6 6364.1 6562.0 6615.2 6749.8 6751.6 15993.3 16011.0 16026.5 16034.6 16066.3 7609.7 7695.2 7719.5 7759.1 7760.9 7106.9 7214.3 7341.7 7343.5 7346.5 5005.1 5160.6 5183.8 5209.9 5209.9 1463.2 1480.2 1489.2 1494.8 1509.5 833.3 855.3 862.2 866.8 877.5 7080.4 7130.3 7162.2 7190.2 7232.4 666.2 681.4 690.5 690.8 701.6 5602.6 5866.4 5898.2 5960.8 5996.0 21083.9 21360.7 21482.9 21553.7 21562.2 19996.9 20827.1 21245.9 21279.0 21366.1 9535.8 9792.7 10812.4 11157.1 11157.1 8512.2 8721.9 8830.1 8870.4 9260.2 16836.1 17424.3 17481.4 17757.4 17868.4 17196.7 17575.8 17795.6 18042.5 18059.4 11567.2 11825.5 11880.1 11885.4 11886.3 13520.8 13648.2 13768.7 13784.4 13799.7 10902.4 10990.9 11104.9 11113.8 11339.8 10857.7 10872.6 11021.5 11041.6 11150.2 9980.6 9986.4 10058.1 10060.1 10067.6 7627.8 7644.7 7671.9 7686.8 7772.2 7419.1 7440.7 7541.7 7557.8 7698.2 18:33:48 INFO - PID 22369 | 18:33:48 INFO - PID 22369 | [#4] prototype.html Cycles:180 Average:5638.11 Median:6378.81 stddev:1590.07 (24.9%) stddev-sans-first:1546.91 18:33:48 INFO - PID 22369 | Values: 492.2 512.4 522.7 523.2 526.2 6353.0 6424.3 6427.6 6440.8 6476.6 6261.4 6399.0 6403.6 6407.3 6408.7 4975.6 5088.0 5094.4 5203.4 5286.7 5130.2 5139.7 5140.8 5148.0 5155.7 5049.6 5051.3 5052.3 5074.1 5076.6 5022.8 5057.6 5087.8 5118.6 5131.5 4546.7 4551.6 4556.5 4556.7 4562.0 6256.8 6276.6 6358.4 6358.6 6415.9 5727.8 5757.9 5790.8 5793.7 5797.7 6652.7 6682.6 6685.0 6706.4 6714.9 4918.8 4937.6 4949.6 4954.6 4955.7 6813.6 6857.3 6971.9 7026.1 7181.0 4951.9 4955.3 5036.9 5053.5 5068.7 5055.5 5087.5 5090.3 5109.4 5148.4 5785.8 5817.2 5817.3 5821.0 5821.4 6705.6 6730.5 6746.3 6790.8 6792.2 1763.1 1789.0 1792.7 1797.5 1804.3 1182.8 1196.2 1200.9 1212.3 1241.0 5986.2 6004.7 6034.7 6043.6 6085.7 6866.9 6895.6 6902.9 6961.3 6982.5 5947.4 5963.7 5963.8 6025.2 6042.5 3665.0 3667.3 3678.2 3683.3 3713.3 6660.0 6661.5 6681.5 6685.4 6743.5 6638.5 7057.9 7159.9 7184.8 7234.6 6512.5 6521.4 6618.3 6619.7 6623.6 6778.9 6835.4 6863.8 6864.9 6940.7 6785.8 6799.9 6830.6 6883.5 6919.8 6596.0 6616.8 6633.9 6651.3 6691.5 5016.8 5027.9 5051.9 5065.5 5089.8 6632.4 6756.4 6767.7 6977.8 7004.7 6571.7 6627.2 6676.3 6713.3 6775.2 6562.2 6599.0 6634.8 6746.1 6771.2 6545.6 6549.9 6575.0 6580.7 6659.8 6634.4 6650.3 6685.1 6761.9 6780.6 6427.8 6490.7 6499.3 6550.3 6646.4 18:33:48 INFO - PID 22369 | 18:33:48 INFO - PID 22369 | [#5] yui.html Cycles:180 Average:23044.77 Median:21909.95 stddev:31150.39 (142.2%) stddev-sans-first:31193.28 18:33:48 INFO - PID 22369 | Values: 870.3 894.3 899.7 902.4 909.8 18350.9 18743.9 19388.3 19471.6 19491.8 19733.8 19750.3 19786.5 19826.9 19985.6 881.7 947.5 950.0 952.3 953.7 959.2 961.2 963.0 968.7 969.1 939.9 983.3 985.8 1021.4 1023.4 977.3 978.1 978.6 978.8 978.8 720.6 721.4 722.8 723.0 723.1 18302.0 18398.9 18542.7 18607.3 18782.2 21257.3 21279.8 21588.2 21894.9 21925.0 17023.5 17056.0 17147.0 17222.2 17223.7 3747.4 3751.6 3762.5 3766.7 3771.7 120734.0 121658.0 121922.0 122236.0 125207.0 16171.3 16282.7 16334.0 16825.7 16831.2 29927.7 30572.8 30878.8 30909.5 30942.2 7650.5 8051.6 8361.5 8582.4 8619.7 4048.1 4202.8 4206.4 4215.7 4217.6 5252.2 5380.3 5388.1 5388.2 5670.7 1057.1 1072.9 1080.7 1081.8 1082.6 686.0 700.6 703.1 703.2 710.2 11654.5 12173.6 12376.3 12615.7 13502.1 699.3 700.1 708.5 711.2 716.8 5710.6 5725.7 5783.8 5812.2 5894.7 113139.0 114931.4 115301.0 115320.0 116235.4 91372.0 92577.1 93138.0 93981.5 95492.0 78421.0 81437.0 81791.6 84036.0 84793.6 10694.9 10796.3 10888.6 11013.4 11172.4 39853.8 40375.0 40546.6 40560.4 42634.8 53024.5 53427.5 54222.0 54842.5 56003.4 15824.4 15924.3 16017.6 16147.4 16267.7 17399.8 17729.8 19238.7 20684.8 20832.2 18260.2 18334.7 18469.2 18503.8 18600.4 18884.2 19082.2 19146.3 19213.6 19439.5 17694.6 17939.0 17961.6 17999.8 18103.0 13614.5 13873.7 14195.6 14320.4 14413.1 14282.6 14920.0 14939.0 15201.8 15225.2 18:33:48 INFO - PID 22369 | -------- Summary: end -------- 18:33:48 INFO - PID 22369 | 18:33:48 INFO - TEST-INFO | 22369: exit 0 18:33:48 INFO - TEST-OK | dromaeo_css | took 1110899ms 18:33:48 INFO - TEST-START | kraken 18:33:48 INFO - Initialising browser for kraken test... 18:33:48 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:45432/getInfo.html -profile /tmp/tmpzQE20O/profile 18:33:48 INFO - TEST-INFO | started process 22794 (/builds/slave/test/build/application/firefox/firefox http://localhost:45432/getInfo.html) 18:33:54 INFO - TEST-INFO | 22794: exit 0 18:33:54 INFO - Browser initialized. 18:33:54 INFO - Running cycle 1/1 for kraken test... 18:33:54 INFO - Using env: {'DISPLAY': ':0', 18:33:54 INFO - 'HOME': '/home/cltbld', 18:33:54 INFO - 'JSGC_DISABLE_POISONING': '1', 18:33:54 INFO - 'LANG': 'en_US.UTF-8', 18:33:54 INFO - 'LANGUAGE': 'en_US:en', 18:33:54 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 18:33:54 INFO - 'LOGNAME': 'cltbld', 18:33:54 INFO - 'MAIL': '/var/mail/cltbld', 18:33:54 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:33:54 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:33:54 INFO - 'MOZ_CRASHREPORTER': '1', 18:33:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:33:54 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 18:33:54 INFO - 'MOZ_NO_REMOTE': '1', 18:33:54 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:33:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:33:54 INFO - 'NO_EM_RESTART': '1', 18:33:54 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:33:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:33:54 INFO - 'PWD': '/builds/slave/test', 18:33:54 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:33:54 INFO - 'RUST_BACKTRACE': 'full', 18:33:54 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 18:33:54 INFO - 'SHELL': '/bin/bash', 18:33:54 INFO - 'SHLVL': '1', 18:33:54 INFO - 'STYLO_FORCE_ENABLED': '1', 18:33:54 INFO - 'TERM': 'linux', 18:33:54 INFO - 'TMOUT': '86400', 18:33:54 INFO - 'USER': 'cltbld', 18:33:54 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1503710048.144063-1391538418', 18:33:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:33:54 INFO - '_': '/tools/buildbot/bin/python'} 18:33:54 INFO - TEST-INFO | started process 22982 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpzQE20O/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 18:33:55 INFO - PID 22982 | 18:33:55 INFO - PID 22982 | (/builds/slave/test/build/application/firefox/firefox:23031): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 18:33:55 INFO - PID 22982 | 18:33:56 INFO - PID 22982 | 18:33:56 INFO - PID 22982 | (/builds/slave/test/build/application/firefox/firefox:23091): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 18:33:56 INFO - PID 22982 | 18:33:56 INFO - PID 22982 | __metrics Screen width/height:1600/1200 18:33:56 INFO - PID 22982 | colorDepth:24 18:33:56 INFO - PID 22982 | Browser inner width/height: 1024/768 18:33:56 INFO - PID 22982 | __metrics 18:34:05 INFO - PID 22982 | RSS: Main: 175058944 18:34:05 INFO - PID 22982 | 18:34:08 INFO - PID 22982 | Cycle 1(1): loaded http://localhost:45432/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:45432/tests/kraken/driver.html?testName=audio-beat-detection) 18:34:09 INFO - PID 22982 | RSS: Main: 180510720 18:34:09 INFO - PID 22982 | 18:34:12 INFO - PID 22982 | Cycle 1(1): loaded http://localhost:45432/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:45432/tests/kraken/driver.html?testName=audio-dft) 18:34:12 INFO - PID 22982 | RSS: Main: 182382592 18:34:12 INFO - PID 22982 | 18:34:15 INFO - PID 22982 | Cycle 1(1): loaded http://localhost:45432/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:45432/tests/kraken/driver.html?testName=audio-fft) 18:34:16 INFO - PID 22982 | RSS: Main: 174587904 18:34:16 INFO - PID 22982 | 18:34:18 INFO - PID 22982 | Cycle 1(1): loaded http://localhost:45432/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:45432/tests/kraken/driver.html?testName=audio-oscillator) 18:34:19 INFO - PID 22982 | RSS: Main: 174899200 18:34:19 INFO - PID 22982 | 18:34:21 INFO - PID 22982 | Cycle 1(1): loaded http://localhost:45432/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:45432/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:34:22 INFO - PID 22982 | RSS: Main: 175017984 18:34:22 INFO - PID 22982 | 18:34:25 INFO - PID 22982 | Cycle 1(1): loaded http://localhost:45432/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:45432/tests/kraken/driver.html?testName=imaging-darkroom) 18:34:26 INFO - PID 22982 | RSS: Main: 176603136 18:34:26 INFO - PID 22982 | 18:34:29 INFO - PID 22982 | Cycle 1(1): loaded http://localhost:45432/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:45432/tests/kraken/driver.html?testName=imaging-desaturate) 18:34:30 INFO - PID 22982 | RSS: Main: 176766976 18:34:30 INFO - PID 22982 | 18:34:33 INFO - PID 22982 | Cycle 1(1): loaded http://localhost:45432/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:45432/tests/kraken/driver.html?testName=json-parse-financial) 18:34:33 INFO - PID 22982 | RSS: Main: 177360896 18:34:33 INFO - PID 22982 | 18:34:35 INFO - PID 22982 | Cycle 1(1): loaded http://localhost:45432/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:45432/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:34:36 INFO - PID 22982 | RSS: Main: 177430528 18:34:36 INFO - PID 22982 | 18:34:37 INFO - PID 22982 | Cycle 1(1): loaded http://localhost:45432/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:45432/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:34:38 INFO - PID 22982 | RSS: Main: 178036736 18:34:38 INFO - PID 22982 | 18:34:40 INFO - PID 22982 | Cycle 1(1): loaded http://localhost:45432/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:45432/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:34:41 INFO - PID 22982 | RSS: Main: 177836032 18:34:41 INFO - PID 22982 | 18:34:44 INFO - PID 22982 | Cycle 1(1): loaded http://localhost:45432/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:45432/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:34:44 INFO - PID 22982 | RSS: Main: 177524736 18:34:44 INFO - PID 22982 | 18:34:47 INFO - PID 22982 | Cycle 1(1): loaded http://localhost:45432/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:45432/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:34:48 INFO - PID 22982 | RSS: Main: 177647616 18:34:48 INFO - PID 22982 | 18:34:50 INFO - PID 22982 | Cycle 1(1): loaded http://localhost:45432/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:45432/tests/kraken/driver.html?testName=ai-astar) 18:34:50 INFO - PID 22982 | RSS: Main: 179236864 18:34:50 INFO - PID 22982 | 18:34:50 INFO - PID 22982 | __start_tp_report 18:34:50 INFO - PID 22982 | _x_x_mozilla_page_load 18:34:50 INFO - PID 22982 | _x_x_mozilla_page_load_details 18:34:50 INFO - PID 22982 | |i|pagename|runs| 18:34:50 INFO - PID 22982 | |0;ai-astar;97;114;99;106;107;113;109;97;108;110 18:34:50 INFO - PID 22982 | |1;audio-beat-detection;150;116;147;160;123;146;145;121;171;118 18:34:50 INFO - PID 22982 | |2;audio-dft;166;162;162;165;162;167;171;169;166;163 18:34:50 INFO - PID 22982 | |3;audio-fft;73;77;74;171;77;74;76;75;181;77 18:34:50 INFO - PID 22982 | |4;audio-oscillator;78;79;131;82;78;79;126;78;83;139 18:34:50 INFO - PID 22982 | |5;imaging-gaussian-blur;113;118;124;115;114;117;115;113;120;114 18:34:50 INFO - PID 22982 | |6;imaging-darkroom;147;148;148;147;147;149;150;147;148;148 18:34:50 INFO - PID 22982 | |7;imaging-desaturate;94;93;94;94;93;94;93;98;94;93 18:34:50 INFO - PID 22982 | |8;json-parse-financial;54;53;51;51;56;57;60;53;57;53 18:34:50 INFO - PID 22982 | |9;json-stringify-tinderbox;56;60;59;55;59;61;55;54;56;56 18:34:50 INFO - PID 22982 | |10;stanford-crypto-aes;82;74;76;76;77;73;78;79;88;79 18:34:50 INFO - PID 22982 | |11;stanford-crypto-ccm;124;120;124;124;120;129;120;117;123;173 18:34:50 INFO - PID 22982 | |12;stanford-crypto-pbkdf2;171;173;174;168;175;174;177;175;171;169 18:34:50 INFO - PID 22982 | |13;stanford-crypto-sha256-iterative;64;65;65;64;68;68;66;67;66;69 18:34:50 INFO - PID 22982 | __end_tp_report 18:34:50 INFO - PID 22982 | __start_cc_report 18:34:50 INFO - PID 22982 | _x_x_mozilla_cycle_collect,944 18:34:50 INFO - PID 22982 | __end_cc_report 18:34:50 INFO - PID 22982 | __startTimestamp1503711290603__endTimestamp 18:34:50 INFO - PID 22982 | 18:34:50 INFO - PID 22982 | ------- Summary: start ------- 18:34:50 INFO - PID 22982 | Number of tests: 14 18:34:50 INFO - PID 22982 | 18:34:50 INFO - PID 22982 | [#0] ai-astar Cycles:10 Average:106.00 Median:113.50 stddev:6.27 (5.5%) stddev-sans-first:5.74 18:34:50 INFO - PID 22982 | Values: 97.0 114.0 99.0 106.0 107.0 113.0 109.0 97.0 108.0 110.0 18:34:50 INFO - PID 22982 | 18:34:50 INFO - PID 22982 | [#1] audio-beat-detection Cycles:10 Average:139.70 Median:146.50 stddev:19.09 (13.0%) stddev-sans-first:19.88 18:34:50 INFO - PID 22982 | Values: 150.0 116.0 147.0 160.0 123.0 146.0 145.0 121.0 171.0 118.0 18:34:50 INFO - PID 22982 | 18:34:50 INFO - PID 22982 | [#2] audio-dft Cycles:10 Average:165.30 Median:166.00 stddev:3.13 (1.9%) stddev-sans-first:3.31 18:34:50 INFO - PID 22982 | Values: 166.0 162.0 162.0 165.0 162.0 167.0 171.0 169.0 166.0 163.0 18:34:50 INFO - PID 22982 | 18:34:50 INFO - PID 22982 | [#3] audio-fft Cycles:10 Average:95.50 Median:75.50 stddev:42.52 (56.3%) stddev-sans-first:44.31 18:34:50 INFO - PID 22982 | Values: 73.0 77.0 74.0 171.0 77.0 74.0 76.0 75.0 181.0 77.0 18:34:50 INFO - PID 22982 | 18:34:50 INFO - PID 22982 | [#4] audio-oscillator Cycles:10 Average:95.30 Median:78.50 stddev:25.57 (32.6%) stddev-sans-first:26.34 18:34:50 INFO - PID 22982 | Values: 78.0 79.0 131.0 82.0 78.0 79.0 126.0 78.0 83.0 139.0 18:34:50 INFO - PID 22982 | 18:34:50 INFO - PID 22982 | [#5] imaging-gaussian-blur Cycles:10 Average:116.30 Median:116.00 stddev:3.53 (3.0%) stddev-sans-first:3.54 18:34:50 INFO - PID 22982 | Values: 113.0 118.0 124.0 115.0 114.0 117.0 115.0 113.0 120.0 114.0 18:34:50 INFO - PID 22982 | 18:34:50 INFO - PID 22982 | [#6] imaging-darkroom Cycles:10 Average:147.90 Median:148.00 stddev:0.99 (0.7%) stddev-sans-first:1.00 18:34:50 INFO - PID 22982 | Values: 147.0 148.0 148.0 147.0 147.0 149.0 150.0 147.0 148.0 148.0 18:34:50 INFO - PID 22982 | 18:34:50 INFO - PID 22982 | [#7] imaging-desaturate Cycles:10 Average:94.00 Median:94.00 stddev:1.49 (1.6%) stddev-sans-first:1.58 18:34:50 INFO - PID 22982 | Values: 94.0 93.0 94.0 94.0 93.0 94.0 93.0 98.0 94.0 93.0 18:34:50 INFO - PID 22982 | 18:34:50 INFO - PID 22982 | [#8] json-parse-financial Cycles:10 Average:54.50 Median:55.00 stddev:2.92 (5.3%) stddev-sans-first:3.09 18:34:50 INFO - PID 22982 | Values: 54.0 53.0 51.0 51.0 56.0 57.0 60.0 53.0 57.0 53.0 18:34:50 INFO - PID 22982 | 18:34:50 INFO - PID 22982 | [#9] json-stringify-tinderbox Cycles:10 Average:57.10 Median:57.50 stddev:2.42 (4.2%) stddev-sans-first:2.54 18:34:50 INFO - PID 22982 | Values: 56.0 60.0 59.0 55.0 59.0 61.0 55.0 54.0 56.0 56.0 18:34:50 INFO - PID 22982 | 18:34:50 INFO - PID 22982 | [#10] stanford-crypto-aes Cycles:10 Average:78.20 Median:78.50 stddev:4.32 (5.5%) stddev-sans-first:4.35 18:34:50 INFO - PID 22982 | Values: 82.0 74.0 76.0 76.0 77.0 73.0 78.0 79.0 88.0 79.0 18:34:50 INFO - PID 22982 | 18:34:50 INFO - PID 22982 | [#11] stanford-crypto-ccm Cycles:10 Average:127.40 Median:124.00 stddev:16.36 (13.2%) stddev-sans-first:17.30 18:34:50 INFO - PID 22982 | Values: 124.0 120.0 124.0 124.0 120.0 129.0 120.0 117.0 123.0 173.0 18:34:50 INFO - PID 22982 | 18:34:50 INFO - PID 22982 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:172.70 Median:174.00 stddev:2.87 (1.6%) stddev-sans-first:2.98 18:34:50 INFO - PID 22982 | Values: 171.0 173.0 174.0 168.0 175.0 174.0 177.0 175.0 171.0 169.0 18:34:50 INFO - PID 22982 | 18:34:50 INFO - PID 22982 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.20 Median:66.50 stddev:1.75 (2.6%) stddev-sans-first:1.67 18:34:50 INFO - PID 22982 | Values: 64.0 65.0 65.0 64.0 68.0 68.0 66.0 67.0 66.0 69.0 18:34:50 INFO - PID 22982 | -------- Summary: end -------- 18:34:50 INFO - PID 22982 | 18:34:50 INFO - PID 22982 | 18:34:50 INFO - PID 22982 | ###!!! [Parent][MessageChannel] Error: (msgtype=0x150082,name=PBrowser::Msg_Destroy) Closed channel: cannot send/recv 18:34:50 INFO - PID 22982 | 18:34:50 INFO - PID 22982 | 18:34:50 INFO - PID 22982 | ###!!! [Child][MessageChannel] Error: (msgtype=0x150001,name=PBrowser::Msg_AsyncMessage) Closed channel: cannot send/recv 18:34:50 INFO - PID 22982 | 18:34:51 INFO - PID 22982 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 18:34:51 INFO - TEST-INFO | 22982: exit 0 18:34:51 INFO - TEST-OK | kraken | took 62626ms 18:34:51 INFO - SUITE-END | took 1173s 18:34:51 INFO - Completed test suite (00:19:33) 18:34:51 INFO - javascript benchmark 18:34:51 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8512.398216531548, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3325.850336734848, 3360.0927749507628, 3360.3111362170766, 3370.5618269624947, 3381.746369022324, 6251.71867265973, 6327.683615819208, 6348.238211414631, 6356.269029061659, 6393.968030159843, 6145.969270153643, 6273.529485288599, 6296.937030629692, 6305.180326557541, 6353.491720662348, 5835.883282334372, 5905.970470147643, 5917.911231331534, 5951.0, 5959.940400596003, 177.53255148213137, 5791.971040144815, 5844.941550584483, 5880.206172166767, 5952.494038006775, 5783.652980821164, 5809.448102430262, 5818.1272809078555, 5914.792982245601, 5924.200232968537, 5815.941840581625, 5820.883582328372, 5893.616914900518, 5894.292684877799, 5905.616134951214, 5805.245318108661, 5822.214001109881, 5874.441928016831, 5874.823755287348, 5890.587658863881, 6363.204599425072, 6368.522360822957, 6399.26408463028, 6404.071409645622, 6409.4551963083195, 6597.86804263912, 6770.458363330971, 6787.626680532569, 6810.863782724317, 6913.516053876231, 6757.864842703168, 6768.56004359715, 6778.22050464198, 6779.966100169517, 6783.3216678332265, 6191.442770150663, 6302.0, 6319.3680631936895, 6319.58922670025, 6326.051092336141, 7054.118235220598, 7070.646467676698, 7073.08049953513, 7107.0, 7117.110361204849, 6708.2956289589865, 6714.496412769062, 6797.864042719118, 6808.727650894008, 6808.8297792554595, 6808.11494505721, 6843.076184715099, 6899.793006209822, 6919.792406227822, 6928.757493487704, 6641.601503909781, 6730.394264516217, 6747.089142965637, 6795.0, 6821.249662537117, 6844.726210951506, 6851.897221541681, 6894.172699276019, 6906.896396554056, 6922.723091076301, 5295.841124766263, 5329.520343169133, 5330.147176451749, 5330.413654498002, 5347.41178470368, 4654.138984287918, 4659.534046595313, 4687.835925742583, 4713.67004309695, 4725.385699858996, 5948.078047902582, 5997.220361352996, 6017.338092809788, 6023.548233882389, 6024.728887200086, 6804.251532331439, 6819.488538359643, 6881.346272104142, 6913.2049814272, 6924.61914594692, 5856.092305692625, 5892.970535147297, 5943.435373639497, 5986.880262394815, 6012.188354572077, 5814.912776308359, 5833.358330583633, 5855.619384739978, 5889.234399528034, 5892.734826932712, 6695.497837662195, 6695.665216739241, 6699.39705426514, 6706.362895524917, 6710.966445167743, 6841.452683785385, 6843.281455447106, 6846.246912839583, 6933.7226510939, 6945.722171113301, 6491.4806815455595, 6568.934310657023, 6577.243616983917, 6667.066610674607, 6674.332566743286, 6640.0, 6724.697388617622, 6731.158605174353, 6731.4951378647775, 6776.864462710817, 6897.896531552131, 6911.550749201286, 6983.022376867141, 6989.1962424321855, 6992.895106573304, 6625.072489851521, 6635.0710900473005, 6671.499637527108, 6704.363085507064, 6759.628220447923, 6497.707603157964, 6556.901646475211, 6557.114789503356, 6636.535442519171, 6720.395164435225, 6455.2576453708425, 6533.346665333429, 6534.901976470262, 6537.738490460329, 6544.738210471528, 6622.900656490253, 6655.068290439439, 6719.899201512079, 6740.0, 6749.42629876466, 6472.935270647234, 6522.184726909137, 6536.575122617015, 6541.901871472027, 6556.967215164084], "value": 6192.781412849208, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [686.8420263339432, 768.8193274580473, 815.6574238819695, 838.2372041442287, 1120.9327440353584, 30788.23029424263, 31362.05913822583, 40564.391534126975, 41767.79116104423, 41809.58190418095, 40386.192276154536, 40460.190796184055, 40494.7975260124, 40701.79649101751, 40733.18533629326, 7607.7717668469895, 7774.766756997271, 7813.21867813217, 7821.100573434042, 7822.452428330019, 7653.15815260321, 7768.339691126261, 7774.339181169579, 7801.3758899288205, 7802.2197780222095, 7750.069991601016, 7793.454458187929, 7815.2966233039015, 7850.175731548191, 7856.882146767803, 7762.30139287464, 7764.767056988299, 7811.960940195319, 7836.294733473957, 7840.137584865717, 7637.579933103732, 7686.730964416274, 7687.154413014564, 7696.7306144284685, 7714.035745531809, 36311.63688363109, 36434.45348319777, 36449.0, 36717.44923826145, 36819.263614727824, 3487.128217945514, 3497.5977762557122, 3512.771669841478, 3526.876559320437, 3540.309639620275, 29908.401831963456, 29967.250818729488, 29975.0, 30056.699433005608, 30110.24724381886, 5122.1804511278015, 5158.380994280673, 5190.558802501791, 5191.896162076775, 5244.895102097975, 183623.0, 184311.0, 185136.0, 185723.0, 186370.0, 46551.30173047407, 46582.301265481045, 46694.53305466902, 46697.0, 46755.0, 49216.0, 49240.0, 49267.26099108516, 49403.258951115764, 49404.50595493999, 13600.251986140856, 13834.515791947233, 15052.924735376253, 15386.538403847902, 15396.692066158614, 12323.383830808423, 12534.0, 12541.93729031349, 12560.623181304576, 12564.81152782709, 13547.458101675822, 13619.86380136186, 13641.795373069412, 13696.315184240748, 13858.099223550436, 5569.498745112878, 5601.63589366698, 5722.599418040697, 5722.7424765885635, 5770.16332631766, 1004.3973615830444, 1020.5611587017505, 1024.4826362686795, 1025.1286406554368, 1028.4240825137947, 22625.88687056587, 23052.884735576215, 23097.653535196987, 23187.652185217234, 23239.0, 1090.5441235949936, 1092.956281748721, 1107.1751545098953, 1113.3264375053138, 1118.72031992002, 12008.819867701992, 12142.635720928387, 15847.841521584867, 15887.52337429879, 16280.185990700418, 233279.0, 233573.0, 233963.0, 234906.0, 234954.0, 49385.0, 49630.50369496259, 49782.99999999927, 49892.25161622579, 49992.0, 1383.4189640351076, 1408.5985494134122, 1411.2238268952035, 1415.8867290616727, 1431.1556181852966, 27123.186304410898, 27409.17772466829, 27477.45045099127, 27674.86162569174, 27741.029063982664, 38242.808785955895, 38501.80749096237, 38502.0, 38576.22847543089, 38594.2281154381, 203370.0, 203418.0, 203742.0, 204306.0, 204420.0, 33914.32171356608, 35151.64848351586, 37124.814375929025, 37154.44268335923, 37192.256154877294, 43856.78071609622, 45432.09135817331, 45520.31719524276, 45573.77213113913, 45574.54425455703, 33364.1658958528, 33434.33131337408, 33565.496517551765, 33901.83049084739, 34067.318653627284, 33472.0, 33473.83263083669, 33530.497042543895, 33667.83166084154, 33814.830925845214, 37046.0, 37474.437883432314, 37542.24915501729, 37577.4363384544, 37737.43393849149, 31950.840245798623, 32032.039038828872, 32172.0, 32457.188570285933, 32473.188170295933, 23343.649845252676, 23672.763272367745, 23823.0, 24082.39794005164, 24253.636195457435], "value": 17211.578370150142, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [553.1205383440329, 555.1395337227297, 560.162556977319, 560.9411011843756, 561.646162917362, 21451.249206277782, 22291.665625015616, 22434.32697019092, 22459.887700561496, 23084.538309233805, 22300.88849555754, 22980.425489362773, 23037.539249215006, 23046.423839404022, 23061.76938230617, 6480.837979050526, 6605.108310378086, 6618.0, 6618.8676226475445, 6631.104800851896, 5907.143464197688, 6449.097126402307, 6453.193350831146, 6456.548041637087, 6480.060391243291, 6422.807315780511, 6434.9678251608675, 6437.806865794034, 6438.839029024265, 6442.098106265126, 6438.581492202992, 6445.1943507061615, 6446.033095035758, 6460.773872914472, 6463.709133088999, 5886.08765641322, 5932.436418540232, 5935.614185077956, 5961.37405572413, 5986.97006514969, 27279.45441091187, 28078.157655270374, 28564.57153142684, 28610.141695748953, 28695.28261793451, 1959.421970518686, 1994.172418446343, 1994.3518356534184, 2003.669394549912, 2005.2380095563738, 18710.438686839418, 18726.719099213522, 19236.422907312804, 19260.711089333672, 19548.902255488774, 2443.0472115874823, 2444.449998750267, 2501.2996361018945, 2502.1117503286255, 2510.635957786129, 132764.0, 135992.0, 136026.31986840002, 136501.0, 136625.0, 14258.146440962608, 14347.354369053417, 14389.56831295042, 14671.266436678123, 14689.0, 22553.548929021326, 22585.322440326818, 22603.547929041328, 22636.886815566148, 22652.546949060925, 9731.90268097324, 10024.147947424404, 10030.799384012278, 10068.748281293027, 10138.188944884392, 7969.0, 8305.0, 9245.907540924505, 9343.205827504738, 9388.436693798394, 8449.493030418194, 9051.366404351764, 9086.681966131153, 9118.544072796467, 9190.448573085503, 2604.793258886884, 2634.77604403624, 2642.973570264311, 2654.137405343271, 2733.9474302393724, 952.4808979106404, 979.647326962293, 982.7690492734201, 995.8257304971601, 995.9701208963743, 19825.30611428593, 19847.702284465744, 20179.192832286542, 20302.898485507478, 20405.163535281896, 1028.2082796246848, 1039.4750650921237, 1039.4802598700649, 1044.4777611194402, 1051.542578978147, 10751.086157676686, 10901.945490272656, 11016.11871050314, 11093.0, 11099.334039957628, 458083.0, 465644.0, 470716.0, 476122.0, 479679.0, 107006.46496767466, 107721.46139269411, 110287.0, 110716.4464177674, 110975.0, 1131.9094472442184, 1145.6563031090639, 1166.177844619558, 1167.9416029198505, 1168.2756690851727, 17438.0, 17501.82498175002, 17539.473815785546, 17628.647427051645, 17902.91048544749, 54824.0, 55264.17103743528, 55832.16251756308, 56782.432175679365, 57717.42282577288, 64958.0, 65699.34300656932, 66095.0, 66515.66742166452, 66911.0, 11870.821937671115, 12509.186902851285, 13967.511137110152, 14282.785758213427, 14451.0, 22596.096156154228, 22622.773772262066, 22745.886270568542, 22806.087756489556, 22869.542609147393, 17509.474715758548, 18275.36036238725, 18360.265589376275, 18389.0, 18426.078696064877, 15784.368625254861, 15846.207689615243, 16889.155542223085, 17260.741088883427, 17485.388011419538, 18741.71887421717, 18830.905845470686, 18936.715949260495, 18941.0, 19015.42953711391, 14308.213048282445, 14480.565583032527, 14574.635634109232, 14594.343254553358, 14611.92694036523, 18150.5462363442, 18158.0, 18376.448706538824, 18462.907685461487, 18573.164207610418], "value": 11715.941417717662, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1233.457278797329, 1338.4110991163886, 1341.5572860955879, 1356.1727346318744, 1357.3484727330874, 14198.57404277872, 14939.327730252138, 14944.402223911044, 14951.92524037381, 14962.25188740564, 14272.500462483815, 15394.61513462164, 15405.306761195745, 15418.152001639904, 15433.92283038586, 2730.877110530021, 2814.535601625729, 2815.957760633592, 2816.676082250547, 2817.3942602340494, 2625.0681008242086, 2816.4226333601564, 2816.4367126574666, 2816.633837601109, 2819.2951762059483, 2740.0272903119403, 2759.434315965222, 2785.791065670073, 2792.2321361625613, 2792.9301767455772, 2640.7623313901745, 2720.1975417251956, 2751.2709132080013, 2792.4415116976484, 2824.1104052223586, 2730.7678847298002, 2738.4796888591104, 2739.1782465260344, 2740.328619488218, 2742.9177124686294, 14643.63390915225, 14714.63213419673, 14877.18175500347, 14911.328990195467, 14942.103473791607, 7596.544207347577, 7815.335696465807, 7872.488288261245, 7896.842063158762, 7900.683972641087, 5525.392206857283, 5607.327120745495, 5641.238432811559, 5781.277340332458, 5800.0, 1918.808119188084, 1939.398786376228, 1944.805519448058, 1977.5155087003632, 1989.910454029572, 24588.754112458824, 24802.5039499209, 24955.126570570126, 25097.874510627513, 25991.610125848318, 6364.077208804697, 6561.967190164114, 6615.206175259, 6749.831254218684, 6751.56114852533, 15993.28030238642, 16011.0, 16026.519204423887, 16034.599135021484, 16066.277017534005, 7609.695612175451, 7695.192004839523, 7719.459637825298, 7759.068911730628, 7760.883586746203, 7106.928930710626, 7214.314640109181, 7341.70633174667, 7343.485955983137, 7346.485745997729, 5005.124103281911, 5160.612954028463, 5183.766730497136, 5209.921851172236, 5209.947900521022, 1463.2244910197612, 1480.2450750117519, 1489.2181604657426, 1494.7608382658939, 1509.4792296657636, 833.2500749325656, 855.2986551028097, 862.2369203255164, 866.8309679612541, 877.5349064995562, 7080.433565314763, 7130.322619351154, 7162.212156662763, 7190.1731300901065, 7232.42140628759, 666.1506579111672, 681.4412182010802, 690.4890381117937, 690.7513295213719, 701.6281370873445, 5602.635828671205, 5866.384029676822, 5898.203742494794, 5960.821175364746, 5995.970020149872, 21083.894580527307, 21360.679589806165, 21482.89258553697, 21553.676694849277, 21562.245321413677, 19996.90001549983, 20827.06278217484, 21245.89377053105, 21279.0, 21366.14535418597, 9535.809283814286, 9792.657256995972, 10812.35125892449, 11157.107431405468, 11157.107431405468, 8512.233898949124, 8721.869171962553, 8830.072842351718, 8870.379073465054, 9260.166585007382, 16836.07401592924, 17424.30302787925, 17481.388151414638, 17757.378491752726, 17868.374606888698, 17196.742048869026, 17575.82424175742, 17795.644087117926, 18042.458726238234, 18059.367922122663, 11567.190296679151, 11825.46785394642, 11880.108991825475, 11885.405729713653, 11886.34625095628, 13520.79718804199, 13648.249346285653, 13768.655783605089, 13784.448622055006, 13799.724005520035, 10902.400367979837, 10990.890091098987, 11104.944475277895, 11113.833292500458, 11339.829902551302, 10857.67426977192, 10872.61945831892, 11021.504032318404, 11041.558337666725, 11150.219484635998, 9980.60077596888, 9986.35088719231, 10058.094771470604, 10060.144887684628, 10067.647632332833, 7627.771166865003, 7644.655990480553, 7671.8849217262905, 7686.769396918102, 7772.222777722182, 7419.146798118286, 7440.702371905063, 7541.660625271986, 7557.811054723676, 7698.153203147761], "value": 6562.154916561512, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [492.1952607486758, 512.3825789923144, 522.736018310753, 523.1916688715934, 526.2290744059951, 6352.968235158829, 6424.293327733952, 6427.553800394909, 6440.838979025527, 6476.611403315805, 6261.405166509186, 6399.0, 6403.58376705515, 6407.327230640785, 6408.743650253996, 4975.552200301972, 5087.974560127194, 5094.414142373637, 5203.401608814987, 5286.65636733613, 5130.2300706256465, 5139.717315547643, 5140.768665410047, 5148.0, 5155.690658560479, 5049.621278404116, 5051.267566202899, 5052.26742122392, 5074.112030394685, 5076.593872490211, 5022.799088036474, 5057.645964782467, 5087.771050302746, 5118.6416950813455, 5131.461196574381, 4546.727196368195, 4551.590356867898, 4556.521565235669, 4556.724117247176, 4561.954380456186, 6256.78101266458, 6276.623402595859, 6358.427741503287, 6358.618482891041, 6415.93584064158, 5727.82105931208, 5757.905997860393, 5790.826275211751, 5793.65238085712, 5797.739101740432, 6652.733890644368, 6682.632455214961, 6684.966575167142, 6706.378555579981, 6714.932850671479, 4918.803247870081, 4937.604991600663, 4949.554540091373, 4954.603631709455, 4955.702657840541, 6813.591184528944, 6857.348551887562, 6971.8605627887155, 7026.086608740882, 7181.0, 4951.925721114186, 4955.306257124005, 5036.891883785562, 5053.545180933734, 5068.746562671926, 5055.469175736568, 5087.456271864068, 5090.345637667773, 5109.3613298337705, 5148.352527191263, 5785.768569257267, 5817.2257461474655, 5817.3019237690905, 5820.9544366665605, 5821.446962538552, 6705.564138330993, 6730.528862979642, 6746.291639377795, 6790.8302292442095, 6792.150981127359, 1763.092007616053, 1788.9534622245872, 1792.7042038063835, 1797.4517772079641, 1804.2602532961423, 1182.7634473105413, 1196.2284326609288, 1200.9279443233434, 1212.3392750950752, 1240.9813852792215, 5986.161937328778, 6004.699765011732, 6034.668093254825, 6043.576949613573, 6085.726142323606, 6866.862662746717, 6895.586264824126, 6902.896456553156, 6961.338672826073, 6982.47631427645, 5947.405259474018, 5963.671998040149, 5963.821085367446, 6025.18659980897, 6042.486388656926, 3664.9633503665154, 3667.3215455140885, 3678.1540245743627, 3683.3185860615336, 3713.2918857325394, 6659.966700166565, 6661.500387470959, 6681.498887583451, 6685.39831415175, 6743.494237932175, 6638.535302528777, 7057.929420705727, 7159.92840071603, 7184.82037949045, 7234.63826808668, 6512.473830687627, 6521.380468855451, 6618.305077966744, 6619.702113404908, 6623.602583844889, 6778.8644227116165, 6835.384815366641, 6863.759768408082, 6864.897026544706, 6940.687669054803, 6785.796426107225, 6799.864002720017, 6830.556013859084, 6883.483738719516, 6919.792406227822, 6596.0, 6616.768413105518, 6633.933660663524, 6651.301613330531, 6691.531592788653, 5016.849494515171, 5027.924581131213, 5051.924221136759, 5065.468125846881, 5089.847304580869, 6632.403083722488, 6756.4257038150345, 6767.695453704693, 6977.8255543609785, 7004.719811207495, 6571.704273307617, 6627.204735431586, 6676.298988606127, 6713.328667133248, 6775.1531058617675, 6562.2125344957, 6598.967005164944, 6634.767783127568, 6746.123004009447, 6771.187457505131, 6545.574537655038, 6549.934500654933, 6575.0, 6580.670966451754, 6659.766908158192, 6634.445738390657, 6650.268470468148, 6685.130932978682, 6761.93238067613, 6780.559263647847, 6427.839304017437, 6490.675466226764, 6499.28507864144, 6550.279469258282, 6646.4350530205475], "value": 5165.035794017811, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [870.2820173356978, 894.3247847874853, 899.6761165980242, 902.4449963272589, 909.8316811389893, 18350.90824545877, 18743.90628046861, 19388.32140875068, 19471.610567788637, 19491.805081949176, 19733.802661973376, 19750.308739194094, 19786.505337366536, 19826.90086549569, 19985.600287994268, 881.7266647339313, 947.5499137909507, 950.0357137505438, 952.333366643349, 953.7472569769017, 959.1751094059103, 961.2406199102701, 962.9888616952207, 968.6851773173712, 969.0842154164318, 939.9154076133146, 983.3264214013388, 985.8126955878396, 1021.3616489693941, 1023.4422239879284, 977.301229620821, 978.0854900667898, 978.6085565773676, 978.7602037500786, 978.8189185000798, 720.5568575326189, 721.39763297646, 722.7867779005206, 723.0419693905544, 723.1177962885324, 18302.0, 18398.89606623607, 18542.721859172125, 18607.255709771594, 18782.248710051583, 21257.255996040218, 21279.787202127936, 21588.24441144568, 21894.89052554743, 21925.0, 17023.489295321036, 17056.0, 17147.0, 17222.224999875034, 17223.655526889394, 3747.400415933465, 3751.624837516254, 3762.49206357141, 3766.6986641068647, 3771.679407250387, 120734.0, 121658.0, 121921.99999999911, 122236.0, 125207.0, 16171.272292746855, 16282.674346513004, 16334.01995880251, 16825.747613785803, 16831.158442078093, 29927.700722992926, 30572.84713576418, 30878.84560577183, 30909.536356954664, 30942.22644433907, 7650.540967541965, 8051.637676304464, 8361.456505327134, 8582.399232053822, 8619.6983105591, 4048.0891799344913, 4202.768847713405, 4206.369044643328, 4215.704900656925, 4217.578242175758, 5252.159654455345, 5380.3274590676165, 5388.137897936312, 5388.218708287276, 5670.659760414388, 1057.1384321777728, 1072.8766191887876, 1080.719013056611, 1081.7728277061747, 1082.5561519776854, 685.9622720750407, 700.6111608057577, 703.1351437731619, 703.1632357494565, 710.1903829634222, 11654.475548600334, 12173.634790956285, 12376.257424554555, 12615.747685046248, 13502.122362046435, 699.3181647893264, 700.0968750312143, 708.4544900426746, 711.2425267090532, 716.8279612892868, 5710.628809127394, 5725.685087320154, 5783.826485205451, 5812.157237200667, 5894.705264736746, 113139.0, 114931.42534287443, 115301.0, 115320.0, 116235.41882290704, 91372.0, 92577.07422925819, 93138.0, 93981.5300923491, 95492.0, 78421.0, 81437.0, 81791.5910420456, 84036.0, 84793.57603212068, 10694.946525267635, 10796.298240614335, 10888.564457421615, 11013.449327533752, 11172.441377931233, 39853.80073099616, 40375.0, 40546.59453405428, 40560.36357636744, 42634.78682606691, 53024.46975530349, 53427.465725343805, 54222.0, 54842.451575483734, 56003.43996559982, 15824.44614438489, 15924.28340724647, 16017.599560011093, 16147.434839780552, 16267.674646507241, 17399.82600173982, 17729.822701772817, 19238.71141932844, 20684.793152068287, 20832.166713331902, 18260.178291976626, 18334.72497912559, 18469.168887399832, 18503.81496185021, 18600.441986740418, 18884.244630214638, 19082.236710531422, 19146.329878454744, 19213.615727685647, 19439.514012149808, 17694.557636059202, 17939.0, 17961.640767184843, 17999.820001799813, 18103.0, 13614.523491677744, 13873.722525549634, 14195.645108871948, 14320.355583998537, 14413.063150895157, 14282.577232120493, 14920.0, 14939.0, 15201.847981520043, 15225.238738063274], "value": 8988.41765689915, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1516.1000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 114.0, 99.0, 106.0, 107.0, 113.0, 109.0, 97.0, 108.0, 110.0], "value": 106.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 116.0, 147.0, 160.0, 123.0, 146.0, 145.0, 121.0, 171.0, 118.0], "value": 139.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 162.0, 162.0, 165.0, 162.0, 167.0, 171.0, 169.0, 166.0, 163.0], "value": 165.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 77.0, 74.0, 171.0, 77.0, 74.0, 76.0, 75.0, 181.0, 77.0], "value": 95.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 79.0, 131.0, 82.0, 78.0, 79.0, 126.0, 78.0, 83.0, 139.0], "value": 95.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 118.0, 124.0, 115.0, 114.0, 117.0, 115.0, 113.0, 120.0, 114.0], "value": 116.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 148.0, 148.0, 147.0, 147.0, 149.0, 150.0, 147.0, 148.0, 148.0], "value": 147.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 93.0, 94.0, 94.0, 93.0, 94.0, 93.0, 98.0, 94.0, 93.0], "value": 94.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 53.0, 51.0, 51.0, 56.0, 57.0, 60.0, 53.0, 57.0, 53.0], "value": 54.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 60.0, 59.0, 55.0, 59.0, 61.0, 55.0, 54.0, 56.0, 56.0], "value": 57.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 74.0, 76.0, 76.0, 77.0, 73.0, 78.0, 79.0, 88.0, 79.0], "value": 78.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [124.0, 120.0, 124.0, 124.0, 120.0, 129.0, 120.0, 117.0, 123.0, 173.0], "value": 127.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 173.0, 174.0, 168.0, 175.0, 174.0, 177.0, 175.0, 171.0, 169.0], "value": 172.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 65.0, 65.0, 64.0, 68.0, 68.0, 66.0, 67.0, 66.0, 69.0], "value": 66.2, "unit": "score"}]}]} 18:34:51 INFO - Return code: 0 18:34:51 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:34:51 INFO - # TBPL SUCCESS # 18:34:51 INFO - Running post-action listener: _package_coverage_data 18:34:51 INFO - Running post-action listener: _resource_record_post_action 18:34:51 INFO - [mozharness: 2017-08-26 01:34:51.890636Z] Finished run-tests step (success) 18:34:51 INFO - Running post-run listener: _resource_record_post_run 18:34:51 INFO - Total resource usage - Wall time: 1192s; CPU: 13.0%; Read bytes: 16384; Write bytes: 440987648; Read time: 192; Write time: 343008 18:34:51 INFO - TinderboxPrint: CPU usage
13.1% 18:34:51 INFO - TinderboxPrint: I/O read bytes / time
16,384 / 192 18:34:51 INFO - TinderboxPrint: I/O write bytes / time
440,987,648 / 343,008 18:34:51 INFO - TinderboxPrint: CPU idle
8,254.4 (86.8%) 18:34:51 INFO - TinderboxPrint: CPU user
1,198.2 (12.6%) 18:34:51 INFO - TinderboxPrint: Swap in / out
0 / 0 18:34:51 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 117633024; Read time: 0; Write time: 104856 18:34:51 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 18:34:51 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 12288; Write bytes: 322232320; Read time: 176; Write time: 238128 18:34:52 INFO - Running post-run listener: _upload_blobber_files 18:34:52 INFO - Blob upload gear active. 18:34:52 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:34:52 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 18:34:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 18:34:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 18:34:52 INFO - (blobuploader) - INFO - Open directory for files ... 18:34:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 18:34:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:34:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:34:53 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 18:34:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:34:53 INFO - (blobuploader) - INFO - Done attempting. 18:34:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 18:34:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:34:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:34:55 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 18:34:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:34:55 INFO - (blobuploader) - INFO - Done attempting. 18:34:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 18:34:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:34:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:34:56 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 18:34:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:34:56 INFO - (blobuploader) - INFO - Done attempting. 18:34:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:34:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:34:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:35:03 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:35:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:35:03 INFO - (blobuploader) - INFO - Done attempting. 18:35:03 INFO - (blobuploader) - INFO - Iteration through files over. 18:35:03 INFO - Return code: 0 18:35:03 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:35:03 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:35:03 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/88c97bdb2c9f4354634efe7a40d57d960929bf3ee7f87db898d1e88e09510785937aea84b82c64ecc205a1532c300847ba6eac70727e22de5bc9f5fd2da36fcb", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/89ea33beccce1263f65d4d17e7da368275b834d85926d7d70c46678fe44cca4c112001a42b4181f15df076e4bedb44630aed185e905d45afb45496105711e1a3", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d6e5da2a0ca926c2e5da33b62685eea6b59ad7d771cc99301f4008ecf35d7457005c82eccd4c36f4772abf6887febdd304cb8da1da6ab7ca476b9b69f24eca1d", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5e2e806f4cf9b9a5aa955e0e87444576d162648db5bb0543280caac19c4e07d2c8418a5d060302dbeaa06c06751925449022c054b1e3f62f17ff44d70d626afc"} 18:35:03 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:35:03 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:35:03 INFO - Contents: 18:35:03 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/88c97bdb2c9f4354634efe7a40d57d960929bf3ee7f87db898d1e88e09510785937aea84b82c64ecc205a1532c300847ba6eac70727e22de5bc9f5fd2da36fcb", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/89ea33beccce1263f65d4d17e7da368275b834d85926d7d70c46678fe44cca4c112001a42b4181f15df076e4bedb44630aed185e905d45afb45496105711e1a3", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d6e5da2a0ca926c2e5da33b62685eea6b59ad7d771cc99301f4008ecf35d7457005c82eccd4c36f4772abf6887febdd304cb8da1da6ab7ca476b9b69f24eca1d", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5e2e806f4cf9b9a5aa955e0e87444576d162648db5bb0543280caac19c4e07d2c8418a5d060302dbeaa06c06751925449022c054b1e3f62f17ff44d70d626afc"} 18:35:03 INFO - Running post-run listener: copy_logs_to_upload_dir 18:35:03 INFO - Copying logs to upload dir... 18:35:03 INFO - mkdir: /builds/slave/test/build/upload/logs 18:35:03 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1253.036773 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 53 secs) (at 2017-08-25 18:35:03.497014) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-25 18:35:03.501001) ========= 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=920470bf432669ad7e3a5387000001ca-1503710048.144063-1391538418 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/88c97bdb2c9f4354634efe7a40d57d960929bf3ee7f87db898d1e88e09510785937aea84b82c64ecc205a1532c300847ba6eac70727e22de5bc9f5fd2da36fcb", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/89ea33beccce1263f65d4d17e7da368275b834d85926d7d70c46678fe44cca4c112001a42b4181f15df076e4bedb44630aed185e905d45afb45496105711e1a3", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d6e5da2a0ca926c2e5da33b62685eea6b59ad7d771cc99301f4008ecf35d7457005c82eccd4c36f4772abf6887febdd304cb8da1da6ab7ca476b9b69f24eca1d", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5e2e806f4cf9b9a5aa955e0e87444576d162648db5bb0543280caac19c4e07d2c8418a5d060302dbeaa06c06751925449022c054b1e3f62f17ff44d70d626afc"} build_url:https://queue.taskcluster.net/v1/task/DhX5PAXHThCBgtR6MhReYw/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011399 build_url: 'https://queue.taskcluster.net/v1/task/DhX5PAXHThCBgtR6MhReYw/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/88c97bdb2c9f4354634efe7a40d57d960929bf3ee7f87db898d1e88e09510785937aea84b82c64ecc205a1532c300847ba6eac70727e22de5bc9f5fd2da36fcb", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/89ea33beccce1263f65d4d17e7da368275b834d85926d7d70c46678fe44cca4c112001a42b4181f15df076e4bedb44630aed185e905d45afb45496105711e1a3", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d6e5da2a0ca926c2e5da33b62685eea6b59ad7d771cc99301f4008ecf35d7457005c82eccd4c36f4772abf6887febdd304cb8da1da6ab7ca476b9b69f24eca1d", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5e2e806f4cf9b9a5aa955e0e87444576d162648db5bb0543280caac19c4e07d2c8418a5d060302dbeaa06c06751925449022c054b1e3f62f17ff44d70d626afc"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-25 18:35:03.527819) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-25 18:35:03.528150) ========= 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=920470bf432669ad7e3a5387000001ca-1503710048.144063-1391538418 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004468 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-25 18:35:03.611438) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-25 18:35:03.613763) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-25 18:35:03.614063) ========= ========= Total master_lag: 0.22 =========