builder: mozilla-central_ubuntu64_hw_styloseq_test-dromaeojs-e10s slave: talos-linux64-ix-012 starttime: 1503706135.31 results: success (0) revision: 86a3c73984a23b1d524eadbd4a62b9b3fa27e46a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-08-25 17:08:55.311328) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-08-25 17:08:55.311788) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-25 17:08:55.312063) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-25 17:08:55.327929) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-25 17:08:55.328253) ========= 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=2c5dd2b0a310ecf02fd87158000001b4-1503706134.484861-159888122 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-08-25 17:08:55-- 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% 99.6M=0s 2017-08-25 17:08:55 (99.6 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.352207 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-25 17:08:55.698654) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-25 17:08:55.699000) ========= 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=2c5dd2b0a310ecf02fd87158000001b4-1503706134.484861-159888122 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.023006 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-25 17:08:55.751474) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-25 17:08:55.751829) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 86a3c73984a23b1d524eadbd4a62b9b3fa27e46a --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 86a3c73984a23b1d524eadbd4a62b9b3fa27e46a --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=2c5dd2b0a310ecf02fd87158000001b4-1503706134.484861-159888122 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-08-25 17:08:55,936 truncating revision to first 12 chars 2017-08-25 17:08:55,936 Setting DEBUG logging. 2017-08-25 17:08:55,936 attempt 1/10 2017-08-25 17:08:55,936 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/86a3c73984a2?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-08-25 17:08:56,365 unpacking tar archive at: mozilla-central-86a3c73984a2/testing/mozharness/ program finished with exit code 0 elapsedTime=0.767530 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-25 17:08:56.544543) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-25 17:08:56.544897) ========= script_repo_revision: 86a3c73984a23b1d524eadbd4a62b9b3fa27e46a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-25 17:08:56.545287) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-25 17:08:56.545557) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-25 17:08:56.557766) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 53 secs) (at 2017-08-25 17:08:56.558145) ========= /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=2c5dd2b0a310ecf02fd87158000001b4-1503706134.484861-159888122 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 17:08:56 INFO - MultiFileLogger online at 20170825 17:08:56 in /builds/slave/test 17:08:56 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 17:08:56 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 17:08:56 INFO - {'append_to_log': False, 17:08:56 INFO - 'base_work_dir': '/builds/slave/test', 17:08:56 INFO - 'blob_upload_branch': 'Firefox', 17:08:56 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 17:08:56 INFO - 'branch': 'Firefox', 17:08:56 INFO - 'buildbot_json_path': 'buildprops.json', 17:08:56 INFO - 'code_coverage': False, 17:08:56 INFO - 'config_files': ('talos/linux_config.py',), 17:08:56 INFO - 'default_actions': ('clobber', 17:08:56 INFO - 'read-buildbot-config', 17:08:56 INFO - 'download-and-extract', 17:08:56 INFO - 'populate-webroot', 17:08:56 INFO - 'create-virtualenv', 17:08:56 INFO - 'install', 17:08:56 INFO - 'setup-mitmproxy', 17:08:56 INFO - 'run-tests'), 17:08:56 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:08:56 INFO - 'disable_ccov_upload': False, 17:08:56 INFO - 'download_minidump_stackwalk': True, 17:08:56 INFO - 'download_symbols': 'ondemand', 17:08:56 INFO - 'enable_stylo': False, 17:08:56 INFO - 'enable_webrender': False, 17:08:56 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 17:08:56 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:08:56 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:08:56 INFO - 'gecko_profile': False, 17:08:56 INFO - 'gecko_profile_interval': 0, 17:08:56 INFO - 'installer_path': 'installer.exe', 17:08:56 INFO - 'log_level': 'info', 17:08:56 INFO - 'log_name': 'talos', 17:08:56 INFO - 'log_to_console': True, 17:08:56 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 17:08:56 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 17:08:56 INFO - 'opt_config_files': (), 17:08:56 INFO - 'pip_index': False, 17:08:56 INFO - 'suite': 'dromaeojs-e10s', 17:08:56 INFO - 'system_bits': '32', 17:08:56 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:08:56 INFO - 'title': 'talos-linux64-ix-012', 17:08:56 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:08:56 INFO - 'use_talos_json': True, 17:08:56 INFO - 'verify': 'False', 17:08:56 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 17:08:56 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:08:56 INFO - 'work_dir': 'build'} 17:08:56 INFO - [mozharness: 2017-08-26 00:08:56.882921Z] Running clobber step. 17:08:56 INFO - Running pre-action listener: _resource_record_pre_action 17:08:56 INFO - Running main action method: clobber 17:08:56 INFO - rmtree: /builds/slave/test/build 17:08:56 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 17:08:57 INFO - Running post-action listener: _resource_record_post_action 17:08:57 INFO - [mozharness: 2017-08-26 00:08:57.939429Z] Finished clobber step (success) 17:08:57 INFO - [mozharness: 2017-08-26 00:08:57.939526Z] Running read-buildbot-config step. 17:08:57 INFO - Running pre-action listener: _resource_record_pre_action 17:08:57 INFO - Running main action method: read_buildbot_config 17:08:57 INFO - Using buildbot properties: 17:08:57 INFO - { 17:08:57 INFO - "project": "", 17:08:57 INFO - "product": "firefox", 17:08:57 INFO - "who": "kwierso@gmail.com", 17:08:57 INFO - "installer_path": "public/build/target.tar.bz2", 17:08:57 INFO - "builddir": "mozilla-central_ubuntu64_hw_styloseq_test-dromaeojs-e10s", 17:08:57 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 17:08:57 INFO - "buildername": "Ubuntu HW 12.04 x64 stylo-sequential mozilla-central talos dromaeojs-e10s", 17:08:57 INFO - "stage_platform": "linux64-stylosequential", 17:08:57 INFO - "basedir": "/builds/slave/test", 17:08:57 INFO - "buildnumber": 48, 17:08:57 INFO - "platform": "ubuntu64_hw_styloseq", 17:08:57 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 17:08:57 INFO - "slavebuilddir": "test", 17:08:57 INFO - "taskId": "D-5tcuHiQZGGvu20pPowLw", 17:08:57 INFO - "branch": "mozilla-central", 17:08:57 INFO - "script_repo_revision": "production", 17:08:57 INFO - "revision": "86a3c73984a23b1d524eadbd4a62b9b3fa27e46a", 17:08:57 INFO - "slavename": "talos-linux64-ix-012", 17:08:57 INFO - "repo_path": "mozilla-central" 17:08:57 INFO - } 17:08:57 INFO - Finding installer, test and symbols from parent task. 17:08:57 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/D-5tcuHiQZGGvu20pPowLw'}, attempt #1 17:08:58 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/D-5tcuHiQZGGvu20pPowLw'}, attempt #1 17:08:58 INFO - Task dependencies: CvxZbX7nQM278U8qCtb6OQ 17:08:58 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CvxZbX7nQM278U8qCtb6OQ'}, attempt #1 17:08:59 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/CvxZbX7nQM278U8qCtb6OQ/artifacts/public/build/target.tar.bz2 17:08:59 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/CvxZbX7nQM278U8qCtb6OQ/artifacts/public/build/target.test_packages.json 17:08:59 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/CvxZbX7nQM278U8qCtb6OQ/artifacts/public/build/target.crashreporter-symbols.zip 17:08:59 INFO - Running post-action listener: _resource_record_post_action 17:08:59 INFO - [mozharness: 2017-08-26 00:08:59.309438Z] Finished read-buildbot-config step (success) 17:08:59 INFO - [mozharness: 2017-08-26 00:08:59.309658Z] Running download-and-extract step. 17:08:59 INFO - Running pre-action listener: _resource_record_pre_action 17:08:59 INFO - Running main action method: download_and_extract 17:08:59 INFO - mkdir: /builds/slave/test/build/tests 17:08: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')]} 17:08:59 INFO - trying https://queue.taskcluster.net/v1/task/CvxZbX7nQM278U8qCtb6OQ/artifacts/public/build/target.test_packages.json 17:08:59 INFO - Downloading https://queue.taskcluster.net/v1/task/CvxZbX7nQM278U8qCtb6OQ/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 17:08:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CvxZbX7nQM278U8qCtb6OQ/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 17:08:59 INFO - Downloaded 989 bytes. 17:08:59 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 17:08:59 INFO - Using the following test package requirements: 17:08:59 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 17:08:59 INFO - u'common': [u'target.common.tests.zip'], 17:08:59 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 17:08:59 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 17:08:59 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 17:08:59 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 17:08:59 INFO - u'mozbase': [u'target.common.tests.zip'], 17:08:59 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 17:08:59 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 17:08:59 INFO - u'web-platform': [u'target.common.tests.zip', 17:08:59 INFO - u'target.web-platform.tests.tar.gz'], 17:08:59 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 17:08:59 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 17:08:59 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CvxZbX7nQM278U8qCtb6OQ/artifacts/public/build/target.common.tests.zip 17:08:59 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CvxZbX7nQM278U8qCtb6OQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 17:08:59 INFO - Fetch https://queue.taskcluster.net/v1/task/CvxZbX7nQM278U8qCtb6OQ/artifacts/public/build/target.common.tests.zip into memory 17:09:00 INFO - Content-Length response header: 54979125 17:09:00 INFO - Bytes received: 54979125 17:09:11 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 17:09:11 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CvxZbX7nQM278U8qCtb6OQ/artifacts/public/build/target.common.tests.zip 17:09:11 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CvxZbX7nQM278U8qCtb6OQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 17:09:11 INFO - Fetch https://queue.taskcluster.net/v1/task/CvxZbX7nQM278U8qCtb6OQ/artifacts/public/build/target.common.tests.zip into memory 17:09:12 INFO - Content-Length response header: 54979125 17:09:12 INFO - Bytes received: 54979125 17:09:24 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CvxZbX7nQM278U8qCtb6OQ/artifacts/public/build/target.talos.tests.zip 17:09:24 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CvxZbX7nQM278U8qCtb6OQ/artifacts/public/build/target.talos.tests.zip'}, attempt #1 17:09:24 INFO - Fetch https://queue.taskcluster.net/v1/task/CvxZbX7nQM278U8qCtb6OQ/artifacts/public/build/target.talos.tests.zip into memory 17:09:25 INFO - Content-Length response header: 14496611 17:09:25 INFO - Bytes received: 14496611 17:09:25 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')]} 17:09:25 INFO - trying https://queue.taskcluster.net/v1/task/CvxZbX7nQM278U8qCtb6OQ/artifacts/public/build/target.tar.bz2 17:09:25 INFO - Downloading https://queue.taskcluster.net/v1/task/CvxZbX7nQM278U8qCtb6OQ/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 17:09:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CvxZbX7nQM278U8qCtb6OQ/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 17:09:27 INFO - Downloaded 61118480 bytes. 17:09:27 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/CvxZbX7nQM278U8qCtb6OQ/artifacts/public/build/target.tar.bz2 17:09:27 INFO - mkdir: /builds/slave/test/properties 17:09:27 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 17:09:27 INFO - Writing to file /builds/slave/test/properties/build_url 17:09:27 INFO - Contents: 17:09:27 INFO - build_url:https://queue.taskcluster.net/v1/task/CvxZbX7nQM278U8qCtb6OQ/artifacts/public/build/target.tar.bz2 17:09:27 INFO - Running post-action listener: _resource_record_post_action 17:09:27 INFO - Running post-action listener: find_tests_for_verification 17:09:27 INFO - Running post-action listener: set_extra_try_arguments 17:09:27 INFO - [mozharness: 2017-08-26 00:09:27.007560Z] Finished download-and-extract step (success) 17:09:27 INFO - [mozharness: 2017-08-26 00:09:27.007657Z] Running populate-webroot step. 17:09:27 INFO - Running pre-action listener: _resource_record_pre_action 17:09:27 INFO - Running main action method: populate_webroot 17:09:27 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 17:09:27 INFO - 'chromez-stylo-e10s': {'talos_options': ['--stylo'], 17:09:27 INFO - 'tests': ['tresize', 'tcanvasmark']}, 17:09:27 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 17:09:27 INFO - 'dromaeojs-stylo-e10s': {'talos_options': ['--stylo'], 17:09:27 INFO - 'tests': ['dromaeo_css', 'kraken']}, 17:09:27 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 17:09:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:09:27 INFO - 'g1-stylo-e10s': {'pagesets_name': 'tp5n.zip', 17:09:27 INFO - 'talos_options': ['--stylo'], 17:09:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:09:27 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 17:09:27 INFO - 'tests': ['damp', 'tps']}, 17:09:27 INFO - 'g2-stylo-e10s': {'pagesets_name': 'tp5n.zip', 17:09:27 INFO - 'talos_options': ['--stylo'], 17:09:27 INFO - 'tests': ['damp', 'tps']}, 17:09:27 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:09:27 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 17:09:27 INFO - 'g4-stylo-e10s': {'talos_options': ['--stylo'], 17:09:27 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 17:09:27 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 17:09:27 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 17:09:27 INFO - 'g5-stylo-e10s': {'pagesets_name': 'tp5n.zip', 17:09:27 INFO - 'talos_options': ['--stylo'], 17:09:27 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 17:09:27 INFO - 'other-e10s': {'tests': ['a11yr', 17:09:27 INFO - 'ts_paint', 17:09:27 INFO - 'tpaint', 17:09:27 INFO - 'sessionrestore', 17:09:27 INFO - 'sessionrestore_many_windows', 17:09:27 INFO - 'sessionrestore_no_auto_restore', 17:09:27 INFO - 'tabpaint']}, 17:09:27 INFO - 'other-stylo-e10s': {'talos_options': ['--stylo'], 17:09:27 INFO - 'tests': ['a11yr', 17:09:27 INFO - 'ts_paint', 17:09:27 INFO - 'tpaint', 17:09:27 INFO - 'sessionrestore', 17:09:27 INFO - 'sessionrestore_many_windows', 17:09:27 INFO - 'sessionrestore_no_auto_restore', 17:09:27 INFO - 'tabpaint']}, 17:09:27 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 17:09:27 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 17:09:27 INFO - 'perf-reftest-singletons-stylo-e10s': {'talos_options': ['--stylo'], 17:09:27 INFO - 'tests': ['bloom_basic_singleton']}, 17:09:27 INFO - 'perf-reftest-stylo-e10s': {'talos_options': ['--stylo'], 17:09:27 INFO - 'tests': ['bloom_basic']}, 17:09:27 INFO - 'svgr-e10s': {'tests': ['tsvgx', 17:09:27 INFO - 'tsvgr_opacity', 17:09:27 INFO - 'tart', 17:09:27 INFO - 'tscrollx', 17:09:27 INFO - 'tsvg_static']}, 17:09:27 INFO - 'svgr-stylo-e10s': {'talos_options': ['--stylo'], 17:09:27 INFO - 'tests': ['tsvgx', 17:09:27 INFO - 'tsvgr_opacity', 17:09:27 INFO - 'tart', 17:09:27 INFO - 'tscrollx', 17:09:27 INFO - 'tsvg_static']}, 17:09:27 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 17:09:27 INFO - 'tp5o-stylo-e10s': {'pagesets_name': 'tp5n.zip', 17:09:27 INFO - 'talos_options': ['--stylo'], 17:09:27 INFO - 'tests': ['tp5o']}, 17:09:27 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 17:09:27 INFO - 'talos_options': ['--mitmproxy', 17:09:27 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 17:09:27 INFO - '--firstNonBlankPaint'], 17:09:27 INFO - 'tests': ['tp6_google', 17:09:27 INFO - 'tp6_youtube', 17:09:27 INFO - 'tp6_amazon', 17:09:27 INFO - 'tp6_facebook']}, 17:09:27 INFO - 'tp6-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 17:09:27 INFO - 'talos_options': ['--stylo', 17:09:27 INFO - '--mitmproxy', 17:09:27 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 17:09:27 INFO - '--firstNonBlankPaint'], 17:09:27 INFO - 'tests': ['tp6_google', 17:09:27 INFO - 'tp6_youtube', 17:09:27 INFO - 'tp6_amazon', 17:09:27 INFO - 'tp6_facebook']}, 17:09:27 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 17:09:27 INFO - 'talos_options': ['--stylo', 17:09:27 INFO - '--stylo-threads=1', 17:09:27 INFO - '--mitmproxy', 17:09:27 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 17:09:27 INFO - '--firstNonBlankPaint'], 17:09:27 INFO - 'tests': ['tp6_google', 17:09:27 INFO - 'tp6_youtube', 17:09:27 INFO - 'tp6_amazon', 17:09:27 INFO - 'tp6_facebook']}, 17:09:27 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 17:09:27 INFO - 'talos_options': ['--xperf_path', 17:09:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:09:27 INFO - 'tests': ['tp5n']}, 17:09:27 INFO - 'xperf-stylo-e10s': {'pagesets_name': 'tp5n.zip', 17:09:27 INFO - 'talos_options': ['--stylo', 17:09:27 INFO - '--xperf_path', 17:09:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:09:27 INFO - 'tests': ['tp5n']}}} 17:09:27 INFO - Running post-action listener: _resource_record_post_action 17:09:27 INFO - [mozharness: 2017-08-26 00:09:27.021771Z] Finished populate-webroot step (success) 17:09:27 INFO - [mozharness: 2017-08-26 00:09:27.021860Z] Running create-virtualenv step. 17:09:27 INFO - Running pre-action listener: _resource_record_pre_action 17:09:27 INFO - Running main action method: create_virtualenv 17:09:27 INFO - Creating virtualenv /builds/slave/test/build/venv 17:09:27 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 17:09:27 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 17:09:27 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 17:09:27 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:09:27 INFO - New python executable in /builds/slave/test/build/venv/bin/python 17:09:27 INFO - Installing distribute.............................................................................................................................................................................................done. 17:09:27 INFO - Installing pip...............done. 17:09:27 INFO - Return code: 0 17:09:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 17:09:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 17:09:28 INFO - Reading from file tmpfile_stdout 17:09:28 INFO - Output received: 17:09:28 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 17:09:28 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 17:09:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:09:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:09:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:09:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:09:28 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 0x29661f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b31a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2cd4f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2cd2130>, '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 0x2cd3030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2cd34c0>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1503706134.484861-159888122', '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 17:09:28 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 17:09:28 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 17:09:28 INFO - Using env: {'DISPLAY': ':0', 17:09:28 INFO - 'HOME': '/home/cltbld', 17:09:28 INFO - 'LANG': 'en_US.UTF-8', 17:09:28 INFO - 'LANGUAGE': 'en_US:en', 17:09:28 INFO - 'LOGNAME': 'cltbld', 17:09:28 INFO - 'MAIL': '/var/mail/cltbld', 17:09:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:09:28 INFO - 'MOZ_NO_REMOTE': '1', 17:09:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:09:28 INFO - 'NO_EM_RESTART': '1', 17:09:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:09:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:09:28 INFO - 'PWD': '/builds/slave/test', 17:09:28 INFO - 'SHELL': '/bin/bash', 17:09:28 INFO - 'SHLVL': '1', 17:09:28 INFO - 'TERM': 'linux', 17:09:28 INFO - 'TMOUT': '86400', 17:09:28 INFO - 'USER': 'cltbld', 17:09:28 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1503706134.484861-159888122', 17:09:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:09:28 INFO - '_': '/tools/buildbot/bin/python'} 17:09:29 INFO - Ignoring indexes: http://pypi.python.org/simple/ 17:09:29 INFO - Downloading/unpacking pip>=1.5 17:09:29 INFO - Running setup.py egg_info for package pip 17:09:29 INFO - warning: no files found matching 'pip/cacert.pem' 17:09:29 INFO - warning: no files found matching '*.html' under directory 'docs' 17:09:29 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 17:09:29 INFO - no previously-included directories found matching 'docs/_build/_sources' 17:09:29 INFO - Installing collected packages: pip 17:09:29 INFO - Found existing installation: pip 1.1 17:09:29 INFO - Uninstalling pip: 17:09:29 INFO - Successfully uninstalled pip 17:09:29 INFO - Running setup.py install for pip 17:09:29 INFO - warning: no files found matching 'pip/cacert.pem' 17:09:29 INFO - warning: no files found matching '*.html' under directory 'docs' 17:09:29 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 17:09:29 INFO - no previously-included directories found matching 'docs/_build/_sources' 17:09:29 INFO - Installing pip script to /builds/slave/test/build/venv/bin 17:09:29 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 17:09:29 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 17:09:29 INFO - Successfully installed pip 17:09:29 INFO - Cleaning up... 17:09:29 INFO - Return code: 0 17:09:29 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 17:09:29 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')]} 17:09:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:09:29 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')]} 17:09:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:09:29 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 0x29661f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b31a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2cd4f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2cd2130>, '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 0x2cd3030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2cd34c0>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1503706134.484861-159888122', '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 17:09:29 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 17:09:29 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 17:09:29 INFO - Using env: {'DISPLAY': ':0', 17:09:29 INFO - 'HOME': '/home/cltbld', 17:09:29 INFO - 'LANG': 'en_US.UTF-8', 17:09:29 INFO - 'LANGUAGE': 'en_US:en', 17:09:29 INFO - 'LOGNAME': 'cltbld', 17:09:29 INFO - 'MAIL': '/var/mail/cltbld', 17:09:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:09:29 INFO - 'MOZ_NO_REMOTE': '1', 17:09:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:09:29 INFO - 'NO_EM_RESTART': '1', 17:09:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:09:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:09:29 INFO - 'PWD': '/builds/slave/test', 17:09:29 INFO - 'SHELL': '/bin/bash', 17:09:29 INFO - 'SHLVL': '1', 17:09:29 INFO - 'TERM': 'linux', 17:09:29 INFO - 'TMOUT': '86400', 17:09:29 INFO - 'USER': 'cltbld', 17:09:29 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1503706134.484861-159888122', 17:09:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:09:29 INFO - '_': '/tools/buildbot/bin/python'} 17:09:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:09:29 INFO - Downloading/unpacking psutil>=3.1.1 17:09:29 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 17:09:29 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 17:09:29 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 17:09:29 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 17:09:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 17:09:30 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:09:30 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:09:30 INFO - Installing collected packages: psutil 17:09:30 INFO - Running setup.py install for psutil 17:09:30 INFO - building 'psutil._psutil_linux' extension 17:09:30 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 17:09:30 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 17:09:30 INFO - building 'psutil._psutil_posix' extension 17:09:30 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 17:09:31 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 17:09:31 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:09:31 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:09:31 INFO - Successfully installed psutil 17:09:31 INFO - Cleaning up... 17:09:31 INFO - Return code: 0 17:09:31 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 17:09:31 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')]} 17:09:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:09:31 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')]} 17:09:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:09:31 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 0x29661f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b31a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2cd4f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2cd2130>, '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 0x2cd3030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2cd34c0>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1503706134.484861-159888122', '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 17:09:31 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 17:09:31 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 17:09:31 INFO - Using env: {'DISPLAY': ':0', 17:09:31 INFO - 'HOME': '/home/cltbld', 17:09:31 INFO - 'LANG': 'en_US.UTF-8', 17:09:31 INFO - 'LANGUAGE': 'en_US:en', 17:09:31 INFO - 'LOGNAME': 'cltbld', 17:09:31 INFO - 'MAIL': '/var/mail/cltbld', 17:09:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:09:31 INFO - 'MOZ_NO_REMOTE': '1', 17:09:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:09:31 INFO - 'NO_EM_RESTART': '1', 17:09:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:09:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:09:31 INFO - 'PWD': '/builds/slave/test', 17:09:31 INFO - 'SHELL': '/bin/bash', 17:09:31 INFO - 'SHLVL': '1', 17:09:31 INFO - 'TERM': 'linux', 17:09:31 INFO - 'TMOUT': '86400', 17:09:31 INFO - 'USER': 'cltbld', 17:09:31 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1503706134.484861-159888122', 17:09:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:09:31 INFO - '_': '/tools/buildbot/bin/python'} 17:09:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:09:31 INFO - Downloading/unpacking mozsystemmonitor==0.3 17:09:31 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 17:09:31 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 17:09:31 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 17:09:31 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 17:09:32 INFO - Downloading mozsystemmonitor-0.3.tar.gz 17:09:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:09:32 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 17:09:32 INFO - Installing collected packages: mozsystemmonitor 17:09:32 INFO - Running setup.py install for mozsystemmonitor 17:09:32 INFO - Successfully installed mozsystemmonitor 17:09:32 INFO - Cleaning up... 17:09:32 INFO - Return code: 0 17:09:32 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 17:09:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:09:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:09:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:09:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:09:32 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 0x29661f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b31a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2cd4f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2cd2130>, '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 0x2cd3030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2cd34c0>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1503706134.484861-159888122', '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 17:09:32 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 17:09:32 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 17:09:32 INFO - Using env: {'DISPLAY': ':0', 17:09:32 INFO - 'HOME': '/home/cltbld', 17:09:32 INFO - 'LANG': 'en_US.UTF-8', 17:09:32 INFO - 'LANGUAGE': 'en_US:en', 17:09:32 INFO - 'LOGNAME': 'cltbld', 17:09:32 INFO - 'MAIL': '/var/mail/cltbld', 17:09:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:09:32 INFO - 'MOZ_NO_REMOTE': '1', 17:09:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:09:32 INFO - 'NO_EM_RESTART': '1', 17:09:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:09:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:09:32 INFO - 'PWD': '/builds/slave/test', 17:09:32 INFO - 'SHELL': '/bin/bash', 17:09:32 INFO - 'SHLVL': '1', 17:09:32 INFO - 'TERM': 'linux', 17:09:32 INFO - 'TMOUT': '86400', 17:09:32 INFO - 'USER': 'cltbld', 17:09:32 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1503706134.484861-159888122', 17:09:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:09:32 INFO - '_': '/tools/buildbot/bin/python'} 17:09:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:09:32 INFO - Downloading/unpacking jsonschema==2.5.1 17:09:32 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 17:09:32 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 17:09:32 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 17:09:32 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 17:09:33 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:09:33 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 17:09:33 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 17:09:33 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 17:09:33 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 17:09:33 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 17:09:34 INFO - Downloading functools32-3.2.3-2.tar.gz 17:09:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 17:09:34 INFO - warning: no files found matching '*.txt' 17:09:34 INFO - no previously-included directories found matching 'build' 17:09:34 INFO - no previously-included directories found matching 'dist' 17:09:34 INFO - no previously-included directories found matching '.git*' 17:09:34 INFO - Installing collected packages: jsonschema, functools32 17:09:34 INFO - Running setup.py install for functools32 17:09:34 INFO - warning: no files found matching '*.txt' 17:09:34 INFO - no previously-included directories found matching 'build' 17:09:34 INFO - no previously-included directories found matching 'dist' 17:09:34 INFO - no previously-included directories found matching '.git*' 17:09:34 INFO - Successfully installed jsonschema functools32 17:09:34 INFO - Cleaning up... 17:09:34 INFO - Return code: 0 17:09:34 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 17:09:34 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')]} 17:09:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:09:34 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')]} 17:09:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:09:34 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 0x29661f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b31a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2cd4f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2cd2130>, '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 0x2cd3030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2cd34c0>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1503706134.484861-159888122', '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 17:09:34 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 17:09:34 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 17:09:34 INFO - Using env: {'DISPLAY': ':0', 17:09:34 INFO - 'HOME': '/home/cltbld', 17:09:34 INFO - 'LANG': 'en_US.UTF-8', 17:09:34 INFO - 'LANGUAGE': 'en_US:en', 17:09:34 INFO - 'LOGNAME': 'cltbld', 17:09:34 INFO - 'MAIL': '/var/mail/cltbld', 17:09:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:09:34 INFO - 'MOZ_NO_REMOTE': '1', 17:09:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:09:34 INFO - 'NO_EM_RESTART': '1', 17:09:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:09:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:09:34 INFO - 'PWD': '/builds/slave/test', 17:09:34 INFO - 'SHELL': '/bin/bash', 17:09:34 INFO - 'SHLVL': '1', 17:09:34 INFO - 'TERM': 'linux', 17:09:34 INFO - 'TMOUT': '86400', 17:09:34 INFO - 'USER': 'cltbld', 17:09:34 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1503706134.484861-159888122', 17:09:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:09:34 INFO - '_': '/tools/buildbot/bin/python'} 17:09:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:09:34 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 17:09:34 INFO - Cleaning up... 17:09:34 INFO - Return code: 0 17:09:34 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 17:09:34 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')]} 17:09:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:09:34 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')]} 17:09:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:09:34 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 0x29661f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b31a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2cd4f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2cd2130>, '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 0x2cd3030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2cd34c0>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1503706134.484861-159888122', '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 17:09:34 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 17:09:34 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 17:09:34 INFO - Using env: {'DISPLAY': ':0', 17:09:34 INFO - 'HOME': '/home/cltbld', 17:09:34 INFO - 'LANG': 'en_US.UTF-8', 17:09:34 INFO - 'LANGUAGE': 'en_US:en', 17:09:34 INFO - 'LOGNAME': 'cltbld', 17:09:34 INFO - 'MAIL': '/var/mail/cltbld', 17:09:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:09:34 INFO - 'MOZ_NO_REMOTE': '1', 17:09:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:09:34 INFO - 'NO_EM_RESTART': '1', 17:09:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:09:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:09:34 INFO - 'PWD': '/builds/slave/test', 17:09:34 INFO - 'SHELL': '/bin/bash', 17:09:34 INFO - 'SHLVL': '1', 17:09:34 INFO - 'TERM': 'linux', 17:09:34 INFO - 'TMOUT': '86400', 17:09:34 INFO - 'USER': 'cltbld', 17:09:34 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1503706134.484861-159888122', 17:09:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:09:34 INFO - '_': '/tools/buildbot/bin/python'} 17:09:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:09:34 INFO - Downloading/unpacking blobuploader==1.2.4 17:09:34 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 17:09:34 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 17:09:34 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 17:09:34 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 17:09:35 INFO - Downloading blobuploader-1.2.4.tar.gz 17:09:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:09:35 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:09:35 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 17:09:35 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 17:09:35 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 17:09:35 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 17:09:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 17:09:36 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:09:36 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 17:09:36 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 17:09:36 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 17:09:36 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 17:09:36 INFO - Downloading docopt-0.6.1.tar.gz 17:09:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 17:09:36 INFO - Installing collected packages: blobuploader, requests, docopt 17:09:36 INFO - Running setup.py install for blobuploader 17:09:36 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:09:36 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 17:09:36 INFO - Running setup.py install for requests 17:09:36 INFO - Running setup.py install for docopt 17:09:37 INFO - Successfully installed blobuploader requests docopt 17:09:37 INFO - Cleaning up... 17:09:37 INFO - Return code: 0 17:09:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:09:37 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')]} 17:09:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:09:37 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')]} 17:09:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:09:37 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 0x29661f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b31a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2cd4f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2cd2130>, '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 0x2cd3030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2cd34c0>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1503706134.484861-159888122', '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 17:09:37 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 17:09:37 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 17:09:37 INFO - Using env: {'DISPLAY': ':0', 17:09:37 INFO - 'HOME': '/home/cltbld', 17:09:37 INFO - 'LANG': 'en_US.UTF-8', 17:09:37 INFO - 'LANGUAGE': 'en_US:en', 17:09:37 INFO - 'LOGNAME': 'cltbld', 17:09:37 INFO - 'MAIL': '/var/mail/cltbld', 17:09:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:09:37 INFO - 'MOZ_NO_REMOTE': '1', 17:09:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:09:37 INFO - 'NO_EM_RESTART': '1', 17:09:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:09:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:09:37 INFO - 'PWD': '/builds/slave/test', 17:09:37 INFO - 'SHELL': '/bin/bash', 17:09:37 INFO - 'SHLVL': '1', 17:09:37 INFO - 'TERM': 'linux', 17:09:37 INFO - 'TMOUT': '86400', 17:09:37 INFO - 'USER': 'cltbld', 17:09:37 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1503706134.484861-159888122', 17:09:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:09:37 INFO - '_': '/tools/buildbot/bin/python'} 17:09:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:09:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:09:37 INFO - Running setup.py (path:/tmp/pip-bvmSFz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:09:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:09:37 INFO - Running setup.py (path:/tmp/pip-E8b1C5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:09:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:09:37 INFO - Running setup.py (path:/tmp/pip-ppMIbB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:09:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:09:37 INFO - Running setup.py (path:/tmp/pip-4fYzhE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:09:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:09:37 INFO - Running setup.py (path:/tmp/pip-drObh0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:09:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:09:37 INFO - Running setup.py (path:/tmp/pip-QVr0S0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:09:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:09:37 INFO - Running setup.py (path:/tmp/pip-UGIoW5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:09:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:09:37 INFO - Running setup.py (path:/tmp/pip-HK0uBR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:09:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:09:37 INFO - Running setup.py (path:/tmp/pip-HBLdEs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:09:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:09:37 INFO - Running setup.py (path:/tmp/pip-q24qk8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:09:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:09:37 INFO - Running setup.py (path:/tmp/pip-Yupqog-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:09:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:09:38 INFO - Running setup.py (path:/tmp/pip-yOdMsC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:09:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:09:38 INFO - Running setup.py (path:/tmp/pip-O8IMFB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:09:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:09:38 INFO - Running setup.py (path:/tmp/pip-L7IkfO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:09:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:09:38 INFO - Running setup.py (path:/tmp/pip-2l7YuH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:09:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:09:38 INFO - Running setup.py (path:/tmp/pip-LJ84gD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:09:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:09:38 INFO - Running setup.py (path:/tmp/pip-blJABo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:09:38 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:09:38 INFO - Running setup.py install for manifestparser 17:09:38 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 17:09:38 INFO - Running setup.py install for mozcrash 17:09:38 INFO - Running setup.py install for mozdebug 17:09:38 INFO - Running setup.py install for mozdevice 17:09:38 INFO - Installing dm script to /builds/slave/test/build/venv/bin 17:09:38 INFO - Running setup.py install for mozfile 17:09:38 INFO - Running setup.py install for mozhttpd 17:09:39 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 17:09:39 INFO - Running setup.py install for mozinfo 17:09:39 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 17:09:39 INFO - Running setup.py install for mozInstall 17:09:39 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 17:09:39 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 17:09:39 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 17:09:39 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 17:09:39 INFO - Running setup.py install for mozleak 17:09:39 INFO - Running setup.py install for mozlog 17:09:39 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 17:09:39 INFO - Running setup.py install for moznetwork 17:09:39 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 17:09:39 INFO - Running setup.py install for mozprocess 17:09:39 INFO - Running setup.py install for mozprofile 17:09:39 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 17:09:39 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 17:09:39 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 17:09:39 INFO - Running setup.py install for mozrunner 17:09:39 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 17:09:39 INFO - Running setup.py install for mozscreenshot 17:09:39 INFO - Running setup.py install for moztest 17:09:40 INFO - Running setup.py install for mozversion 17:09:40 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 17:09:40 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:09:40 INFO - Cleaning up... 17:09:40 INFO - Return code: 0 17:09:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:09:40 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')]} 17:09:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:09:40 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')]} 17:09:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:09:40 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 0x29661f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b31a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2cd4f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2cd2130>, '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 0x2cd3030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2cd34c0>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1503706134.484861-159888122', '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 17:09:40 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 17:09:40 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 17:09:40 INFO - Using env: {'DISPLAY': ':0', 17:09:40 INFO - 'HOME': '/home/cltbld', 17:09:40 INFO - 'LANG': 'en_US.UTF-8', 17:09:40 INFO - 'LANGUAGE': 'en_US:en', 17:09:40 INFO - 'LOGNAME': 'cltbld', 17:09:40 INFO - 'MAIL': '/var/mail/cltbld', 17:09:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:09:40 INFO - 'MOZ_NO_REMOTE': '1', 17:09:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:09:40 INFO - 'NO_EM_RESTART': '1', 17:09:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:09:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:09:40 INFO - 'PWD': '/builds/slave/test', 17:09:40 INFO - 'SHELL': '/bin/bash', 17:09:40 INFO - 'SHLVL': '1', 17:09:40 INFO - 'TERM': 'linux', 17:09:40 INFO - 'TMOUT': '86400', 17:09:40 INFO - 'USER': 'cltbld', 17:09:40 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1503706134.484861-159888122', 17:09:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:09:40 INFO - '_': '/tools/buildbot/bin/python'} 17:09:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:09:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:09:40 INFO - Running setup.py (path:/tmp/pip-GhSqp6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:09:40 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)) 17:09:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:09:40 INFO - Running setup.py (path:/tmp/pip-u3peCI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:09:40 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)) 17:09:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:09:40 INFO - Running setup.py (path:/tmp/pip-nIJ5HB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:09:40 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)) 17:09:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:09:40 INFO - Running setup.py (path:/tmp/pip-gnOb6O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:09:40 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)) 17:09:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:09:40 INFO - Running setup.py (path:/tmp/pip-DH5gcO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:09:40 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)) 17:09:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:09:40 INFO - Running setup.py (path:/tmp/pip-fSp_dR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:09:40 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)) 17:09:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:09:40 INFO - Running setup.py (path:/tmp/pip-PbKeR6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:09:40 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)) 17:09:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:09:40 INFO - Running setup.py (path:/tmp/pip-hgnuaY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:09:40 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)) 17:09:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:09:40 INFO - Running setup.py (path:/tmp/pip-lD1E5j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:09:41 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)) 17:09:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:09:41 INFO - Running setup.py (path:/tmp/pip-DhdycX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:09:41 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)) 17:09:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:09:41 INFO - Running setup.py (path:/tmp/pip-mqV52C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:09:41 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)) 17:09:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:09:41 INFO - Running setup.py (path:/tmp/pip-90kDdi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:09:41 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)) 17:09:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:09:41 INFO - Running setup.py (path:/tmp/pip-drSWhY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:09:41 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)) 17:09:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:09:41 INFO - Running setup.py (path:/tmp/pip-1a3kk_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:09:41 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)) 17:09:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:09:41 INFO - Running setup.py (path:/tmp/pip-hbeXPO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:09:41 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)) 17:09:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:09:41 INFO - Running setup.py (path:/tmp/pip-G3fknT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:09:41 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)) 17:09:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:09:41 INFO - Running setup.py (path:/tmp/pip-Qz7M_p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:09:41 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)) 17:09:41 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)) 17:09:41 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)) 17:09:41 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)) 17:09:41 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)) 17:09:41 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)) 17:09:41 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)) 17:09:41 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:09:41 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 17:09:41 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 17:09:41 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 17:09:41 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 17:09:42 INFO - Downloading blessings-1.6.tar.gz 17:09:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 17:09:42 INFO - Installing collected packages: blessings 17:09:42 INFO - Running setup.py install for blessings 17:09:42 INFO - Successfully installed blessings 17:09:42 INFO - Cleaning up... 17:09:42 INFO - Return code: 0 17:09:42 INFO - Done creating virtualenv /builds/slave/test/build/venv. 17:09:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:09:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:09:43 INFO - Reading from file tmpfile_stdout 17:09:43 INFO - Current package versions: 17:09:43 INFO - argparse == 1.2.1 17:09:43 INFO - blessings == 1.6 17:09:43 INFO - blobuploader == 1.2.4 17:09:43 INFO - docopt == 0.6.1 17:09:43 INFO - functools32 == 3.2.3-2 17:09:43 INFO - jsonschema == 2.5.1 17:09:43 INFO - manifestparser == 1.1 17:09:43 INFO - mozInstall == 1.13 17:09:43 INFO - mozcrash == 1.0 17:09:43 INFO - mozdebug == 0.1 17:09:43 INFO - mozdevice == 0.51 17:09:43 INFO - mozfile == 1.2 17:09:43 INFO - mozhttpd == 0.7 17:09:43 INFO - mozinfo == 0.10 17:09:43 INFO - mozleak == 0.1 17:09:43 INFO - mozlog == 3.5 17:09:43 INFO - moznetwork == 0.27 17:09:43 INFO - mozprocess == 0.25 17:09:43 INFO - mozprofile == 0.28 17:09:43 INFO - mozrunner == 6.13 17:09:43 INFO - mozscreenshot == 0.1 17:09:43 INFO - mozsystemmonitor == 0.3 17:09:43 INFO - moztest == 0.8 17:09:43 INFO - mozversion == 1.4 17:09:43 INFO - psutil == 3.1.1 17:09:43 INFO - requests == 1.2.3 17:09:43 INFO - wsgiref == 0.1.2 17:09:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:09:43 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')]} 17:09:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:09:43 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')]} 17:09:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:09:43 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 0x29661f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b31a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2cd4f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2cd2130>, '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 0x2cd3030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2cd34c0>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1503706134.484861-159888122', '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 17:09:43 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 17:09:43 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 17:09:43 INFO - Using env: {'DISPLAY': ':0', 17:09:43 INFO - 'HOME': '/home/cltbld', 17:09:43 INFO - 'LANG': 'en_US.UTF-8', 17:09:43 INFO - 'LANGUAGE': 'en_US:en', 17:09:43 INFO - 'LOGNAME': 'cltbld', 17:09:43 INFO - 'MAIL': '/var/mail/cltbld', 17:09:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:09:43 INFO - 'MOZ_NO_REMOTE': '1', 17:09:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:09:43 INFO - 'NO_EM_RESTART': '1', 17:09:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:09:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:09:43 INFO - 'PWD': '/builds/slave/test', 17:09:43 INFO - 'SHELL': '/bin/bash', 17:09:43 INFO - 'SHLVL': '1', 17:09:43 INFO - 'TERM': 'linux', 17:09:43 INFO - 'TMOUT': '86400', 17:09:43 INFO - 'USER': 'cltbld', 17:09:43 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1503706134.484861-159888122', 17:09:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:09:43 INFO - '_': '/tools/buildbot/bin/python'} 17:09:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:09:43 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)) 17:09:43 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)) 17:09:43 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)) 17:09:43 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)) 17:09:43 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)) 17:09:43 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)) 17:09:43 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)) 17:09:43 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)) 17:09:43 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)) 17:09:43 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 17:09:43 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 17:09:43 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 17:09:43 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 17:09:43 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 17:09:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 17:09:44 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)) 17:09:44 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)) 17:09:44 INFO - Installing collected packages: simplejson 17:09:44 INFO - Running setup.py install for simplejson 17:09:44 INFO - building 'simplejson._speedups' extension 17:09:44 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 17:09:45 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 17:09:45 INFO - Successfully installed simplejson 17:09:45 INFO - Cleaning up... 17:09:45 INFO - Return code: 0 17:09:45 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 17:09:45 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')]} 17:09:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:09:45 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')]} 17:09:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:09:45 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 0x29661f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b31a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2cd4f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2cd2130>, '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 0x2cd3030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2cd34c0>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1503706134.484861-159888122', '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 17:09:45 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 17:09:45 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 17:09:45 INFO - Using env: {'DISPLAY': ':0', 17:09:45 INFO - 'HOME': '/home/cltbld', 17:09:45 INFO - 'LANG': 'en_US.UTF-8', 17:09:45 INFO - 'LANGUAGE': 'en_US:en', 17:09:45 INFO - 'LOGNAME': 'cltbld', 17:09:45 INFO - 'MAIL': '/var/mail/cltbld', 17:09:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:09:45 INFO - 'MOZ_NO_REMOTE': '1', 17:09:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:09:45 INFO - 'NO_EM_RESTART': '1', 17:09:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:09:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:09:45 INFO - 'PWD': '/builds/slave/test', 17:09:45 INFO - 'SHELL': '/bin/bash', 17:09:45 INFO - 'SHLVL': '1', 17:09:45 INFO - 'TERM': 'linux', 17:09:45 INFO - 'TMOUT': '86400', 17:09:45 INFO - 'USER': 'cltbld', 17:09:45 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1503706134.484861-159888122', 17:09:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:09:45 INFO - '_': '/tools/buildbot/bin/python'} 17:09:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:09:45 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 17:09:45 INFO - Cleaning up... 17:09:45 INFO - Return code: 0 17:09:45 INFO - Running post-action listener: _resource_record_post_action 17:09:45 INFO - Running post-action listener: _start_resource_monitoring 17:09:45 INFO - Starting resource monitoring. 17:09:45 INFO - [mozharness: 2017-08-26 00:09:45.858288Z] Finished create-virtualenv step (success) 17:09:45 INFO - [mozharness: 2017-08-26 00:09:45.858803Z] Running install step. 17:09:45 INFO - Running pre-action listener: _resource_record_pre_action 17:09:45 INFO - Running main action method: install 17:09:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:09:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:09:46 INFO - Reading from file tmpfile_stdout 17:09:46 INFO - Detecting whether we're running mozinstall >=1.0... 17:09:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 17:09:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 17:09:46 INFO - Reading from file tmpfile_stdout 17:09:46 INFO - Output received: 17:09:46 INFO - Usage: mozinstall [options] installer 17:09:46 INFO - Options: 17:09:46 INFO - -h, --help show this help message and exit 17:09:46 INFO - -d DEST, --destination=DEST 17:09:46 INFO - Directory to install application into. [default: 17:09:46 INFO - "/builds/slave/test"] 17:09:46 INFO - --app=APP Application being installed. [default: firefox] 17:09:46 INFO - mkdir: /builds/slave/test/build/application 17:09:46 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'] 17:09:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 17:10:03 INFO - Reading from file tmpfile_stdout 17:10:03 INFO - Output received: 17:10:03 INFO - /builds/slave/test/build/application/firefox/firefox 17:10:03 INFO - Running post-action listener: _resource_record_post_action 17:10:03 INFO - [mozharness: 2017-08-26 00:10:03.900413Z] Finished install step (success) 17:10:03 INFO - [mozharness: 2017-08-26 00:10:03.900663Z] Running setup-mitmproxy step. 17:10:03 INFO - Running pre-action listener: _resource_record_pre_action 17:10:03 INFO - Running main action method: setup_mitmproxy 17:10:03 INFO - Skipping: mitmproxy is not required 17:10:03 INFO - Running post-action listener: _resource_record_post_action 17:10:03 INFO - [mozharness: 2017-08-26 00:10:03.901928Z] Finished setup-mitmproxy step (success) 17:10:03 INFO - [mozharness: 2017-08-26 00:10:03.902148Z] Running run-tests step. 17:10:03 INFO - Running pre-action listener: _resource_record_pre_action 17:10:03 INFO - Running pre-action listener: _set_gcov_prefix 17:10:03 INFO - Running main action method: run_tests 17:10:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 17:10:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 17:10:03 INFO - Python 2.7.3 17:10:03 INFO - Return code: 0 17:10:03 INFO - grabbing minidump binary from tooltool 17:10:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:10:03 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 0x2cd2130>, '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 0x2cd3030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2cd34c0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 17:10:03 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 17:10:03 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 17:10:03 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 17:10:03 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:10:04 INFO - Return code: 0 17:10:04 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 17:10:04 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 17:10:04 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 17:10:04 INFO - ENV: RUST_BACKTRACE is now full 17:10:04 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 17:10:04 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 17:10:04 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/CvxZbX7nQM278U8qCtb6OQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-012', '--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 17:10:04 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/CvxZbX7nQM278U8qCtb6OQ/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-012 --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 17:10:04 INFO - Using env: {'DISPLAY': ':0', 17:10:04 INFO - 'HOME': '/home/cltbld', 17:10:04 INFO - 'LANG': 'en_US.UTF-8', 17:10:04 INFO - 'LANGUAGE': 'en_US:en', 17:10:04 INFO - 'LOGNAME': 'cltbld', 17:10:04 INFO - 'MAIL': '/var/mail/cltbld', 17:10:04 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:10:04 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 17:10:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:10:04 INFO - 'MOZ_NO_REMOTE': '1', 17:10:04 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:10:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:10:04 INFO - 'NO_EM_RESTART': '1', 17:10:04 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:10:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:10:04 INFO - 'PWD': '/builds/slave/test', 17:10:04 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:10:04 INFO - 'RUST_BACKTRACE': 'full', 17:10:04 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 17:10:04 INFO - 'SHELL': '/bin/bash', 17:10:04 INFO - 'SHLVL': '1', 17:10:04 INFO - 'STYLO_FORCE_ENABLED': '1', 17:10:04 INFO - 'TERM': 'linux', 17:10:04 INFO - 'TMOUT': '86400', 17:10:04 INFO - 'USER': 'cltbld', 17:10:04 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1503706134.484861-159888122', 17:10:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:10:04 INFO - '_': '/tools/buildbot/bin/python'} 17:10:04 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/CvxZbX7nQM278U8qCtb6OQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-012', '--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 17:10:04 INFO - mozversion application_buildid: 20170825232216 17:10:04 INFO - mozversion application_changeset: 86a3c73984a23b1d524eadbd4a62b9b3fa27e46a 17:10:04 INFO - mozversion application_display_name: Nightly 17:10:04 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:10:04 INFO - mozversion application_name: Firefox 17:10:04 INFO - mozversion application_remotingname: firefox 17:10:04 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 17:10:04 INFO - mozversion application_vendor: Mozilla 17:10:04 INFO - mozversion application_version: 57.0a1 17:10:04 INFO - mozversion platform_buildid: 20170825232216 17:10:04 INFO - mozversion platform_changeset: 86a3c73984a23b1d524eadbd4a62b9b3fa27e46a 17:10:04 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 17:10:04 INFO - mozversion platform_version: 57.0a1 17:10:04 INFO - using testdate: 1503706204 17:10:04 INFO - actual date: 1503706204 17:10:04 INFO - starting webserver on 'localhost:50240' 17:10:04 INFO - SUITE-START | Running 2 tests 17:10:04 INFO - TEST-START | dromaeo_css 17:10:04 INFO - Initialising browser for dromaeo_css test... 17:10:04 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:50240/getInfo.html -profile /tmp/tmpW2cfi1/profile 17:10:04 INFO - TEST-INFO | started process 3977 (/builds/slave/test/build/application/firefox/firefox http://localhost:50240/getInfo.html) 17:10:09 INFO - TEST-INFO | 3977: exit 0 17:10:09 INFO - Browser initialized. 17:10:09 INFO - Running cycle 1/1 for dromaeo_css test... 17:10:09 INFO - Using env: {'DISPLAY': ':0', 17:10:09 INFO - 'HOME': '/home/cltbld', 17:10:09 INFO - 'JSGC_DISABLE_POISONING': '1', 17:10:09 INFO - 'LANG': 'en_US.UTF-8', 17:10:09 INFO - 'LANGUAGE': 'en_US:en', 17:10:09 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 17:10:09 INFO - 'LOGNAME': 'cltbld', 17:10:09 INFO - 'MAIL': '/var/mail/cltbld', 17:10:09 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:10:09 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 17:10:09 INFO - 'MOZ_CRASHREPORTER': '1', 17:10:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:10:09 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 17:10:09 INFO - 'MOZ_NO_REMOTE': '1', 17:10:09 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:10:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:10:09 INFO - 'NO_EM_RESTART': '1', 17:10:09 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:10:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:10:09 INFO - 'PWD': '/builds/slave/test', 17:10:09 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:10:09 INFO - 'RUST_BACKTRACE': 'full', 17:10:09 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 17:10:09 INFO - 'SHELL': '/bin/bash', 17:10:09 INFO - 'SHLVL': '1', 17:10:09 INFO - 'STYLO_FORCE_ENABLED': '1', 17:10:09 INFO - 'TERM': 'linux', 17:10:09 INFO - 'TMOUT': '86400', 17:10:09 INFO - 'USER': 'cltbld', 17:10:09 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1503706134.484861-159888122', 17:10:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:10:09 INFO - '_': '/tools/buildbot/bin/python'} 17:10:09 INFO - TEST-INFO | started process 4163 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpW2cfi1/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 17:10:10 INFO - PID 4163 | 17:10:10 INFO - PID 4163 | (/builds/slave/test/build/application/firefox/firefox:4212): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 17:10:10 INFO - PID 4163 | 17:10:11 INFO - PID 4163 | 17:10:11 INFO - PID 4163 | (/builds/slave/test/build/application/firefox/firefox:4273): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 17:10:11 INFO - PID 4163 | 17:10:11 INFO - PID 4163 | __metrics Screen width/height:1600/1200 17:10:11 INFO - PID 4163 | colorDepth:24 17:10:11 INFO - PID 4163 | Browser inner width/height: 1024/768 17:10:11 INFO - PID 4163 | __metrics 17:10:20 INFO - PID 4163 | RSS: Main: 174743552 17:10:20 INFO - PID 4163 | 17:13:19 INFO - PID 4163 | Cycle 1(1): loaded http://localhost:50240/tests/dromaeo/cssquery-dojo.html (next: http://localhost:50240/tests/dromaeo/cssquery-ext.html) 17:13:20 INFO - PID 4163 | RSS: Main: 178561024 17:13:20 INFO - PID 4163 | 17:16:22 INFO - PID 4163 | Cycle 1(1): loaded http://localhost:50240/tests/dromaeo/cssquery-ext.html (next: http://localhost:50240/tests/dromaeo/cssquery-jquery.html) 17:16:23 INFO - PID 4163 | RSS: Main: 174252032 17:16:23 INFO - PID 4163 | 17:19:25 INFO - PID 4163 | Cycle 1(1): loaded http://localhost:50240/tests/dromaeo/cssquery-jquery.html (next: http://localhost:50240/tests/dromaeo/cssquery-mootools.html) 17:19:25 INFO - PID 4163 | RSS: Main: 177168384 17:19:25 INFO - PID 4163 | 17:22:27 INFO - PID 4163 | Cycle 1(1): loaded http://localhost:50240/tests/dromaeo/cssquery-mootools.html (next: http://localhost:50240/tests/dromaeo/cssquery-prototype.html) 17:22:28 INFO - PID 4163 | RSS: Main: 175558656 17:22:28 INFO - PID 4163 | 17:25:31 INFO - PID 4163 | Cycle 1(1): loaded http://localhost:50240/tests/dromaeo/cssquery-prototype.html (next: http://localhost:50240/tests/dromaeo/cssquery-yui.html) 17:25:32 INFO - PID 4163 | RSS: Main: 176881664 17:25:32 INFO - PID 4163 | 17:28:34 INFO - PID 4163 | Cycle 1(1): loaded http://localhost:50240/tests/dromaeo/cssquery-yui.html (next: http://localhost:50240/tests/dromaeo/cssquery-dojo.html) 17:28:34 INFO - PID 4163 | RSS: Main: 179810304 17:28:34 INFO - PID 4163 | 17:28:34 INFO - PID 4163 | __start_tp_report 17:28:34 INFO - PID 4163 | _x_x_mozilla_page_load 17:28:34 INFO - PID 4163 | _x_x_mozilla_page_load_details 17:28:34 INFO - PID 4163 | |i|pagename|runs| 17:28:34 INFO - PID 4163 | |0;dojo.html;3295.851686674098;3315.552400425942;3316.121227874612;3322.368749937512;3329.017939707786;6234.2518897732225;6307.05394190871;6336.904946425807;6368.681565921708;6370.9362906370925;6143.754249830013;6262.53031022674;6265.592736472137;6282.874342513147;6297.244330680325;5567.972160139194;5575.442455754433;5579.163125531182;5747.0517364635;5829.533637309027;5820.708964551776;5835.708214589275;5859.355470898219;5865.912011319812;5872.882542349151;5655.4910058094965;5740.7703691852275;5768.596198266123;5914.172015917775;5918.881622367571;5720.141978703186;5729.6562206267345;5772.365039845614;5818.1272809078555;5833.912491312633;5716.914246286309;5771.913421298642;5782.045962416196;5812.186293918855;5839;6101.694915254219;6133.172021777093;6223.471004964584;6283.968580157116;6357.554971151975;6553.213614366259;6557.311482294338;6563.3764792344655;6593.835154121138;6639.170103737033;6825.453963682893;6835.111435513401;6837.692303846289;6848.794536163923;6860.965695171492;6231.158793562901;6257.405546473124;6285.685715714242;6291.182146320949;6368.395002474711;6758.594484330857;6890.758823441155;6946.2011868634745;6962.199347075151;7017.89473157903;6480.870382592322;6544.312847150981;6586.571872828251;6629.86740265192;6646.069550262967;6741.528093033538;6754.290799465985;6787.898181527281;6802.251752307242;6806.863862722717;6634.336566343327;6652.368025037614;6679.231888332807;6700.2294736105;6764.222114456802;6593.703283352261;6686.765963191265;6699.129113215251;6794.286599907037;6801.659917004229;5259.737013149327;5259.947400526023;5305.4164041955355;5315.973420132875;5319.840404787863;4670.579647831712;4722.362481065081;4733.5503127202865;4738.1708201064675;4746.928796068062;5909.5863289569315;5941.792037278675;5948.821535353946;6038.5169186465855;6041.426064523863;6588.901166482507;6760.492963027793;6760.898586521206;6856.348646878539;6862.039314495974;5820.534357251409;5877.52979761618;5936.7031648417405;5944.554158438048;5949.702514874239;5825.097109947965;5874.853128671732;5876.9118463223085;5878.500327472213;5881.5882888198275;6540.509461790385;6548.541602087904;6559.704813283413;6568.6058836469965;6661.666916654148;6661.900071498834;6734.696938637675;6757.594544327356;6856.725730970706;6861.828454288683;6580.605163690194;6584.76953306632;6594.109795177781;6604.735810567524;6624.205095388585;6617.8014659560295;6622.63575503352;6730.966345168243;6745.325467453412;6769.390754831892;6753.493487988322;6753.696083676345;6848.3494068063455;6854.246032936273;6868.896966545406;6351.2060992375955;6444.355564443706;6474.805755827333;6542.378474044958;6633.6019838809825;6510.283868774526;6565.244996825426;6594.043863639649;6597.043428702716;6602.9009564855605;6497.382748638872;6509.674516274262;6551.377619126175;6570.5729127608465;6601.801945941629;6522.445592124723;6574.3754343337305;6590;6635.568688035262;6684.264730879502;6342.143810585512;6413.198350206224;6421.903671445026;6422.903656445251;6437.806865794034 17:28:34 INFO - PID 4163 | |1;ext.html;812.1594150054694;841.8316336732653;869.6347534035706;874.1608056265984;1122.927009744367;30783.23041923953;41545.58454415459;42160;42185;42195.57804421955;41677.16645667092;41722;41854.37218441726;41992.16015679685;42059.57940420595;7386.556806591621;7820.217978202177;7980.082290536576;7984.40116991228;7990.360771138324;7807.375409967189;7940.841183176333;7943.285104340608;7945.443818932648;7949.682012719484;7911.683532658687;7997.840043199132;8003.399745019147;8003.759887203393;8015.078265999427;7909.050913890313;7980.4413691041655;7985.441019128663;7988.640511177011;7995.999999999971;7844;7913.920860791376;7916.010498687664;7920.881186782203;7924.841503169962;37541;37737.622623773685;37866.24267514662;37906.24187516262;37945.81027094847;3512.8594856205723;3546.4503002034726;3557.324108419418;3565.6612621800887;3566;31171.68828311733;31387.84306078455;31403.68596314053;31445;31453.213669658213;4982.153033984195;5166.225066240057;5251.737413129366;5256;5283.260343551906;172739;172748;173416;173621;173678.13160934116;47469.28796068062;47608.76195619;47664.046719066115;47697.28454073192;47735;49034.75482622635;51135;51759.74120129375;51836.22245666243;52003.47996520062;12810.359482025862;15447.768283475756;15506.689866202612;15542.222888855513;15544.455944041903;10428.895711042944;12419.130660853654;12729.427175777113;12759.29823859678;12768.170068945488;14318.713625727427;14353.569392918229;14387.856121438652;14397.280135993367;14440.711185776225;5549.139883318092;5561.249231353715;5762.596618236767;5814.796482123105;5868.911966320423;1008.2790804574618;1009.0565321424344;1012.5342342522375;1016.2022812092472;1017.2319898476603;22855.657165142537;22958.770412295664;23017.079316827476;23099.96050177746;23469.295921122393;1105.2263415609107;1105.6130354375905;1114.3648120571359;1119.658504156224;1120.1654767198327;12088.395580220953;12108.394580270951;12375.504979800708;12418.441170147365;12479.688007799878;221721;221739;223907;224630;224713;50693.49306506961;50928;51383.486165138616;51400.228996564336;51574.22638660348;1416.277698373816;1439.7264519741216;1440.625437386287;1442.7980082788417;1455.8398576156615;22629.43426414353;22811.88594057019;22872.428189294737;22974.540509190057;23108.884455577616;37083.25833483369;37236.627633723314;37241.81379093087;37355.252894942496;37388.439173411876;189338;189515;189749;190114;190810;33852;35319.470207947415;35742.4638630426;36011.819940900124;36050.63949360473;44014.779926100164;45186.548134518234;45345.77327113343;45370.31944520901;45430.31854522251;32794.67205327916;32799.344013120084;33047;33112.50331244985;33467.497987530696;32423.351532969682;32646.673533264362;32862.34275314432;33051.17372065622;33567.32865342631;36746.448803267434;36861.44707829438;36875.26249475049;37788.05529861776;38138.80930595329;32471.512927305637;32659.510107347935;32791;32804;33384;24018.15936442216;24484.6327305087;24846.751532484443;24876.12933547317;25008 17:28:34 INFO - PID 4163 | |2;jquery.html;558.312346948406;566.2751677852348;568.2584227583003;568.3577557360182;585.7891159182693;21892.89053554732;22558.88720556397;22595.887020564896;22743.658845117337;22750.77249227507;22125.00437480317;24007.63988540173;24033.279001629937;24033.279001629937;24114.75885241147;7087.184973728023;7236.927630723704;7242.71029158833;7248.927510724904;7250.891236631454;6772.08576842125;7057.752978645748;7087.291270872897;7212.17060038097;7279.4176465882865;7080.221175670672;7087.681054352541;7089.71641134354;7101.467389945749;7101.751438699646;7096.467764917626;7096.7516136934955;7099.538529995534;7109.680064397115;7111.288871112899;6908.9309106909295;6948.305169483062;6951.7566885159285;6953.548019378774;6969.546979446371;30222.395552089056;30533.38933221323;30922.845385772926;31090.689093109006;31208;2057.5781964697203;2079.8960051997337;2097.1925808563815;2097.3812725245934;2098.8635738676935;20328.475364347585;20933.685994710093;20937.790622093737;20940;20947.895260523754;2473.9257822265363;2492.887820048102;2522.6846644169477;2580.548404029287;2588.974110258911;136178;139722;140056;140311;140323.29838350744;20017;20992.950352482316;21482.570348593254;21756.347309580422;22292.777072229394;41679.374809377885;41742.37386439206;42213.57786422096;42334.57665423368;42421;11274.210805243718;11338.716532086599;11411.144164187719;11412.942935285271;11476.88523114775;8289.95855020721;9341.766455838659;9372.95313523428;9383.343165978316;9546.18857397115;9376.531173441437;11171;11377.544898204145;11437.885621143849;11466.140039497071;3916.962005068659;3978.980105099456;4062.7765472899273;4120.134771697917;4140.948043387123;946.2477330522252;965.6234068713208;966.6085235479642;971.8920430708997;973.1679414100842;19259.325923592605;19529.21883124659;19677.50806229827;19825;20160.69758953617;1035.1201478743008;1037.4501514197486;1039.740064983754;1039.870016247969;1045.6653870761436;10748.89251107479;11194.10447164225;12819.871801281868;13718.93140534291;13897.235652039033;481387;485515;489746;489835;492423;116655.41672291585;116976;117950;119497.40251298861;119930;1201.4953719437858;1233.962981110568;1253.4234252243887;1257.4844313831286;1262.2868079535033;20820.479488012315;21062.995077338142;21104.788952110895;21419.357419277447;21594.2442014535;55366;56981.43018569927;56989.71505142448;57229.42770572241;59157.40842591519;69446;69819;70481.29518704953;70618.29381706323;71413.64293178501;14139.787903181666;14599.051061680957;14694.55916322512;14729.263536822902;14733.26333683333;21790.782092178877;22972.19597314086;23003.539929201655;23033.539329213658;23308.417289567897;18229.635407291513;18274.543136421697;18325;18419.171137298585;18514.537136571154;16206.351745930031;16612.667746644758;16645.16774161263;17027.148642567576;17193.14034298305;17955.730664039787;18861.622767544846;18955.431337059912;19078.236870525023;19098.236070557577;14493.637659058186;14586.270686465847;14607.780883286547;14635.560933172022;14655.63360915943;17141.657166856345;18184.90907545454;18201.81798182001;18298.085095744893;18314.175862085973 17:28:34 INFO - PID 4163 | |3;mootools.html;1306.7778477658796;1353.0934274036401;1372.2932689664824;1389.2770170465885;1394.6513371657086;14497.347619357128;15106.39574417022;15107.320170592322;15113.471028514005;15124.773128403069;15222.847771522282;15806.762898556532;15812.920935395336;15817.288222030007;15819.52541423759;2697.4739925714493;2759.2412086676222;2768.349437882096;2771.459565384751;2774.9445011099765;2658.853763043032;2695.0163190435446;2730.167298973812;2772.4455108978186;2775.4726601945567;2752.146834481317;2781.5549512078073;2781.805273630847;2785.4429114177146;2785.7492825645686;2675.0904692404483;2699.9730002700016;2703.175531462903;2704.5672692369126;2710.7966902482394;2652.270625577962;2654.9601755973677;2673.1178711025314;2673.31830383251;2675.772559332459;14639.19484428356;14858.851411485855;14980.625484362978;14997.100173989596;15000.849991500054;7445.516041457288;7625.923740762577;7717.189695082048;7726.922730772677;7729.729459468947;5480.671159730429;5619.269494965669;5672.808710170869;5737.885242295172;5773.566982476289;1933.2556965568217;1937.9127939242767;1938.8255057044792;1941.2914286285545;1987.6024795040905;25578.36054098644;25864.22407327783;25928;25977.350566235807;25994.6100808488;6667.43326817226;6687.665616719145;6758.560693554903;6878.415334696607;6906.447484201352;17024.744628830576;17030.91484542594;17030.91484542594;17068.829311706973;17073;7418.777436676908;7708.344790692854;7735.381169506426;7749.7675069748;7752.147263800977;7085.574865508086;7159.534630249017;7235.63821808918;7250.746223882139;7299.087614048244;5055.797768089235;5243.8164664236565;5244.344456942882;5277.997180535763;5282.471752824764;1299.6490947444327;1312.4028567001992;1504.0975414751063;1520.908745475275;1522.7868098466224;878.8769572259761;899.4648184330313;901.9233365164034;901.9582382348398;912.493566070837;8326.583670816435;8757.824843503095;8767.342449316327;8783.341249406192;8856.734297971072;963.2679163835536;994.5226291380034;999.7600575861741;1018.2363227579316;1038.4651904269308;4943.728094954812;5031.7987280509105;5156.458571849902;5164.070467315844;5189.143791274398;20466.795332046786;20943.790562094186;21078.367648970554;21092.78907210908;21409.250676226256;20702.792972070387;20804.895975520027;20852.1659133636;20879.895600522203;20932.267370641955;10353.741156470998;10686.893131068746;10730.35617862915;10754.677359679223;10767.138628909668;8504.40469167177;8588.22705956467;8655.826883462421;8703.91296087031;8736.301095912184;17690.64618707593;18423.170957306684;18537.35119270819;18736.62526749485;18919.527011824815;18176.09119544044;18599.81400185981;18817.247310107443;18906.338278160198;19172.424827255203;10903.56385744593;11021.889781102087;11030.779384412428;11070.335779853234;11106.666799996014;13800.447982080605;13840.515581954583;13941.302934853014;14027.649308766955;14084.507042253472;10898.618548350769;10903.781924361627;11011.339319640847;11093.11255099574;11217.214794964275;11021.118310534977;11132.554697811996;11192.272502287326;11258.324500529994;11386.772264554827;9594.42433453995;9889.406635601887;10221.131203847755;10292.433916134685;10335.069843714102;7207.9639601801655;7218.855622887408;7238.203797582157;7284.344409003215;7339.6330183489545;7660.808479787827;7721.420893433127;7776.183500732342;7822.765317040498;7850.4112191587 17:28:34 INFO - PID 4163 | |4;prototype.html;498.97709695124985;501.1155310876304;515.6721442286112;521.7964993652475;524.4336116993645;6361.1412459318035;6414.262359828621;6417.58285711429;6427.646479443629;6450.870982580357;6199.907001394971;6202.689865506718;6223.935832829737;6282.811515654526;6292.748290068404;5141.254518094874;5197.68813871168;5201;5208.947910520884;5214.843554693346;4902.039184326262;4996.875078123039;5008.574271186953;5037.168867136918;5052.823151189709;4898.755062246892;4931.432885218192;4943.269855550565;5011.749412529359;5066.645334826564;4922.360093187898;4946.597528199905;4957.454679985199;5012.724300163508;5040.899182016376;4493.191225579394;4498.783369890544;4507.391502147234;4521.864344069683;4527.796249168795;6184.505239580868;6247.75008999644;6285.937140628581;6315.24217093947;6321.367863213688;5681.801136960186;5709.77160913563;5751.792123654038;5762.942370576325;5779.768809247625;6519.152510173647;6560.212774467047;6610.471162307004;6634.170728658918;6680.799576012728;4830.550834749596;4900.950990490085;4914.0908931847725;4914.852554423373;4919.606431485508;6565.277819439858;6735.393814556714;6827.931720682829;6969.965150174267;7025.156981162293;4962.181240095344;4996.5003499650475;5018.648694591417;5039.193729003342;5054.115529782273;4976.576990955737;5030.647854650139;5050.267711181858;5077.923831142536;5081.898362032813;5721.456461636138;5730.770769169187;5734.2258795062135;5758.712064396764;5773.134029895549;6693.564918280296;6699.263081061079;6723.193216814014;6730.02414649873;6741.561798483083;1750.1074452029557;1755.0873545756135;1755.877088603811;1758.5691505581215;1760.9735853962202;1154.1978325064167;1209.9153059285766;1218.9329586872807;1233.340163012784;1235.2835355493971;5970.76116955317;6002.939970600238;6014.180995068075;6032.155498230251;6039.456448919531;6790.694418751168;6849.2465828758795;6887.519183375498;6888;6896.448284137257;5919.141724449931;5937.435943585352;5945.375735547792;5945.643261404243;5973.940260597338;3603.891883243507;3654.7076233901225;3655.250673611903;3667.5965643779164;3679.4848721179055;6645.900311495331;6663.967894607942;6681.599104053772;6703.061571380011;6710.396064354231;7016.158061032606;7046.788596342118;7063.92936070643;7106.324899134574;7113.288671132846;6195.760952366723;6539.803805885831;6605.834854128685;6668.966655166693;6672.099266599044;6827.829304267433;6850;6853.629274145092;6877.209120951155;6889.724411023503;6697.7655782047395;6861.965690171517;6875.5530890491955;6896.931030689629;6909.93090069113;6663.733450662113;6680.899786503109;6689.297623749436;6757.729690812509;6758.391744742997;5061.544460998381;5061.544460998528;5064.670796398223;5082.974585127051;5105.387353517602;6735.730570777114;6785.1518560179975;6826.863462730618;6929;6929;6654.537821006259;6670.166229221348;6693.698783554654;6704;6707.1616047994;6616.172978377703;6686.83282917931;6695.363940425652;6695.430888374348;6710.429613482908;6480.837979050561;6572.047053177358;6611;6630.46956243509;6633.1376920999965;6702.68757278738;6730.226024007302;6737.73049078051;6742.393184613408;6782.796516104525;6448.613083215022;6496.285408604956;6539.215294164731;6539.247986481425;6541.934580654323 17:28:34 INFO - PID 4163 | |5;yui.html;832.3507664022061;846.6613354658132;850.7490290364344;880.3309484791556;898.2858627391223;16573.25420356084;16850.157492125392;18677.346292879753;19189.712154317698;20622;19572.510687232825;19844.900775496102;20130;20235.089420976055;20592.485187870312;924.4684306523742;935.5041827831226;942.1097063275173;942.1661829281102;946.6402766948585;952.4570994533119;960.4429430930079;961.2838435365662;961.9134277914986;962.874826272587;893.0266010049045;976.7899901521172;991.8462638291077;992.9056739609725;993.7913038261973;973.1825266775907;978.7797745507276;979.7648564344577;981.906718861707;982.1406269514175;714.1715609892551;717.8636059148745;720.4092644031896;720.6973071310063;720.7225218290944;18280.90859545694;18694.252229910788;18798.3420580279;18963.905180474147;19415.126319315525;20689.68965465504;20922.476938076517;21159.576808463746;21336.573268534543;21338.466538336666;16161.91919040409;16317.51047468566;16328.591785205346;16349.427770027993;16966;3774.5093137892172;3803.6386543278345;3823.330917089498;3824.7896365699876;3826.6364695353896;116293;120363.39818300852;121354;123092.38453807763;128412.71587284101;16716;16906.661866762595;17408.825911740973;17495.212715427835;17511.474655760347;30222.697773021988;30439.239019024702;30485.847570762005;30546.38907221843;30686;7200.279972002758;7568.508046976929;8465.957670211732;8931.821363572823;9231.353805233703;4272.850450234227;4388.539203383662;4397.538258482878;4399.780010999437;4402.3176407656865;5150.639455238097;5219.660722053054;5276.1030624793875;5282.049231138432;5293.3647962244;1008.566316483919;1008.7326858382535;1028.5885645741614;1032.7986042721748;1038.096855735515;694.885343918258;711.3917600451642;714.424887965193;717.0785540580433;721.1598487761804;11154.219204655596;11718.30535890811;12433.813492797615;13085.345732713326;13271.93364033193;703.6622421237835;704.2253521126822;709.3722055980494;718.0019772516207;721.6680327049615;5611;5645.069021266012;5661.886762264731;5745.1669507921115;5768.451997060272;107755.46122269338;107937;108255;110149;111016;94745;95611;97880;98614;101726;82085.58957205176;86613.5669321662;86633;87106.56446717726;92909;12308.569200077955;12770.169938953939;12788.424520896768;12823.268858703956;13089.738205236032;38815.80592097021;40478.190436190525;40654.39018414785;40776.3883541753;43024.92437689083;69919.30080699331;73309;75653.62173189099;75661.24338756542;79645;18659.16033778456;18911.148998294833;19337.4198774037;19820.20719171217;19946.102425391182;18701.71947420817;19281.807181928;19494;21425.678614821103;21587.2444464443;20111.19555217775;20494.180232791117;20655.586888262453;20846.270380536607;20909.89545052265;20435.795642043984;20472.385828425173;20806;20977;21341;20197.59604807925;20442.38672839817;20834.583308333444;21019.369418917457;21139.894300528398;14003.43986240539;14010.369533371226;14532.782008270096;14671.339789709275;14724.779128313297;14387;14643.26783660834;14714.337854796773;14756.335964881391;14850.257487125384 17:28:34 INFO - PID 4163 | __end_tp_report 17:28:34 INFO - PID 4163 | __start_cc_report 17:28:34 INFO - PID 4163 | _x_x_mozilla_cycle_collect,364 17:28:34 INFO - PID 4163 | __end_cc_report 17:28:34 INFO - PID 4163 | __startTimestamp1503707314412__endTimestamp 17:28:34 INFO - PID 4163 | 17:28:34 INFO - PID 4163 | ------- Summary: start ------- 17:28:34 INFO - PID 4163 | Number of tests: 6 17:28:34 INFO - PID 4163 | 17:28:34 INFO - PID 4163 | [#0] dojo.html Cycles:165 Average:6211.22 Median:6474.81 stddev:715.79 (11.1%) stddev-sans-first:680.47 17:28:34 INFO - PID 4163 | Values: 3295.9 3315.6 3316.1 3322.4 3329.0 6234.3 6307.1 6336.9 6368.7 6370.9 6143.8 6262.5 6265.6 6282.9 6297.2 5568.0 5575.4 5579.2 5747.1 5829.5 5820.7 5835.7 5859.4 5865.9 5872.9 5655.5 5740.8 5768.6 5914.2 5918.9 5720.1 5729.7 5772.4 5818.1 5833.9 5716.9 5771.9 5782.0 5812.2 5839.0 6101.7 6133.2 6223.5 6284.0 6357.6 6553.2 6557.3 6563.4 6593.8 6639.2 6825.5 6835.1 6837.7 6848.8 6861.0 6231.2 6257.4 6285.7 6291.2 6368.4 6758.6 6890.8 6946.2 6962.2 7017.9 6480.9 6544.3 6586.6 6629.9 6646.1 6741.5 6754.3 6787.9 6802.3 6806.9 6634.3 6652.4 6679.2 6700.2 6764.2 6593.7 6686.8 6699.1 6794.3 6801.7 5259.7 5259.9 5305.4 5316.0 5319.8 4670.6 4722.4 4733.6 4738.2 4746.9 5909.6 5941.8 5948.8 6038.5 6041.4 6588.9 6760.5 6760.9 6856.3 6862.0 5820.5 5877.5 5936.7 5944.6 5949.7 5825.1 5874.9 5876.9 5878.5 5881.6 6540.5 6548.5 6559.7 6568.6 6661.7 6661.9 6734.7 6757.6 6856.7 6861.8 6580.6 6584.8 6594.1 6604.7 6624.2 6617.8 6622.6 6731.0 6745.3 6769.4 6753.5 6753.7 6848.3 6854.2 6868.9 6351.2 6444.4 6474.8 6542.4 6633.6 6510.3 6565.2 6594.0 6597.0 6602.9 6497.4 6509.7 6551.4 6570.6 6601.8 6522.4 6574.4 6590.0 6635.6 6684.3 6342.1 6413.2 6421.9 6422.9 6437.8 17:28:34 INFO - PID 4163 | 17:28:34 INFO - PID 4163 | [#1] ext.html Cycles:180 Average:36779.56 Median:3551.89 stddev:50870.13 (1432.2%) stddev-sans-first:50941.14 17:28:34 INFO - PID 4163 | Values: 812.2 841.8 869.6 874.2 1122.9 30783.2 41545.6 42160.0 42185.0 42195.6 41677.2 41722.0 41854.4 41992.2 42059.6 7386.6 7820.2 7980.1 7984.4 7990.4 7807.4 7940.8 7943.3 7945.4 7949.7 7911.7 7997.8 8003.4 8003.8 8015.1 7909.1 7980.4 7985.4 7988.6 7996.0 7844.0 7913.9 7916.0 7920.9 7924.8 37541.0 37737.6 37866.2 37906.2 37945.8 3512.9 3546.5 3557.3 3565.7 3566.0 31171.7 31387.8 31403.7 31445.0 31453.2 4982.2 5166.2 5251.7 5256.0 5283.3 172739.0 172748.0 173416.0 173621.0 173678.1 47469.3 47608.8 47664.0 47697.3 47735.0 49034.8 51135.0 51759.7 51836.2 52003.5 12810.4 15447.8 15506.7 15542.2 15544.5 10428.9 12419.1 12729.4 12759.3 12768.2 14318.7 14353.6 14387.9 14397.3 14440.7 5549.1 5561.2 5762.6 5814.8 5868.9 1008.3 1009.1 1012.5 1016.2 1017.2 22855.7 22958.8 23017.1 23100.0 23469.3 1105.2 1105.6 1114.4 1119.7 1120.2 12088.4 12108.4 12375.5 12418.4 12479.7 221721.0 221739.0 223907.0 224630.0 224713.0 50693.5 50928.0 51383.5 51400.2 51574.2 1416.3 1439.7 1440.6 1442.8 1455.8 22629.4 22811.9 22872.4 22974.5 23108.9 37083.3 37236.6 37241.8 37355.3 37388.4 189338.0 189515.0 189749.0 190114.0 190810.0 33852.0 35319.5 35742.5 36011.8 36050.6 44014.8 45186.5 45345.8 45370.3 45430.3 32794.7 32799.3 33047.0 33112.5 33467.5 32423.4 32646.7 32862.3 33051.2 33567.3 36746.4 36861.4 36875.3 37788.1 38138.8 32471.5 32659.5 32791.0 32804.0 33384.0 24018.2 24484.6 24846.8 24876.1 25008.0 17:28:34 INFO - PID 4163 | 17:28:34 INFO - PID 4163 | [#2] jquery.html Cycles:180 Average:35852.56 Median:22208.89 stddev:82184.30 (370.1%) stddev-sans-first:82372.13 17:28:34 INFO - PID 4163 | Values: 558.3 566.3 568.3 568.4 585.8 21892.9 22558.9 22595.9 22743.7 22750.8 22125.0 24007.6 24033.3 24033.3 24114.8 7087.2 7236.9 7242.7 7248.9 7250.9 6772.1 7057.8 7087.3 7212.2 7279.4 7080.2 7087.7 7089.7 7101.5 7101.8 7096.5 7096.8 7099.5 7109.7 7111.3 6908.9 6948.3 6951.8 6953.5 6969.5 30222.4 30533.4 30922.8 31090.7 31208.0 2057.6 2079.9 2097.2 2097.4 2098.9 20328.5 20933.7 20937.8 20940.0 20947.9 2473.9 2492.9 2522.7 2580.5 2589.0 136178.0 139722.0 140056.0 140311.0 140323.3 20017.0 20993.0 21482.6 21756.3 22292.8 41679.4 41742.4 42213.6 42334.6 42421.0 11274.2 11338.7 11411.1 11412.9 11476.9 8290.0 9341.8 9373.0 9383.3 9546.2 9376.5 11171.0 11377.5 11437.9 11466.1 3917.0 3979.0 4062.8 4120.1 4140.9 946.2 965.6 966.6 971.9 973.2 19259.3 19529.2 19677.5 19825.0 20160.7 1035.1 1037.5 1039.7 1039.9 1045.7 10748.9 11194.1 12819.9 13718.9 13897.2 481387.0 485515.0 489746.0 489835.0 492423.0 116655.4 116976.0 117950.0 119497.4 119930.0 1201.5 1234.0 1253.4 1257.5 1262.3 20820.5 21063.0 21104.8 21419.4 21594.2 55366.0 56981.4 56989.7 57229.4 59157.4 69446.0 69819.0 70481.3 70618.3 71413.6 14139.8 14599.1 14694.6 14729.3 14733.3 21790.8 22972.2 23003.5 23033.5 23308.4 18229.6 18274.5 18325.0 18419.2 18514.5 16206.4 16612.7 16645.2 17027.1 17193.1 17955.7 18861.6 18955.4 19078.2 19098.2 14493.6 14586.3 14607.8 14635.6 14655.6 17141.7 18184.9 18201.8 18298.1 18314.2 17:28:34 INFO - PID 4163 | 17:28:34 INFO - PID 4163 | [#3] mootools.html Cycles:180 Average:9259.65 Median:2653.62 stddev:6519.83 (245.7%) stddev-sans-first:6510.74 17:28:34 INFO - PID 4163 | Values: 1306.8 1353.1 1372.3 1389.3 1394.7 14497.3 15106.4 15107.3 15113.5 15124.8 15222.8 15806.8 15812.9 15817.3 15819.5 2697.5 2759.2 2768.3 2771.5 2774.9 2658.9 2695.0 2730.2 2772.4 2775.5 2752.1 2781.6 2781.8 2785.4 2785.7 2675.1 2700.0 2703.2 2704.6 2710.8 2652.3 2655.0 2673.1 2673.3 2675.8 14639.2 14858.9 14980.6 14997.1 15000.8 7445.5 7625.9 7717.2 7726.9 7729.7 5480.7 5619.3 5672.8 5737.9 5773.6 1933.3 1937.9 1938.8 1941.3 1987.6 25578.4 25864.2 25928.0 25977.4 25994.6 6667.4 6687.7 6758.6 6878.4 6906.4 17024.7 17030.9 17030.9 17068.8 17073.0 7418.8 7708.3 7735.4 7749.8 7752.1 7085.6 7159.5 7235.6 7250.7 7299.1 5055.8 5243.8 5244.3 5278.0 5282.5 1299.6 1312.4 1504.1 1520.9 1522.8 878.9 899.5 901.9 902.0 912.5 8326.6 8757.8 8767.3 8783.3 8856.7 963.3 994.5 999.8 1018.2 1038.5 4943.7 5031.8 5156.5 5164.1 5189.1 20466.8 20943.8 21078.4 21092.8 21409.3 20702.8 20804.9 20852.2 20879.9 20932.3 10353.7 10686.9 10730.4 10754.7 10767.1 8504.4 8588.2 8655.8 8703.9 8736.3 17690.6 18423.2 18537.4 18736.6 18919.5 18176.1 18599.8 18817.2 18906.3 19172.4 10903.6 11021.9 11030.8 11070.3 11106.7 13800.4 13840.5 13941.3 14027.6 14084.5 10898.6 10903.8 11011.3 11093.1 11217.2 11021.1 11132.6 11192.3 11258.3 11386.8 9594.4 9889.4 10221.1 10292.4 10335.1 7208.0 7218.9 7238.2 7284.3 7339.6 7660.8 7721.4 7776.2 7822.8 7850.4 17:28:34 INFO - PID 4163 | 17:28:34 INFO - PID 4163 | [#4] prototype.html Cycles:180 Average:5609.09 Median:6265.28 stddev:1590.13 (25.4%) stddev-sans-first:1547.64 17:28:34 INFO - PID 4163 | Values: 499.0 501.1 515.7 521.8 524.4 6361.1 6414.3 6417.6 6427.6 6450.9 6199.9 6202.7 6223.9 6282.8 6292.7 5141.3 5197.7 5201.0 5208.9 5214.8 4902.0 4996.9 5008.6 5037.2 5052.8 4898.8 4931.4 4943.3 5011.7 5066.6 4922.4 4946.6 4957.5 5012.7 5040.9 4493.2 4498.8 4507.4 4521.9 4527.8 6184.5 6247.8 6285.9 6315.2 6321.4 5681.8 5709.8 5751.8 5762.9 5779.8 6519.2 6560.2 6610.5 6634.2 6680.8 4830.6 4901.0 4914.1 4914.9 4919.6 6565.3 6735.4 6827.9 6970.0 7025.2 4962.2 4996.5 5018.6 5039.2 5054.1 4976.6 5030.6 5050.3 5077.9 5081.9 5721.5 5730.8 5734.2 5758.7 5773.1 6693.6 6699.3 6723.2 6730.0 6741.6 1750.1 1755.1 1755.9 1758.6 1761.0 1154.2 1209.9 1218.9 1233.3 1235.3 5970.8 6002.9 6014.2 6032.2 6039.5 6790.7 6849.2 6887.5 6888.0 6896.4 5919.1 5937.4 5945.4 5945.6 5973.9 3603.9 3654.7 3655.3 3667.6 3679.5 6645.9 6664.0 6681.6 6703.1 6710.4 7016.2 7046.8 7063.9 7106.3 7113.3 6195.8 6539.8 6605.8 6669.0 6672.1 6827.8 6850.0 6853.6 6877.2 6889.7 6697.8 6862.0 6875.6 6896.9 6909.9 6663.7 6680.9 6689.3 6757.7 6758.4 5061.5 5061.5 5064.7 5083.0 5105.4 6735.7 6785.2 6826.9 6929.0 6929.0 6654.5 6670.2 6693.7 6704.0 6707.2 6616.2 6686.8 6695.4 6695.4 6710.4 6480.8 6572.0 6611.0 6630.5 6633.1 6702.7 6730.2 6737.7 6742.4 6782.8 6448.6 6496.3 6539.2 6539.2 6541.9 17:28:34 INFO - PID 4163 | 17:28:34 INFO - PID 4163 | [#5] yui.html Cycles:180 Average:24021.32 Median:25904.97 stddev:31896.85 (123.1%) stddev-sans-first:31938.80 17:28:34 INFO - PID 4163 | Values: 832.4 846.7 850.7 880.3 898.3 16573.3 16850.2 18677.3 19189.7 20622.0 19572.5 19844.9 20130.0 20235.1 20592.5 924.5 935.5 942.1 942.2 946.6 952.5 960.4 961.3 961.9 962.9 893.0 976.8 991.8 992.9 993.8 973.2 978.8 979.8 981.9 982.1 714.2 717.9 720.4 720.7 720.7 18280.9 18694.3 18798.3 18963.9 19415.1 20689.7 20922.5 21159.6 21336.6 21338.5 16161.9 16317.5 16328.6 16349.4 16966.0 3774.5 3803.6 3823.3 3824.8 3826.6 116293.0 120363.4 121354.0 123092.4 128412.7 16716.0 16906.7 17408.8 17495.2 17511.5 30222.7 30439.2 30485.8 30546.4 30686.0 7200.3 7568.5 8466.0 8931.8 9231.4 4272.9 4388.5 4397.5 4399.8 4402.3 5150.6 5219.7 5276.1 5282.0 5293.4 1008.6 1008.7 1028.6 1032.8 1038.1 694.9 711.4 714.4 717.1 721.2 11154.2 11718.3 12433.8 13085.3 13271.9 703.7 704.2 709.4 718.0 721.7 5611.0 5645.1 5661.9 5745.2 5768.5 107755.5 107937.0 108255.0 110149.0 111016.0 94745.0 95611.0 97880.0 98614.0 101726.0 82085.6 86613.6 86633.0 87106.6 92909.0 12308.6 12770.2 12788.4 12823.3 13089.7 38815.8 40478.2 40654.4 40776.4 43024.9 69919.3 73309.0 75653.6 75661.2 79645.0 18659.2 18911.1 19337.4 19820.2 19946.1 18701.7 19281.8 19494.0 21425.7 21587.2 20111.2 20494.2 20655.6 20846.3 20909.9 20435.8 20472.4 20806.0 20977.0 21341.0 20197.6 20442.4 20834.6 21019.4 21139.9 14003.4 14010.4 14532.8 14671.3 14724.8 14387.0 14643.3 14714.3 14756.3 14850.3 17:28:34 INFO - PID 4163 | -------- Summary: end -------- 17:28:34 INFO - PID 4163 | 17:28:35 INFO - TEST-INFO | 4163: exit 0 17:28:35 INFO - TEST-OK | dromaeo_css | took 1110800ms 17:28:35 INFO - TEST-START | kraken 17:28:35 INFO - Initialising browser for kraken test... 17:28:35 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:50240/getInfo.html -profile /tmp/tmpFXhl4z/profile 17:28:35 INFO - TEST-INFO | started process 4591 (/builds/slave/test/build/application/firefox/firefox http://localhost:50240/getInfo.html) 17:28:40 INFO - TEST-INFO | 4591: exit 0 17:28:40 INFO - Browser initialized. 17:28:40 INFO - Running cycle 1/1 for kraken test... 17:28:40 INFO - Using env: {'DISPLAY': ':0', 17:28:40 INFO - 'HOME': '/home/cltbld', 17:28:40 INFO - 'JSGC_DISABLE_POISONING': '1', 17:28:40 INFO - 'LANG': 'en_US.UTF-8', 17:28:40 INFO - 'LANGUAGE': 'en_US:en', 17:28:40 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 17:28:40 INFO - 'LOGNAME': 'cltbld', 17:28:40 INFO - 'MAIL': '/var/mail/cltbld', 17:28:40 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:28:40 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 17:28:40 INFO - 'MOZ_CRASHREPORTER': '1', 17:28:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:28:40 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 17:28:40 INFO - 'MOZ_NO_REMOTE': '1', 17:28:40 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:28:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:28:40 INFO - 'NO_EM_RESTART': '1', 17:28:40 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:28:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:28:40 INFO - 'PWD': '/builds/slave/test', 17:28:40 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:28:40 INFO - 'RUST_BACKTRACE': 'full', 17:28:40 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 17:28:40 INFO - 'SHELL': '/bin/bash', 17:28:40 INFO - 'SHLVL': '1', 17:28:40 INFO - 'STYLO_FORCE_ENABLED': '1', 17:28:40 INFO - 'TERM': 'linux', 17:28:40 INFO - 'TMOUT': '86400', 17:28:40 INFO - 'USER': 'cltbld', 17:28:40 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1503706134.484861-159888122', 17:28:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:28:40 INFO - '_': '/tools/buildbot/bin/python'} 17:28:40 INFO - TEST-INFO | started process 4779 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpFXhl4z/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 17:28:41 INFO - PID 4779 | 17:28:41 INFO - PID 4779 | (/builds/slave/test/build/application/firefox/firefox:4830): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 17:28:41 INFO - PID 4779 | 17:28:41 INFO - PID 4779 | 17:28:41 INFO - PID 4779 | (/builds/slave/test/build/application/firefox/firefox:4889): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 17:28:41 INFO - PID 4779 | 17:28:41 INFO - PID 4779 | __metrics Screen width/height:1600/1200 17:28:41 INFO - PID 4779 | colorDepth:24 17:28:41 INFO - PID 4779 | Browser inner width/height: 1024/768 17:28:41 INFO - PID 4779 | __metrics 17:28:51 INFO - PID 4779 | RSS: Main: 175124480 17:28:51 INFO - PID 4779 | 17:28:54 INFO - PID 4779 | Cycle 1(1): loaded http://localhost:50240/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:50240/tests/kraken/driver.html?testName=audio-beat-detection) 17:28:55 INFO - PID 4779 | RSS: Main: 178810880 17:28:55 INFO - PID 4779 | 17:28:57 INFO - PID 4779 | Cycle 1(1): loaded http://localhost:50240/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:50240/tests/kraken/driver.html?testName=audio-dft) 17:28:58 INFO - PID 4779 | RSS: Main: 179429376 17:28:58 INFO - PID 4779 | 17:29:01 INFO - PID 4779 | Cycle 1(1): loaded http://localhost:50240/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:50240/tests/kraken/driver.html?testName=audio-fft) 17:29:02 INFO - PID 4779 | RSS: Main: 174522368 17:29:02 INFO - PID 4779 | 17:29:04 INFO - PID 4779 | Cycle 1(1): loaded http://localhost:50240/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:50240/tests/kraken/driver.html?testName=audio-oscillator) 17:29:04 INFO - PID 4779 | RSS: Main: 175046656 17:29:04 INFO - PID 4779 | 17:29:07 INFO - PID 4779 | Cycle 1(1): loaded http://localhost:50240/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:50240/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:29:07 INFO - PID 4779 | RSS: Main: 175509504 17:29:07 INFO - PID 4779 | 17:29:11 INFO - PID 4779 | Cycle 1(1): loaded http://localhost:50240/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:50240/tests/kraken/driver.html?testName=imaging-darkroom) 17:29:11 INFO - PID 4779 | RSS: Main: 176513024 17:29:11 INFO - PID 4779 | 17:29:15 INFO - PID 4779 | Cycle 1(1): loaded http://localhost:50240/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:50240/tests/kraken/driver.html?testName=imaging-desaturate) 17:29:15 INFO - PID 4779 | RSS: Main: 176189440 17:29:15 INFO - PID 4779 | 17:29:18 INFO - PID 4779 | Cycle 1(1): loaded http://localhost:50240/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:50240/tests/kraken/driver.html?testName=json-parse-financial) 17:29:19 INFO - PID 4779 | RSS: Main: 176713728 17:29:19 INFO - PID 4779 | 17:29:21 INFO - PID 4779 | Cycle 1(1): loaded http://localhost:50240/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:50240/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:29:21 INFO - PID 4779 | RSS: Main: 176500736 17:29:21 INFO - PID 4779 | 17:29:23 INFO - PID 4779 | Cycle 1(1): loaded http://localhost:50240/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:50240/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:29:24 INFO - PID 4779 | RSS: Main: 176517120 17:29:24 INFO - PID 4779 | 17:29:26 INFO - PID 4779 | Cycle 1(1): loaded http://localhost:50240/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:50240/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:29:26 INFO - PID 4779 | RSS: Main: 176787456 17:29:26 INFO - PID 4779 | 17:29:29 INFO - PID 4779 | Cycle 1(1): loaded http://localhost:50240/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:50240/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:29:30 INFO - PID 4779 | RSS: Main: 176918528 17:29:30 INFO - PID 4779 | 17:29:33 INFO - PID 4779 | Cycle 1(1): loaded http://localhost:50240/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:50240/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:29:34 INFO - PID 4779 | RSS: Main: 177238016 17:29:34 INFO - PID 4779 | 17:29:35 INFO - PID 4779 | Cycle 1(1): loaded http://localhost:50240/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:50240/tests/kraken/driver.html?testName=ai-astar) 17:29:36 INFO - PID 4779 | RSS: Main: 178569216 17:29:36 INFO - PID 4779 | 17:29:36 INFO - PID 4779 | __start_tp_report 17:29:36 INFO - PID 4779 | _x_x_mozilla_page_load 17:29:36 INFO - PID 4779 | _x_x_mozilla_page_load_details 17:29:36 INFO - PID 4779 | |i|pagename|runs| 17:29:36 INFO - PID 4779 | |0;ai-astar;98;113;101;108;109;108;114;98;108;109 17:29:36 INFO - PID 4779 | |1;audio-beat-detection;154;117;159;166;124;151;148;116;173;120 17:29:36 INFO - PID 4779 | |2;audio-dft;159;167;161;162;161;171;163;165;166;165 17:29:36 INFO - PID 4779 | |3;audio-fft;79;74;78;169;75;77;74;84;98;77 17:29:36 INFO - PID 4779 | |4;audio-oscillator;83;85;152;79;79;79;133;80;88;136 17:29:36 INFO - PID 4779 | |5;imaging-gaussian-blur;114;114;114;114;114;118;114;114;120;114 17:29:36 INFO - PID 4779 | |6;imaging-darkroom;149;152;147;154;149;148;148;156;148;149 17:29:36 INFO - PID 4779 | |7;imaging-desaturate;94;94;94;94;99;95;96;95;94;93 17:29:36 INFO - PID 4779 | |8;json-parse-financial;52;51;52;51;51;52;51;51;51;52 17:29:36 INFO - PID 4779 | |9;json-stringify-tinderbox;56;55;55;55;54;61;55;56;54;63 17:29:36 INFO - PID 4779 | |10;stanford-crypto-aes;77;78;79;77;77;76;76;77;74;84 17:29:36 INFO - PID 4779 | |11;stanford-crypto-ccm;121;128;122;122;134;124;134;121;124;161 17:29:36 INFO - PID 4779 | |12;stanford-crypto-pbkdf2;176;187;169;178;175;173;169;176;194;170 17:29:36 INFO - PID 4779 | |13;stanford-crypto-sha256-iterative;71;71;66;68;66;64;63;65;63;67 17:29:36 INFO - PID 4779 | __end_tp_report 17:29:36 INFO - PID 4779 | __start_cc_report 17:29:36 INFO - PID 4779 | _x_x_mozilla_cycle_collect,908 17:29:36 INFO - PID 4779 | __end_cc_report 17:29:36 INFO - PID 4779 | __startTimestamp1503707376130__endTimestamp 17:29:36 INFO - PID 4779 | 17:29:36 INFO - PID 4779 | ------- Summary: start ------- 17:29:36 INFO - PID 4779 | Number of tests: 14 17:29:36 INFO - PID 4779 | 17:29:36 INFO - PID 4779 | [#0] ai-astar Cycles:10 Average:106.60 Median:111.00 stddev:5.70 (5.1%) stddev-sans-first:5.13 17:29:36 INFO - PID 4779 | Values: 98.0 113.0 101.0 108.0 109.0 108.0 114.0 98.0 108.0 109.0 17:29:36 INFO - PID 4779 | 17:29:36 INFO - PID 4779 | [#1] audio-beat-detection Cycles:10 Average:142.80 Median:152.50 stddev:21.58 (14.1%) stddev-sans-first:22.50 17:29:36 INFO - PID 4779 | Values: 154.0 117.0 159.0 166.0 124.0 151.0 148.0 116.0 173.0 120.0 17:29:36 INFO - PID 4779 | 17:29:36 INFO - PID 4779 | [#2] audio-dft Cycles:10 Average:164.00 Median:165.00 stddev:3.53 (2.1%) stddev-sans-first:3.24 17:29:36 INFO - PID 4779 | Values: 159.0 167.0 161.0 162.0 161.0 171.0 163.0 165.0 166.0 165.0 17:29:36 INFO - PID 4779 | 17:29:36 INFO - PID 4779 | [#3] audio-fft Cycles:10 Average:88.50 Median:77.50 stddev:29.17 (37.6%) stddev-sans-first:30.74 17:29:36 INFO - PID 4779 | Values: 79.0 74.0 78.0 169.0 75.0 77.0 74.0 84.0 98.0 77.0 17:29:36 INFO - PID 4779 | 17:29:36 INFO - PID 4779 | [#4] audio-oscillator Cycles:10 Average:99.40 Median:79.50 stddev:28.80 (36.2%) stddev-sans-first:29.93 17:29:36 INFO - PID 4779 | Values: 83.0 85.0 152.0 79.0 79.0 79.0 133.0 80.0 88.0 136.0 17:29:36 INFO - PID 4779 | 17:29:36 INFO - PID 4779 | [#5] imaging-gaussian-blur Cycles:10 Average:115.00 Median:114.00 stddev:2.16 (1.9%) stddev-sans-first:2.26 17:29:36 INFO - PID 4779 | Values: 114.0 114.0 114.0 114.0 114.0 118.0 114.0 114.0 120.0 114.0 17:29:36 INFO - PID 4779 | 17:29:36 INFO - PID 4779 | [#6] imaging-darkroom Cycles:10 Average:150.00 Median:149.00 stddev:2.98 (2.0%) stddev-sans-first:3.14 17:29:36 INFO - PID 4779 | Values: 149.0 152.0 147.0 154.0 149.0 148.0 148.0 156.0 148.0 149.0 17:29:36 INFO - PID 4779 | 17:29:36 INFO - PID 4779 | [#7] imaging-desaturate Cycles:10 Average:94.80 Median:94.50 stddev:1.69 (1.8%) stddev-sans-first:1.76 17:29:36 INFO - PID 4779 | Values: 94.0 94.0 94.0 94.0 99.0 95.0 96.0 95.0 94.0 93.0 17:29:36 INFO - PID 4779 | 17:29:36 INFO - PID 4779 | [#8] json-parse-financial Cycles:10 Average:51.40 Median:51.50 stddev:0.52 (1.0%) stddev-sans-first:0.50 17:29:36 INFO - PID 4779 | Values: 52.0 51.0 52.0 51.0 51.0 52.0 51.0 51.0 51.0 52.0 17:29:36 INFO - PID 4779 | 17:29:36 INFO - PID 4779 | [#9] json-stringify-tinderbox Cycles:10 Average:56.40 Median:55.50 stddev:3.06 (5.5%) stddev-sans-first:3.24 17:29:36 INFO - PID 4779 | Values: 56.0 55.0 55.0 55.0 54.0 61.0 55.0 56.0 54.0 63.0 17:29:36 INFO - PID 4779 | 17:29:36 INFO - PID 4779 | [#10] stanford-crypto-aes Cycles:10 Average:77.50 Median:77.00 stddev:2.64 (3.4%) stddev-sans-first:2.79 17:29:36 INFO - PID 4779 | Values: 77.0 78.0 79.0 77.0 77.0 76.0 76.0 77.0 74.0 84.0 17:29:36 INFO - PID 4779 | 17:29:36 INFO - PID 4779 | [#11] stanford-crypto-ccm Cycles:10 Average:129.10 Median:126.00 stddev:12.25 (9.7%) stddev-sans-first:12.64 17:29:36 INFO - PID 4779 | Values: 121.0 128.0 122.0 122.0 134.0 124.0 134.0 121.0 124.0 161.0 17:29:36 INFO - PID 4779 | 17:29:36 INFO - PID 4779 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:176.70 Median:176.00 stddev:8.08 (4.6%) stddev-sans-first:8.57 17:29:36 INFO - PID 4779 | Values: 176.0 187.0 169.0 178.0 175.0 173.0 169.0 176.0 194.0 170.0 17:29:36 INFO - PID 4779 | 17:29:36 INFO - PID 4779 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.40 Median:66.50 stddev:2.91 (4.4%) stddev-sans-first:2.57 17:29:36 INFO - PID 4779 | Values: 71.0 71.0 66.0 68.0 66.0 64.0 63.0 65.0 63.0 67.0 17:29:36 INFO - PID 4779 | -------- Summary: end -------- 17:29:36 INFO - PID 4779 | 17:29:36 INFO - PID 4779 | 17:29:36 INFO - PID 4779 | ###!!! [Parent][MessageChannel] Error: (msgtype=0x150082,name=PBrowser::Msg_Destroy) Closed channel: cannot send/recv 17:29:36 INFO - PID 4779 | 17:29:36 INFO - PID 4779 | 17:29:36 INFO - PID 4779 | ###!!! [Child][MessageChannel] Error: (msgtype=0x150001,name=PBrowser::Msg_AsyncMessage) Closed channel: cannot send/recv 17:29:36 INFO - PID 4779 | 17:29:36 INFO - PID 4779 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 17:29:36 INFO - TEST-INFO | 4779: exit 0 17:29:36 INFO - TEST-OK | kraken | took 61773ms 17:29:36 INFO - SUITE-END | took 1172s 17:29:37 INFO - Completed test suite (00:19:33) 17:29:37 INFO - javascript benchmark 17:29:37 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8686.300635205273, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3295.851686674098, 3315.552400425942, 3316.121227874612, 3322.368749937512, 3329.017939707786, 6234.2518897732225, 6307.05394190871, 6336.904946425807, 6368.681565921708, 6370.9362906370925, 6143.754249830013, 6262.53031022674, 6265.592736472137, 6282.874342513147, 6297.244330680325, 5567.972160139194, 5575.442455754433, 5579.163125531182, 5747.0517364635, 5829.533637309027, 5820.708964551776, 5835.708214589275, 5859.355470898219, 5865.912011319812, 5872.882542349151, 5655.4910058094965, 5740.7703691852275, 5768.596198266123, 5914.172015917775, 5918.881622367571, 5720.141978703186, 5729.6562206267345, 5772.365039845614, 5818.1272809078555, 5833.912491312633, 5716.914246286309, 5771.913421298642, 5782.045962416196, 5812.186293918855, 5839.0, 6101.694915254219, 6133.172021777093, 6223.471004964584, 6283.968580157116, 6357.554971151975, 6553.213614366259, 6557.311482294338, 6563.3764792344655, 6593.835154121138, 6639.170103737033, 6825.453963682893, 6835.111435513401, 6837.692303846289, 6848.794536163923, 6860.965695171492, 6231.158793562901, 6257.405546473124, 6285.685715714242, 6291.182146320949, 6368.395002474711, 6758.594484330857, 6890.758823441155, 6946.2011868634745, 6962.199347075151, 7017.89473157903, 6480.870382592322, 6544.312847150981, 6586.571872828251, 6629.86740265192, 6646.069550262967, 6741.528093033538, 6754.290799465985, 6787.898181527281, 6802.251752307242, 6806.863862722717, 6634.336566343327, 6652.368025037614, 6679.231888332807, 6700.2294736105, 6764.222114456802, 6593.703283352261, 6686.765963191265, 6699.129113215251, 6794.286599907037, 6801.659917004229, 5259.737013149327, 5259.947400526023, 5305.4164041955355, 5315.973420132875, 5319.840404787863, 4670.579647831712, 4722.362481065081, 4733.5503127202865, 4738.1708201064675, 4746.928796068062, 5909.5863289569315, 5941.792037278675, 5948.821535353946, 6038.5169186465855, 6041.426064523863, 6588.901166482507, 6760.492963027793, 6760.898586521206, 6856.348646878539, 6862.039314495974, 5820.534357251409, 5877.52979761618, 5936.7031648417405, 5944.554158438048, 5949.702514874239, 5825.097109947965, 5874.853128671732, 5876.9118463223085, 5878.500327472213, 5881.5882888198275, 6540.509461790385, 6548.541602087904, 6559.704813283413, 6568.6058836469965, 6661.666916654148, 6661.900071498834, 6734.696938637675, 6757.594544327356, 6856.725730970706, 6861.828454288683, 6580.605163690194, 6584.76953306632, 6594.109795177781, 6604.735810567524, 6624.205095388585, 6617.8014659560295, 6622.63575503352, 6730.966345168243, 6745.325467453412, 6769.390754831892, 6753.493487988322, 6753.696083676345, 6848.3494068063455, 6854.246032936273, 6868.896966545406, 6351.2060992375955, 6444.355564443706, 6474.805755827333, 6542.378474044958, 6633.6019838809825, 6510.283868774526, 6565.244996825426, 6594.043863639649, 6597.043428702716, 6602.9009564855605, 6497.382748638872, 6509.674516274262, 6551.377619126175, 6570.5729127608465, 6601.801945941629, 6522.445592124723, 6574.3754343337305, 6590.0, 6635.568688035262, 6684.264730879502, 6342.143810585512, 6413.198350206224, 6421.903671445026, 6422.903656445251, 6437.806865794034], "value": 6159.853989969315, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [812.1594150054694, 841.8316336732653, 869.6347534035706, 874.1608056265984, 1122.927009744367, 30783.23041923953, 41545.58454415459, 42160.0, 42185.0, 42195.57804421955, 41677.16645667092, 41722.0, 41854.37218441726, 41992.16015679685, 42059.57940420595, 7386.556806591621, 7820.217978202177, 7980.082290536576, 7984.40116991228, 7990.360771138324, 7807.375409967189, 7940.841183176333, 7943.285104340608, 7945.443818932648, 7949.682012719484, 7911.683532658687, 7997.840043199132, 8003.399745019147, 8003.759887203393, 8015.078265999427, 7909.050913890313, 7980.4413691041655, 7985.441019128663, 7988.640511177011, 7995.999999999971, 7844.0, 7913.920860791376, 7916.010498687664, 7920.881186782203, 7924.841503169962, 37541.0, 37737.622623773685, 37866.24267514662, 37906.24187516262, 37945.81027094847, 3512.8594856205723, 3546.4503002034726, 3557.324108419418, 3565.6612621800887, 3566.0, 31171.68828311733, 31387.84306078455, 31403.68596314053, 31445.0, 31453.213669658213, 4982.153033984195, 5166.225066240057, 5251.737413129366, 5256.0, 5283.260343551906, 172739.0, 172748.0, 173416.0, 173621.0, 173678.13160934116, 47469.28796068062, 47608.76195619, 47664.046719066115, 47697.28454073192, 47735.0, 49034.75482622635, 51135.0, 51759.74120129375, 51836.22245666243, 52003.47996520062, 12810.359482025862, 15447.768283475756, 15506.689866202612, 15542.222888855513, 15544.455944041903, 10428.895711042944, 12419.130660853654, 12729.427175777113, 12759.29823859678, 12768.170068945488, 14318.713625727427, 14353.569392918229, 14387.856121438652, 14397.280135993367, 14440.711185776225, 5549.139883318092, 5561.249231353715, 5762.596618236767, 5814.796482123105, 5868.911966320423, 1008.2790804574618, 1009.0565321424344, 1012.5342342522375, 1016.2022812092472, 1017.2319898476603, 22855.657165142537, 22958.770412295664, 23017.079316827476, 23099.96050177746, 23469.295921122393, 1105.2263415609107, 1105.6130354375905, 1114.3648120571359, 1119.658504156224, 1120.1654767198327, 12088.395580220953, 12108.394580270951, 12375.504979800708, 12418.441170147365, 12479.688007799878, 221721.0, 221739.0, 223907.0, 224630.0, 224713.0, 50693.49306506961, 50928.0, 51383.486165138616, 51400.228996564336, 51574.22638660348, 1416.277698373816, 1439.7264519741216, 1440.625437386287, 1442.7980082788417, 1455.8398576156615, 22629.43426414353, 22811.88594057019, 22872.428189294737, 22974.540509190057, 23108.884455577616, 37083.25833483369, 37236.627633723314, 37241.81379093087, 37355.252894942496, 37388.439173411876, 189338.0, 189515.0, 189749.0, 190114.0, 190810.0, 33852.0, 35319.470207947415, 35742.4638630426, 36011.819940900124, 36050.63949360473, 44014.779926100164, 45186.548134518234, 45345.77327113343, 45370.31944520901, 45430.31854522251, 32794.67205327916, 32799.344013120084, 33047.0, 33112.50331244985, 33467.497987530696, 32423.351532969682, 32646.673533264362, 32862.34275314432, 33051.17372065622, 33567.32865342631, 36746.448803267434, 36861.44707829438, 36875.26249475049, 37788.05529861776, 38138.80930595329, 32471.512927305637, 32659.510107347935, 32791.0, 32804.0, 33384.0, 24018.15936442216, 24484.6327305087, 24846.751532484443, 24876.12933547317, 25008.0], "value": 17183.52431951142, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [558.312346948406, 566.2751677852348, 568.2584227583003, 568.3577557360182, 585.7891159182693, 21892.89053554732, 22558.88720556397, 22595.887020564896, 22743.658845117337, 22750.77249227507, 22125.00437480317, 24007.63988540173, 24033.279001629937, 24033.279001629937, 24114.75885241147, 7087.184973728023, 7236.927630723704, 7242.71029158833, 7248.927510724904, 7250.891236631454, 6772.08576842125, 7057.752978645748, 7087.291270872897, 7212.17060038097, 7279.4176465882865, 7080.221175670672, 7087.681054352541, 7089.71641134354, 7101.467389945749, 7101.751438699646, 7096.467764917626, 7096.7516136934955, 7099.538529995534, 7109.680064397115, 7111.288871112899, 6908.9309106909295, 6948.305169483062, 6951.7566885159285, 6953.548019378774, 6969.546979446371, 30222.395552089056, 30533.38933221323, 30922.845385772926, 31090.689093109006, 31208.0, 2057.5781964697203, 2079.8960051997337, 2097.1925808563815, 2097.3812725245934, 2098.8635738676935, 20328.475364347585, 20933.685994710093, 20937.790622093737, 20940.0, 20947.895260523754, 2473.9257822265363, 2492.887820048102, 2522.6846644169477, 2580.548404029287, 2588.974110258911, 136178.0, 139722.0, 140056.0, 140311.0, 140323.29838350744, 20017.0, 20992.950352482316, 21482.570348593254, 21756.347309580422, 22292.777072229394, 41679.374809377885, 41742.37386439206, 42213.57786422096, 42334.57665423368, 42421.0, 11274.210805243718, 11338.716532086599, 11411.144164187719, 11412.942935285271, 11476.88523114775, 8289.95855020721, 9341.766455838659, 9372.95313523428, 9383.343165978316, 9546.18857397115, 9376.531173441437, 11171.0, 11377.544898204145, 11437.885621143849, 11466.140039497071, 3916.962005068659, 3978.980105099456, 4062.7765472899273, 4120.134771697917, 4140.948043387123, 946.2477330522252, 965.6234068713208, 966.6085235479642, 971.8920430708997, 973.1679414100842, 19259.325923592605, 19529.21883124659, 19677.50806229827, 19825.0, 20160.69758953617, 1035.1201478743008, 1037.4501514197486, 1039.740064983754, 1039.870016247969, 1045.6653870761436, 10748.89251107479, 11194.10447164225, 12819.871801281868, 13718.93140534291, 13897.235652039033, 481387.0, 485515.0, 489746.0, 489835.0, 492423.0, 116655.41672291585, 116976.0, 117950.0, 119497.40251298861, 119930.0, 1201.4953719437858, 1233.962981110568, 1253.4234252243887, 1257.4844313831286, 1262.2868079535033, 20820.479488012315, 21062.995077338142, 21104.788952110895, 21419.357419277447, 21594.2442014535, 55366.0, 56981.43018569927, 56989.71505142448, 57229.42770572241, 59157.40842591519, 69446.0, 69819.0, 70481.29518704953, 70618.29381706323, 71413.64293178501, 14139.787903181666, 14599.051061680957, 14694.55916322512, 14729.263536822902, 14733.26333683333, 21790.782092178877, 22972.19597314086, 23003.539929201655, 23033.539329213658, 23308.417289567897, 18229.635407291513, 18274.543136421697, 18325.0, 18419.171137298585, 18514.537136571154, 16206.351745930031, 16612.667746644758, 16645.16774161263, 17027.148642567576, 17193.14034298305, 17955.730664039787, 18861.622767544846, 18955.431337059912, 19078.236870525023, 19098.236070557577, 14493.637659058186, 14586.270686465847, 14607.780883286547, 14635.560933172022, 14655.63360915943, 17141.657166856345, 18184.90907545454, 18201.81798182001, 18298.085095744893, 18314.175862085973], "value": 12840.807165294807, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1306.7778477658796, 1353.0934274036401, 1372.2932689664824, 1389.2770170465885, 1394.6513371657086, 14497.347619357128, 15106.39574417022, 15107.320170592322, 15113.471028514005, 15124.773128403069, 15222.847771522282, 15806.762898556532, 15812.920935395336, 15817.288222030007, 15819.52541423759, 2697.4739925714493, 2759.2412086676222, 2768.349437882096, 2771.459565384751, 2774.9445011099765, 2658.853763043032, 2695.0163190435446, 2730.167298973812, 2772.4455108978186, 2775.4726601945567, 2752.146834481317, 2781.5549512078073, 2781.805273630847, 2785.4429114177146, 2785.7492825645686, 2675.0904692404483, 2699.9730002700016, 2703.175531462903, 2704.5672692369126, 2710.7966902482394, 2652.270625577962, 2654.9601755973677, 2673.1178711025314, 2673.31830383251, 2675.772559332459, 14639.19484428356, 14858.851411485855, 14980.625484362978, 14997.100173989596, 15000.849991500054, 7445.516041457288, 7625.923740762577, 7717.189695082048, 7726.922730772677, 7729.729459468947, 5480.671159730429, 5619.269494965669, 5672.808710170869, 5737.885242295172, 5773.566982476289, 1933.2556965568217, 1937.9127939242767, 1938.8255057044792, 1941.2914286285545, 1987.6024795040905, 25578.36054098644, 25864.22407327783, 25928.0, 25977.350566235807, 25994.6100808488, 6667.43326817226, 6687.665616719145, 6758.560693554903, 6878.415334696607, 6906.447484201352, 17024.744628830576, 17030.91484542594, 17030.91484542594, 17068.829311706973, 17073.0, 7418.777436676908, 7708.344790692854, 7735.381169506426, 7749.7675069748, 7752.147263800977, 7085.574865508086, 7159.534630249017, 7235.63821808918, 7250.746223882139, 7299.087614048244, 5055.797768089235, 5243.8164664236565, 5244.344456942882, 5277.997180535763, 5282.471752824764, 1299.6490947444327, 1312.4028567001992, 1504.0975414751063, 1520.908745475275, 1522.7868098466224, 878.8769572259761, 899.4648184330313, 901.9233365164034, 901.9582382348398, 912.493566070837, 8326.583670816435, 8757.824843503095, 8767.342449316327, 8783.341249406192, 8856.734297971072, 963.2679163835536, 994.5226291380034, 999.7600575861741, 1018.2363227579316, 1038.4651904269308, 4943.728094954812, 5031.7987280509105, 5156.458571849902, 5164.070467315844, 5189.143791274398, 20466.795332046786, 20943.790562094186, 21078.367648970554, 21092.78907210908, 21409.250676226256, 20702.792972070387, 20804.895975520027, 20852.1659133636, 20879.895600522203, 20932.267370641955, 10353.741156470998, 10686.893131068746, 10730.35617862915, 10754.677359679223, 10767.138628909668, 8504.40469167177, 8588.22705956467, 8655.826883462421, 8703.91296087031, 8736.301095912184, 17690.64618707593, 18423.170957306684, 18537.35119270819, 18736.62526749485, 18919.527011824815, 18176.09119544044, 18599.81400185981, 18817.247310107443, 18906.338278160198, 19172.424827255203, 10903.56385744593, 11021.889781102087, 11030.779384412428, 11070.335779853234, 11106.666799996014, 13800.447982080605, 13840.515581954583, 13941.302934853014, 14027.649308766955, 14084.507042253472, 10898.618548350769, 10903.781924361627, 11011.339319640847, 11093.11255099574, 11217.214794964275, 11021.118310534977, 11132.554697811996, 11192.272502287326, 11258.324500529994, 11386.772264554827, 9594.42433453995, 9889.406635601887, 10221.131203847755, 10292.433916134685, 10335.069843714102, 7207.9639601801655, 7218.855622887408, 7238.203797582157, 7284.344409003215, 7339.6330183489545, 7660.808479787827, 7721.420893433127, 7776.183500732342, 7822.765317040498, 7850.4112191587], "value": 6663.045743363214, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [498.97709695124985, 501.1155310876304, 515.6721442286112, 521.7964993652475, 524.4336116993645, 6361.1412459318035, 6414.262359828621, 6417.58285711429, 6427.646479443629, 6450.870982580357, 6199.907001394971, 6202.689865506718, 6223.935832829737, 6282.811515654526, 6292.748290068404, 5141.254518094874, 5197.68813871168, 5201.0, 5208.947910520884, 5214.843554693346, 4902.039184326262, 4996.875078123039, 5008.574271186953, 5037.168867136918, 5052.823151189709, 4898.755062246892, 4931.432885218192, 4943.269855550565, 5011.749412529359, 5066.645334826564, 4922.360093187898, 4946.597528199905, 4957.454679985199, 5012.724300163508, 5040.899182016376, 4493.191225579394, 4498.783369890544, 4507.391502147234, 4521.864344069683, 4527.796249168795, 6184.505239580868, 6247.75008999644, 6285.937140628581, 6315.24217093947, 6321.367863213688, 5681.801136960186, 5709.77160913563, 5751.792123654038, 5762.942370576325, 5779.768809247625, 6519.152510173647, 6560.212774467047, 6610.471162307004, 6634.170728658918, 6680.799576012728, 4830.550834749596, 4900.950990490085, 4914.0908931847725, 4914.852554423373, 4919.606431485508, 6565.277819439858, 6735.393814556714, 6827.931720682829, 6969.965150174267, 7025.156981162293, 4962.181240095344, 4996.5003499650475, 5018.648694591417, 5039.193729003342, 5054.115529782273, 4976.576990955737, 5030.647854650139, 5050.267711181858, 5077.923831142536, 5081.898362032813, 5721.456461636138, 5730.770769169187, 5734.2258795062135, 5758.712064396764, 5773.134029895549, 6693.564918280296, 6699.263081061079, 6723.193216814014, 6730.02414649873, 6741.561798483083, 1750.1074452029557, 1755.0873545756135, 1755.877088603811, 1758.5691505581215, 1760.9735853962202, 1154.1978325064167, 1209.9153059285766, 1218.9329586872807, 1233.340163012784, 1235.2835355493971, 5970.76116955317, 6002.939970600238, 6014.180995068075, 6032.155498230251, 6039.456448919531, 6790.694418751168, 6849.2465828758795, 6887.519183375498, 6888.0, 6896.448284137257, 5919.141724449931, 5937.435943585352, 5945.375735547792, 5945.643261404243, 5973.940260597338, 3603.891883243507, 3654.7076233901225, 3655.250673611903, 3667.5965643779164, 3679.4848721179055, 6645.900311495331, 6663.967894607942, 6681.599104053772, 6703.061571380011, 6710.396064354231, 7016.158061032606, 7046.788596342118, 7063.92936070643, 7106.324899134574, 7113.288671132846, 6195.760952366723, 6539.803805885831, 6605.834854128685, 6668.966655166693, 6672.099266599044, 6827.829304267433, 6850.0, 6853.629274145092, 6877.209120951155, 6889.724411023503, 6697.7655782047395, 6861.965690171517, 6875.5530890491955, 6896.931030689629, 6909.93090069113, 6663.733450662113, 6680.899786503109, 6689.297623749436, 6757.729690812509, 6758.391744742997, 5061.544460998381, 5061.544460998528, 5064.670796398223, 5082.974585127051, 5105.387353517602, 6735.730570777114, 6785.1518560179975, 6826.863462730618, 6929.0, 6929.0, 6654.537821006259, 6670.166229221348, 6693.698783554654, 6704.0, 6707.1616047994, 6616.172978377703, 6686.83282917931, 6695.363940425652, 6695.430888374348, 6710.429613482908, 6480.837979050561, 6572.047053177358, 6611.0, 6630.46956243509, 6633.1376920999965, 6702.68757278738, 6730.226024007302, 6737.73049078051, 6742.393184613408, 6782.796516104525, 6448.613083215022, 6496.285408604956, 6539.215294164731, 6539.247986481425, 6541.934580654323], "value": 5136.099541847247, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [832.3507664022061, 846.6613354658132, 850.7490290364344, 880.3309484791556, 898.2858627391223, 16573.25420356084, 16850.157492125392, 18677.346292879753, 19189.712154317698, 20622.0, 19572.510687232825, 19844.900775496102, 20130.0, 20235.089420976055, 20592.485187870312, 924.4684306523742, 935.5041827831226, 942.1097063275173, 942.1661829281102, 946.6402766948585, 952.4570994533119, 960.4429430930079, 961.2838435365662, 961.9134277914986, 962.874826272587, 893.0266010049045, 976.7899901521172, 991.8462638291077, 992.9056739609725, 993.7913038261973, 973.1825266775907, 978.7797745507276, 979.7648564344577, 981.906718861707, 982.1406269514175, 714.1715609892551, 717.8636059148745, 720.4092644031896, 720.6973071310063, 720.7225218290944, 18280.90859545694, 18694.252229910788, 18798.3420580279, 18963.905180474147, 19415.126319315525, 20689.68965465504, 20922.476938076517, 21159.576808463746, 21336.573268534543, 21338.466538336666, 16161.91919040409, 16317.51047468566, 16328.591785205346, 16349.427770027993, 16966.0, 3774.5093137892172, 3803.6386543278345, 3823.330917089498, 3824.7896365699876, 3826.6364695353896, 116293.0, 120363.39818300852, 121354.0, 123092.38453807763, 128412.71587284101, 16716.0, 16906.661866762595, 17408.825911740973, 17495.212715427835, 17511.474655760347, 30222.697773021988, 30439.239019024702, 30485.847570762005, 30546.38907221843, 30686.0, 7200.279972002758, 7568.508046976929, 8465.957670211732, 8931.821363572823, 9231.353805233703, 4272.850450234227, 4388.539203383662, 4397.538258482878, 4399.780010999437, 4402.3176407656865, 5150.639455238097, 5219.660722053054, 5276.1030624793875, 5282.049231138432, 5293.3647962244, 1008.566316483919, 1008.7326858382535, 1028.5885645741614, 1032.7986042721748, 1038.096855735515, 694.885343918258, 711.3917600451642, 714.424887965193, 717.0785540580433, 721.1598487761804, 11154.219204655596, 11718.30535890811, 12433.813492797615, 13085.345732713326, 13271.93364033193, 703.6622421237835, 704.2253521126822, 709.3722055980494, 718.0019772516207, 721.6680327049615, 5611.0, 5645.069021266012, 5661.886762264731, 5745.1669507921115, 5768.451997060272, 107755.46122269338, 107937.0, 108255.0, 110149.0, 111016.0, 94745.0, 95611.0, 97880.0, 98614.0, 101726.0, 82085.58957205176, 86613.5669321662, 86633.0, 87106.56446717726, 92909.0, 12308.569200077955, 12770.169938953939, 12788.424520896768, 12823.268858703956, 13089.738205236032, 38815.80592097021, 40478.190436190525, 40654.39018414785, 40776.3883541753, 43024.92437689083, 69919.30080699331, 73309.0, 75653.62173189099, 75661.24338756542, 79645.0, 18659.16033778456, 18911.148998294833, 19337.4198774037, 19820.20719171217, 19946.102425391182, 18701.71947420817, 19281.807181928, 19494.0, 21425.678614821103, 21587.2444464443, 20111.19555217775, 20494.180232791117, 20655.586888262453, 20846.270380536607, 20909.89545052265, 20435.795642043984, 20472.385828425173, 20806.0, 20977.0, 21341.0, 20197.59604807925, 20442.38672839817, 20834.583308333444, 21019.369418917457, 21139.894300528398, 14003.43986240539, 14010.369533371226, 14532.782008270096, 14671.339789709275, 14724.779128313297, 14387.0, 14643.26783660834, 14714.337854796773, 14756.335964881391, 14850.257487125384], "value": 9234.248383993132, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1518.6, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 113.0, 101.0, 108.0, 109.0, 108.0, 114.0, 98.0, 108.0, 109.0], "value": 106.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 117.0, 159.0, 166.0, 124.0, 151.0, 148.0, 116.0, 173.0, 120.0], "value": 142.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 167.0, 161.0, 162.0, 161.0, 171.0, 163.0, 165.0, 166.0, 165.0], "value": 164.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 74.0, 78.0, 169.0, 75.0, 77.0, 74.0, 84.0, 98.0, 77.0], "value": 88.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 85.0, 152.0, 79.0, 79.0, 79.0, 133.0, 80.0, 88.0, 136.0], "value": 99.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 114.0, 114.0, 114.0, 118.0, 114.0, 114.0, 120.0, 114.0], "value": 115.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 152.0, 147.0, 154.0, 149.0, 148.0, 148.0, 156.0, 148.0, 149.0], "value": 150.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 94.0, 99.0, 95.0, 96.0, 95.0, 94.0, 93.0], "value": 94.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 51.0, 52.0, 51.0, 51.0, 52.0, 51.0, 51.0, 51.0, 52.0], "value": 51.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 55.0, 55.0, 55.0, 54.0, 61.0, 55.0, 56.0, 54.0, 63.0], "value": 56.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 78.0, 79.0, 77.0, 77.0, 76.0, 76.0, 77.0, 74.0, 84.0], "value": 77.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [121.0, 128.0, 122.0, 122.0, 134.0, 124.0, 134.0, 121.0, 124.0, 161.0], "value": 129.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [176.0, 187.0, 169.0, 178.0, 175.0, 173.0, 169.0, 176.0, 194.0, 170.0], "value": 176.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 71.0, 66.0, 68.0, 66.0, 64.0, 63.0, 65.0, 63.0, 67.0], "value": 66.4, "unit": "score"}]}]} 17:29:37 INFO - Return code: 0 17:29:37 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 17:29:37 INFO - # TBPL SUCCESS # 17:29:37 INFO - Running post-action listener: _package_coverage_data 17:29:37 INFO - Running post-action listener: _resource_record_post_action 17:29:37 INFO - [mozharness: 2017-08-26 00:29:37.437545Z] Finished run-tests step (success) 17:29:37 INFO - Running post-run listener: _resource_record_post_run 17:29:37 INFO - Total resource usage - Wall time: 1191s; CPU: 13.0%; Read bytes: 53248; Write bytes: 437506048; Read time: 384; Write time: 275440 17:29:37 INFO - TinderboxPrint: CPU usage
13.1% 17:29:37 INFO - TinderboxPrint: I/O read bytes / time
53,248 / 384 17:29:37 INFO - TinderboxPrint: I/O write bytes / time
437,506,048 / 275,440 17:29:37 INFO - TinderboxPrint: CPU idle
8,248.5 (86.9%) 17:29:37 INFO - TinderboxPrint: CPU user
1,197.6 (12.6%) 17:29:37 INFO - TinderboxPrint: Swap in / out
0 / 0 17:29:37 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 4096; Write bytes: 114196480; Read time: 60; Write time: 82784 17:29:37 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 17:29:37 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 45056; Write bytes: 322797568; Read time: 316; Write time: 192652 17:29:38 INFO - Running post-run listener: _upload_blobber_files 17:29:38 INFO - Blob upload gear active. 17:29:38 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:29:38 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 17:29:38 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'] 17:29:38 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 17:29:38 INFO - (blobuploader) - INFO - Open directory for files ... 17:29:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 17:29:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:29:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:29:39 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 17:29:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:29:39 INFO - (blobuploader) - INFO - Done attempting. 17:29:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 17:29:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:29:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:29:47 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 17:29:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:29:47 INFO - (blobuploader) - INFO - Done attempting. 17:29:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 17:29:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:29:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:29:48 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 17:29:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:29:48 INFO - (blobuploader) - INFO - Done attempting. 17:29:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 17:29:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:29:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:29:49 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 17:29:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:29:49 INFO - (blobuploader) - INFO - Done attempting. 17:29:49 INFO - (blobuploader) - INFO - Iteration through files over. 17:29:49 INFO - Return code: 0 17:29:49 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:29:49 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:29:49 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a0f160308115a852d9c8893c67a9ffd07d264aa1b82519e71ea096c8c53ad38e8e23e6fd9d6e492734b4ac3e8eaffcb03a7c8cd8fd6e047b33a5467c6eb1c517", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/19514a8eb2a0f8a40a3771ec73901934bba00963dc247eb0f2b00b4543b4bb3777a7baa4477d209db1e5fd537139d4101c382ee1591e4b11696a6607feaf2d41", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f9889da04c6de600bc9ba45d48c9b738f431d901934cb42bd781ad841158a6b46134130741328c92354cc21ae6b8b55562f5598fe1f331d8b99a487a2c6f8ac5", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3592a7439a4af2d29eb3f97f334cb9bf369dc3b0a79fce34b6de4bdf2e610a38e14412f07798b1a890a7a7be1e5858367dd1d30289a44e15cc7bf76ea42a12a5"} 17:29:49 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:29:49 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:29:49 INFO - Contents: 17:29:49 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a0f160308115a852d9c8893c67a9ffd07d264aa1b82519e71ea096c8c53ad38e8e23e6fd9d6e492734b4ac3e8eaffcb03a7c8cd8fd6e047b33a5467c6eb1c517", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/19514a8eb2a0f8a40a3771ec73901934bba00963dc247eb0f2b00b4543b4bb3777a7baa4477d209db1e5fd537139d4101c382ee1591e4b11696a6607feaf2d41", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f9889da04c6de600bc9ba45d48c9b738f431d901934cb42bd781ad841158a6b46134130741328c92354cc21ae6b8b55562f5598fe1f331d8b99a487a2c6f8ac5", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3592a7439a4af2d29eb3f97f334cb9bf369dc3b0a79fce34b6de4bdf2e610a38e14412f07798b1a890a7a7be1e5858367dd1d30289a44e15cc7bf76ea42a12a5"} 17:29:49 INFO - Running post-run listener: copy_logs_to_upload_dir 17:29:49 INFO - Copying logs to upload dir... 17:29:49 INFO - mkdir: /builds/slave/test/build/upload/logs 17:29:49 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1252.893933 ========= master_lag: 0.36 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 53 secs) (at 2017-08-25 17:29:49.816132) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-25 17:29:49.820848) ========= 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=2c5dd2b0a310ecf02fd87158000001b4-1503706134.484861-159888122 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a0f160308115a852d9c8893c67a9ffd07d264aa1b82519e71ea096c8c53ad38e8e23e6fd9d6e492734b4ac3e8eaffcb03a7c8cd8fd6e047b33a5467c6eb1c517", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/19514a8eb2a0f8a40a3771ec73901934bba00963dc247eb0f2b00b4543b4bb3777a7baa4477d209db1e5fd537139d4101c382ee1591e4b11696a6607feaf2d41", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f9889da04c6de600bc9ba45d48c9b738f431d901934cb42bd781ad841158a6b46134130741328c92354cc21ae6b8b55562f5598fe1f331d8b99a487a2c6f8ac5", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3592a7439a4af2d29eb3f97f334cb9bf369dc3b0a79fce34b6de4bdf2e610a38e14412f07798b1a890a7a7be1e5858367dd1d30289a44e15cc7bf76ea42a12a5"} build_url:https://queue.taskcluster.net/v1/task/CvxZbX7nQM278U8qCtb6OQ/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011269 build_url: 'https://queue.taskcluster.net/v1/task/CvxZbX7nQM278U8qCtb6OQ/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a0f160308115a852d9c8893c67a9ffd07d264aa1b82519e71ea096c8c53ad38e8e23e6fd9d6e492734b4ac3e8eaffcb03a7c8cd8fd6e047b33a5467c6eb1c517", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/19514a8eb2a0f8a40a3771ec73901934bba00963dc247eb0f2b00b4543b4bb3777a7baa4477d209db1e5fd537139d4101c382ee1591e4b11696a6607feaf2d41", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f9889da04c6de600bc9ba45d48c9b738f431d901934cb42bd781ad841158a6b46134130741328c92354cc21ae6b8b55562f5598fe1f331d8b99a487a2c6f8ac5", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3592a7439a4af2d29eb3f97f334cb9bf369dc3b0a79fce34b6de4bdf2e610a38e14412f07798b1a890a7a7be1e5858367dd1d30289a44e15cc7bf76ea42a12a5"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-25 17:29:49.849459) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-25 17:29:49.849779) ========= 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=2c5dd2b0a310ecf02fd87158000001b4-1503706134.484861-159888122 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004684 ========= master_lag: 0.25 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-25 17:29:50.106373) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-25 17:29:50.106727) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-25 17:29:50.107061) ========= ========= Total master_lag: 0.71 =========