builder: mozilla-central_ubuntu64_hw_qr_test-dromaeojs-e10s slave: talos-linux64-ix-068 starttime: 1503706436.28 results: success (0) revision: 86a3c73984a23b1d524eadbd4a62b9b3fa27e46a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-08-25 17:13:56.282810) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-08-25 17:13:56.283619) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-25 17:13:56.284163) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-25 17:13:56.302345) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-25 17:13:56.302711) ========= 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=97facb8ba2ac28944cc45e6b000001d4-1503706434.912044-1353726718 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-08-25 17:13:56-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 70.0M=0s 2017-08-25 17:13:56 (70.0 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.166259 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-25 17:13:56.512456) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-25 17:13:56.512844) ========= 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=97facb8ba2ac28944cc45e6b000001d4-1503706434.912044-1353726718 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019479 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-25 17:13:56.562899) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2017-08-25 17:13:56.563267) ========= 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=97facb8ba2ac28944cc45e6b000001d4-1503706434.912044-1353726718 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-08-25 17:13:56,633 truncating revision to first 12 chars 2017-08-25 17:13:56,633 Setting DEBUG logging. 2017-08-25 17:13:56,633 attempt 1/10 2017-08-25 17:13:56,633 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:13:59,503 unpacking tar archive at: mozilla-central-86a3c73984a2/testing/mozharness/ program finished with exit code 0 elapsedTime=3.254373 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2017-08-25 17:13:59.834971) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-25 17:13:59.835368) ========= script_repo_revision: 86a3c73984a23b1d524eadbd4a62b9b3fa27e46a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-25 17:13:59.835816) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-25 17:13:59.836090) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-25 17:13:59.848495) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2017-08-25 17:13:59.848937) ========= /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=97facb8ba2ac28944cc45e6b000001d4-1503706434.912044-1353726718 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 17:14:00 INFO - MultiFileLogger online at 20170825 17:14:00 in /builds/slave/test 17:14:00 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:14:00 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 17:14:00 INFO - {'append_to_log': False, 17:14:00 INFO - 'base_work_dir': '/builds/slave/test', 17:14:00 INFO - 'blob_upload_branch': 'Firefox', 17:14:00 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 17:14:00 INFO - 'branch': 'Firefox', 17:14:00 INFO - 'buildbot_json_path': 'buildprops.json', 17:14:00 INFO - 'code_coverage': False, 17:14:00 INFO - 'config_files': ('talos/linux_config.py',), 17:14:00 INFO - 'default_actions': ('clobber', 17:14:00 INFO - 'read-buildbot-config', 17:14:00 INFO - 'download-and-extract', 17:14:00 INFO - 'populate-webroot', 17:14:00 INFO - 'create-virtualenv', 17:14:00 INFO - 'install', 17:14:00 INFO - 'setup-mitmproxy', 17:14:00 INFO - 'run-tests'), 17:14:00 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:14:00 INFO - 'disable_ccov_upload': False, 17:14:00 INFO - 'download_minidump_stackwalk': True, 17:14:00 INFO - 'download_symbols': 'ondemand', 17:14:00 INFO - 'enable_stylo': False, 17:14:00 INFO - 'enable_webrender': False, 17:14:00 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 17:14:00 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:14:00 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:14:00 INFO - 'gecko_profile': False, 17:14:00 INFO - 'gecko_profile_interval': 0, 17:14:00 INFO - 'installer_path': 'installer.exe', 17:14:00 INFO - 'log_level': 'info', 17:14:00 INFO - 'log_name': 'talos', 17:14:00 INFO - 'log_to_console': True, 17:14:00 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 17:14:00 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 17:14:00 INFO - 'opt_config_files': (), 17:14:00 INFO - 'pip_index': False, 17:14:00 INFO - 'suite': 'dromaeojs-e10s', 17:14:00 INFO - 'system_bits': '32', 17:14:00 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:14:00 INFO - 'title': 'talos-linux64-ix-068', 17:14:00 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:14:00 INFO - 'use_talos_json': True, 17:14:00 INFO - 'verify': 'False', 17:14:00 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 17:14:00 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:14:00 INFO - 'work_dir': 'build'} 17:14:00 INFO - [mozharness: 2017-08-26 00:14:00.248323Z] Running clobber step. 17:14:00 INFO - Running pre-action listener: _resource_record_pre_action 17:14:00 INFO - Running main action method: clobber 17:14:00 INFO - rmtree: /builds/slave/test/build 17:14:00 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 17:14:01 INFO - Running post-action listener: _resource_record_post_action 17:14:01 INFO - [mozharness: 2017-08-26 00:14:01.227960Z] Finished clobber step (success) 17:14:01 INFO - [mozharness: 2017-08-26 00:14:01.228118Z] Running read-buildbot-config step. 17:14:01 INFO - Running pre-action listener: _resource_record_pre_action 17:14:01 INFO - Running main action method: read_buildbot_config 17:14:01 INFO - Using buildbot properties: 17:14:01 INFO - { 17:14:01 INFO - "project": "", 17:14:01 INFO - "product": "firefox", 17:14:01 INFO - "who": "kwierso@gmail.com", 17:14:01 INFO - "installer_path": "public/build/target.tar.bz2", 17:14:01 INFO - "builddir": "mozilla-central_ubuntu64_hw_qr_test-dromaeojs-e10s", 17:14:01 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 17:14:01 INFO - "buildername": "Ubuntu HW 12.04 x64 qr mozilla-central talos dromaeojs-e10s", 17:14:01 INFO - "stage_platform": "linux64-qr", 17:14:01 INFO - "basedir": "/builds/slave/test", 17:14:01 INFO - "buildnumber": 16, 17:14:01 INFO - "platform": "ubuntu64_hw_qr", 17:14:01 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 17:14:01 INFO - "slavebuilddir": "test", 17:14:01 INFO - "taskId": "ZVGZwE1ET0e8NTiTlX9sBw", 17:14:01 INFO - "branch": "mozilla-central", 17:14:01 INFO - "script_repo_revision": "production", 17:14:01 INFO - "revision": "86a3c73984a23b1d524eadbd4a62b9b3fa27e46a", 17:14:01 INFO - "slavename": "talos-linux64-ix-068", 17:14:01 INFO - "repo_path": "mozilla-central" 17:14:01 INFO - } 17:14:01 INFO - Finding installer, test and symbols from parent task. 17:14:01 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ZVGZwE1ET0e8NTiTlX9sBw'}, attempt #1 17:14:01 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ZVGZwE1ET0e8NTiTlX9sBw'}, attempt #1 17:14:02 INFO - Task dependencies: CvxZbX7nQM278U8qCtb6OQ 17:14:02 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CvxZbX7nQM278U8qCtb6OQ'}, attempt #1 17:14:02 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/CvxZbX7nQM278U8qCtb6OQ/artifacts/public/build/target.tar.bz2 17:14:02 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/CvxZbX7nQM278U8qCtb6OQ/artifacts/public/build/target.test_packages.json 17:14:02 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/CvxZbX7nQM278U8qCtb6OQ/artifacts/public/build/target.crashreporter-symbols.zip 17:14:02 INFO - Running post-action listener: _resource_record_post_action 17:14:02 INFO - [mozharness: 2017-08-26 00:14:02.590441Z] Finished read-buildbot-config step (success) 17:14:02 INFO - [mozharness: 2017-08-26 00:14:02.590657Z] Running download-and-extract step. 17:14:02 INFO - Running pre-action listener: _resource_record_pre_action 17:14:02 INFO - Running main action method: download_and_extract 17:14:02 INFO - mkdir: /builds/slave/test/build/tests 17:14:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:14:02 INFO - trying https://queue.taskcluster.net/v1/task/CvxZbX7nQM278U8qCtb6OQ/artifacts/public/build/target.test_packages.json 17:14:02 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:14:02 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:14:03 INFO - Downloaded 989 bytes. 17:14:03 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 17:14:03 INFO - Using the following test package requirements: 17:14:03 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 17:14:03 INFO - u'common': [u'target.common.tests.zip'], 17:14:03 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 17:14:03 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 17:14:03 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 17:14:03 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 17:14:03 INFO - u'mozbase': [u'target.common.tests.zip'], 17:14:03 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 17:14:03 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 17:14:03 INFO - u'web-platform': [u'target.common.tests.zip', 17:14:03 INFO - u'target.web-platform.tests.tar.gz'], 17:14:03 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 17:14:03 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 17:14:03 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:14:03 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:14:03 INFO - Fetch https://queue.taskcluster.net/v1/task/CvxZbX7nQM278U8qCtb6OQ/artifacts/public/build/target.common.tests.zip into memory 17:14:04 INFO - Content-Length response header: 54979125 17:14:04 INFO - Bytes received: 54979125 17:14:15 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 17:14:15 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:14:15 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:14:15 INFO - Fetch https://queue.taskcluster.net/v1/task/CvxZbX7nQM278U8qCtb6OQ/artifacts/public/build/target.common.tests.zip into memory 17:14:16 INFO - Content-Length response header: 54979125 17:14:16 INFO - Bytes received: 54979125 17:14:28 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:14:28 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:14:28 INFO - Fetch https://queue.taskcluster.net/v1/task/CvxZbX7nQM278U8qCtb6OQ/artifacts/public/build/target.talos.tests.zip into memory 17:14:29 INFO - Content-Length response header: 14496611 17:14:29 INFO - Bytes received: 14496611 17:14:30 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:14:30 INFO - trying https://queue.taskcluster.net/v1/task/CvxZbX7nQM278U8qCtb6OQ/artifacts/public/build/target.tar.bz2 17:14:30 INFO - Downloading https://queue.taskcluster.net/v1/task/CvxZbX7nQM278U8qCtb6OQ/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 17:14:30 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:14:31 INFO - Downloaded 61118480 bytes. 17:14:31 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/CvxZbX7nQM278U8qCtb6OQ/artifacts/public/build/target.tar.bz2 17:14:31 INFO - mkdir: /builds/slave/test/properties 17:14:31 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 17:14:31 INFO - Writing to file /builds/slave/test/properties/build_url 17:14:31 INFO - Contents: 17:14:31 INFO - build_url:https://queue.taskcluster.net/v1/task/CvxZbX7nQM278U8qCtb6OQ/artifacts/public/build/target.tar.bz2 17:14:31 INFO - Running post-action listener: _resource_record_post_action 17:14:31 INFO - Running post-action listener: find_tests_for_verification 17:14:31 INFO - Running post-action listener: set_extra_try_arguments 17:14:31 INFO - [mozharness: 2017-08-26 00:14:31.358616Z] Finished download-and-extract step (success) 17:14:31 INFO - [mozharness: 2017-08-26 00:14:31.358713Z] Running populate-webroot step. 17:14:31 INFO - Running pre-action listener: _resource_record_pre_action 17:14:31 INFO - Running main action method: populate_webroot 17:14:31 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 17:14:31 INFO - 'chromez-stylo-e10s': {'talos_options': ['--stylo'], 17:14:31 INFO - 'tests': ['tresize', 'tcanvasmark']}, 17:14:31 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 17:14:31 INFO - 'dromaeojs-stylo-e10s': {'talos_options': ['--stylo'], 17:14:31 INFO - 'tests': ['dromaeo_css', 'kraken']}, 17:14:31 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 17:14:31 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:14:31 INFO - 'g1-stylo-e10s': {'pagesets_name': 'tp5n.zip', 17:14:31 INFO - 'talos_options': ['--stylo'], 17:14:31 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:14:31 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 17:14:31 INFO - 'tests': ['damp', 'tps']}, 17:14:31 INFO - 'g2-stylo-e10s': {'pagesets_name': 'tp5n.zip', 17:14:31 INFO - 'talos_options': ['--stylo'], 17:14:31 INFO - 'tests': ['damp', 'tps']}, 17:14:31 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:14:31 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 17:14:31 INFO - 'g4-stylo-e10s': {'talos_options': ['--stylo'], 17:14:31 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 17:14:31 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 17:14:31 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 17:14:31 INFO - 'g5-stylo-e10s': {'pagesets_name': 'tp5n.zip', 17:14:31 INFO - 'talos_options': ['--stylo'], 17:14:31 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 17:14:31 INFO - 'other-e10s': {'tests': ['a11yr', 17:14:31 INFO - 'ts_paint', 17:14:31 INFO - 'tpaint', 17:14:31 INFO - 'sessionrestore', 17:14:31 INFO - 'sessionrestore_many_windows', 17:14:31 INFO - 'sessionrestore_no_auto_restore', 17:14:31 INFO - 'tabpaint']}, 17:14:31 INFO - 'other-stylo-e10s': {'talos_options': ['--stylo'], 17:14:31 INFO - 'tests': ['a11yr', 17:14:31 INFO - 'ts_paint', 17:14:31 INFO - 'tpaint', 17:14:31 INFO - 'sessionrestore', 17:14:31 INFO - 'sessionrestore_many_windows', 17:14:31 INFO - 'sessionrestore_no_auto_restore', 17:14:31 INFO - 'tabpaint']}, 17:14:31 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 17:14:31 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 17:14:31 INFO - 'perf-reftest-singletons-stylo-e10s': {'talos_options': ['--stylo'], 17:14:31 INFO - 'tests': ['bloom_basic_singleton']}, 17:14:31 INFO - 'perf-reftest-stylo-e10s': {'talos_options': ['--stylo'], 17:14:31 INFO - 'tests': ['bloom_basic']}, 17:14:31 INFO - 'svgr-e10s': {'tests': ['tsvgx', 17:14:31 INFO - 'tsvgr_opacity', 17:14:31 INFO - 'tart', 17:14:31 INFO - 'tscrollx', 17:14:31 INFO - 'tsvg_static']}, 17:14:31 INFO - 'svgr-stylo-e10s': {'talos_options': ['--stylo'], 17:14:31 INFO - 'tests': ['tsvgx', 17:14:31 INFO - 'tsvgr_opacity', 17:14:31 INFO - 'tart', 17:14:31 INFO - 'tscrollx', 17:14:31 INFO - 'tsvg_static']}, 17:14:31 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 17:14:31 INFO - 'tp5o-stylo-e10s': {'pagesets_name': 'tp5n.zip', 17:14:31 INFO - 'talos_options': ['--stylo'], 17:14:31 INFO - 'tests': ['tp5o']}, 17:14:31 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 17:14:31 INFO - 'talos_options': ['--mitmproxy', 17:14:31 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 17:14:31 INFO - '--firstNonBlankPaint'], 17:14:31 INFO - 'tests': ['tp6_google', 17:14:31 INFO - 'tp6_youtube', 17:14:31 INFO - 'tp6_amazon', 17:14:31 INFO - 'tp6_facebook']}, 17:14:31 INFO - 'tp6-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 17:14:31 INFO - 'talos_options': ['--stylo', 17:14:31 INFO - '--mitmproxy', 17:14:31 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 17:14:31 INFO - '--firstNonBlankPaint'], 17:14:31 INFO - 'tests': ['tp6_google', 17:14:31 INFO - 'tp6_youtube', 17:14:31 INFO - 'tp6_amazon', 17:14:31 INFO - 'tp6_facebook']}, 17:14:31 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 17:14:31 INFO - 'talos_options': ['--stylo', 17:14:31 INFO - '--stylo-threads=1', 17:14:31 INFO - '--mitmproxy', 17:14:31 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 17:14:31 INFO - '--firstNonBlankPaint'], 17:14:31 INFO - 'tests': ['tp6_google', 17:14:31 INFO - 'tp6_youtube', 17:14:31 INFO - 'tp6_amazon', 17:14:31 INFO - 'tp6_facebook']}, 17:14:31 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 17:14:31 INFO - 'talos_options': ['--xperf_path', 17:14:31 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:14:31 INFO - 'tests': ['tp5n']}, 17:14:31 INFO - 'xperf-stylo-e10s': {'pagesets_name': 'tp5n.zip', 17:14:31 INFO - 'talos_options': ['--stylo', 17:14:31 INFO - '--xperf_path', 17:14:31 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:14:31 INFO - 'tests': ['tp5n']}}} 17:14:31 INFO - Running post-action listener: _resource_record_post_action 17:14:31 INFO - [mozharness: 2017-08-26 00:14:31.373628Z] Finished populate-webroot step (success) 17:14:31 INFO - [mozharness: 2017-08-26 00:14:31.373717Z] Running create-virtualenv step. 17:14:31 INFO - Running pre-action listener: _resource_record_pre_action 17:14:31 INFO - Running main action method: create_virtualenv 17:14:31 INFO - Creating virtualenv /builds/slave/test/build/venv 17:14:31 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 17:14:31 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 17:14:31 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 17:14:32 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:14:32 INFO - New python executable in /builds/slave/test/build/venv/bin/python 17:14:33 INFO - Installing distribute.............................................................................................................................................................................................done. 17:14:33 INFO - Installing pip...............done. 17:14:33 INFO - Return code: 0 17:14:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 17:14:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 17:14:34 INFO - Reading from file tmpfile_stdout 17:14:34 INFO - Output received: 17:14:34 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:14:34 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 17:14: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:14:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:14: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:14:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:14: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xc1e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xde9a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf8cf90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf8a130>, '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 0xf8b030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf8b4c0>, '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': '97facb8ba2ac28944cc45e6b000001d4-1503706434.912044-1353726718', '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:14: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', 'pip>=1.5'] in /builds/slave/test/build 17:14: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 pip>=1.5 17:14:34 INFO - Using env: {'DISPLAY': ':0', 17:14:34 INFO - 'HOME': '/home/cltbld', 17:14:34 INFO - 'LANG': 'en_US.UTF-8', 17:14:34 INFO - 'LANGUAGE': 'en_US:en', 17:14:34 INFO - 'LOGNAME': 'cltbld', 17:14:34 INFO - 'MAIL': '/var/mail/cltbld', 17:14:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:14:34 INFO - 'MOZ_NO_REMOTE': '1', 17:14:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:14:34 INFO - 'NO_EM_RESTART': '1', 17:14:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:14:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:14:34 INFO - 'PWD': '/builds/slave/test', 17:14:34 INFO - 'SHELL': '/bin/bash', 17:14:34 INFO - 'SHLVL': '1', 17:14:34 INFO - 'TERM': 'linux', 17:14:34 INFO - 'TMOUT': '86400', 17:14:34 INFO - 'USER': 'cltbld', 17:14:34 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1503706434.912044-1353726718', 17:14:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:14:34 INFO - '_': '/tools/buildbot/bin/python'} 17:14:35 INFO - Ignoring indexes: http://pypi.python.org/simple/ 17:14:35 INFO - Downloading/unpacking pip>=1.5 17:14:35 INFO - Running setup.py egg_info for package pip 17:14:35 INFO - warning: no files found matching 'pip/cacert.pem' 17:14:35 INFO - warning: no files found matching '*.html' under directory 'docs' 17:14:35 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 17:14:35 INFO - no previously-included directories found matching 'docs/_build/_sources' 17:14:35 INFO - Installing collected packages: pip 17:14:35 INFO - Found existing installation: pip 1.1 17:14:35 INFO - Uninstalling pip: 17:14:35 INFO - Successfully uninstalled pip 17:14:35 INFO - Running setup.py install for pip 17:14:35 INFO - warning: no files found matching 'pip/cacert.pem' 17:14:35 INFO - warning: no files found matching '*.html' under directory 'docs' 17:14:35 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 17:14:35 INFO - no previously-included directories found matching 'docs/_build/_sources' 17:14:35 INFO - Installing pip script to /builds/slave/test/build/venv/bin 17:14:35 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 17:14:35 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 17:14:35 INFO - Successfully installed pip 17:14:35 INFO - Cleaning up... 17:14:35 INFO - Return code: 0 17:14:35 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 17:14:35 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:14:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:14:35 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:14:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:14:35 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 0xc1e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xde9a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf8cf90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf8a130>, '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 0xf8b030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf8b4c0>, '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': '97facb8ba2ac28944cc45e6b000001d4-1503706434.912044-1353726718', '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:14:35 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:14:35 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:14:35 INFO - Using env: {'DISPLAY': ':0', 17:14:35 INFO - 'HOME': '/home/cltbld', 17:14:35 INFO - 'LANG': 'en_US.UTF-8', 17:14:35 INFO - 'LANGUAGE': 'en_US:en', 17:14:35 INFO - 'LOGNAME': 'cltbld', 17:14:35 INFO - 'MAIL': '/var/mail/cltbld', 17:14:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:14:35 INFO - 'MOZ_NO_REMOTE': '1', 17:14:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:14:35 INFO - 'NO_EM_RESTART': '1', 17:14:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:14:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:14:35 INFO - 'PWD': '/builds/slave/test', 17:14:35 INFO - 'SHELL': '/bin/bash', 17:14:35 INFO - 'SHLVL': '1', 17:14:35 INFO - 'TERM': 'linux', 17:14:35 INFO - 'TMOUT': '86400', 17:14:35 INFO - 'USER': 'cltbld', 17:14:35 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1503706434.912044-1353726718', 17:14:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:14:35 INFO - '_': '/tools/buildbot/bin/python'} 17:14:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:14:35 INFO - Downloading/unpacking psutil>=3.1.1 17:14: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:14: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:14: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:14: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:14:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 17:14:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:14:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:14:36 INFO - Installing collected packages: psutil 17:14:36 INFO - Running setup.py install for psutil 17:14:36 INFO - building 'psutil._psutil_linux' extension 17:14:36 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:14:38 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:14:38 INFO - building 'psutil._psutil_posix' extension 17:14:38 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:14:38 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:14:38 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:14:38 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:14:38 INFO - Successfully installed psutil 17:14:38 INFO - Cleaning up... 17:14:38 INFO - Return code: 0 17:14:38 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 17:14:38 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:14:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:14:38 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:14:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:14:38 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 0xc1e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xde9a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf8cf90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf8a130>, '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 0xf8b030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf8b4c0>, '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': '97facb8ba2ac28944cc45e6b000001d4-1503706434.912044-1353726718', '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:14:38 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:14:38 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:14:38 INFO - Using env: {'DISPLAY': ':0', 17:14:38 INFO - 'HOME': '/home/cltbld', 17:14:38 INFO - 'LANG': 'en_US.UTF-8', 17:14:38 INFO - 'LANGUAGE': 'en_US:en', 17:14:38 INFO - 'LOGNAME': 'cltbld', 17:14:38 INFO - 'MAIL': '/var/mail/cltbld', 17:14:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:14:38 INFO - 'MOZ_NO_REMOTE': '1', 17:14:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:14:38 INFO - 'NO_EM_RESTART': '1', 17:14:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:14:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:14:38 INFO - 'PWD': '/builds/slave/test', 17:14:38 INFO - 'SHELL': '/bin/bash', 17:14:38 INFO - 'SHLVL': '1', 17:14:38 INFO - 'TERM': 'linux', 17:14:38 INFO - 'TMOUT': '86400', 17:14:38 INFO - 'USER': 'cltbld', 17:14:38 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1503706434.912044-1353726718', 17:14:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:14:38 INFO - '_': '/tools/buildbot/bin/python'} 17:14:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:14:38 INFO - Downloading/unpacking mozsystemmonitor==0.3 17:14:38 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:14:38 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:14:38 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:14:38 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:14:39 INFO - Downloading mozsystemmonitor-0.3.tar.gz 17:14:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:14:39 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 17:14:39 INFO - Installing collected packages: mozsystemmonitor 17:14:39 INFO - Running setup.py install for mozsystemmonitor 17:14:39 INFO - Successfully installed mozsystemmonitor 17:14:39 INFO - Cleaning up... 17:14:40 INFO - Return code: 0 17:14:40 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 17:14: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:14:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:14: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:14:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:14:40 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 0xc1e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xde9a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf8cf90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf8a130>, '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 0xf8b030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf8b4c0>, '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': '97facb8ba2ac28944cc45e6b000001d4-1503706434.912044-1353726718', '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:14:40 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:14:40 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:14:40 INFO - Using env: {'DISPLAY': ':0', 17:14:40 INFO - 'HOME': '/home/cltbld', 17:14:40 INFO - 'LANG': 'en_US.UTF-8', 17:14:40 INFO - 'LANGUAGE': 'en_US:en', 17:14:40 INFO - 'LOGNAME': 'cltbld', 17:14:40 INFO - 'MAIL': '/var/mail/cltbld', 17:14:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:14:40 INFO - 'MOZ_NO_REMOTE': '1', 17:14:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:14:40 INFO - 'NO_EM_RESTART': '1', 17:14:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:14:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:14:40 INFO - 'PWD': '/builds/slave/test', 17:14:40 INFO - 'SHELL': '/bin/bash', 17:14:40 INFO - 'SHLVL': '1', 17:14:40 INFO - 'TERM': 'linux', 17:14:40 INFO - 'TMOUT': '86400', 17:14:40 INFO - 'USER': 'cltbld', 17:14:40 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1503706434.912044-1353726718', 17:14:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:14:40 INFO - '_': '/tools/buildbot/bin/python'} 17:14:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:14:40 INFO - Downloading/unpacking jsonschema==2.5.1 17:14:40 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:14:40 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:14:40 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:14:40 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:14:41 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:14:41 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 17:14: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:14: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:14: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:14: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:14:41 INFO - Downloading functools32-3.2.3-2.tar.gz 17:14:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 17:14:41 INFO - warning: no files found matching '*.txt' 17:14:41 INFO - no previously-included directories found matching 'build' 17:14:41 INFO - no previously-included directories found matching 'dist' 17:14:41 INFO - no previously-included directories found matching '.git*' 17:14:41 INFO - Installing collected packages: jsonschema, functools32 17:14:41 INFO - Running setup.py install for functools32 17:14:41 INFO - warning: no files found matching '*.txt' 17:14:41 INFO - no previously-included directories found matching 'build' 17:14:41 INFO - no previously-included directories found matching 'dist' 17:14:41 INFO - no previously-included directories found matching '.git*' 17:14:41 INFO - Successfully installed jsonschema functools32 17:14:41 INFO - Cleaning up... 17:14:41 INFO - Return code: 0 17:14:41 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 17:14:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:14:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:14:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:14:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:14:41 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0xc1e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xde9a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf8cf90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf8a130>, '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 0xf8b030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf8b4c0>, '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': '97facb8ba2ac28944cc45e6b000001d4-1503706434.912044-1353726718', '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:14:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 17:14:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 17:14:41 INFO - Using env: {'DISPLAY': ':0', 17:14:41 INFO - 'HOME': '/home/cltbld', 17:14:41 INFO - 'LANG': 'en_US.UTF-8', 17:14:41 INFO - 'LANGUAGE': 'en_US:en', 17:14:41 INFO - 'LOGNAME': 'cltbld', 17:14:41 INFO - 'MAIL': '/var/mail/cltbld', 17:14:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:14:41 INFO - 'MOZ_NO_REMOTE': '1', 17:14:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:14:41 INFO - 'NO_EM_RESTART': '1', 17:14:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:14:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:14:41 INFO - 'PWD': '/builds/slave/test', 17:14:41 INFO - 'SHELL': '/bin/bash', 17:14:41 INFO - 'SHLVL': '1', 17:14:41 INFO - 'TERM': 'linux', 17:14:41 INFO - 'TMOUT': '86400', 17:14:41 INFO - 'USER': 'cltbld', 17:14:41 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1503706434.912044-1353726718', 17:14:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:14:41 INFO - '_': '/tools/buildbot/bin/python'} 17:14:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:14:41 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 17:14:41 INFO - Cleaning up... 17:14:41 INFO - Return code: 0 17:14:41 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 17:14:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:14:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:14:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:14:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:14:41 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0xc1e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xde9a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf8cf90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf8a130>, '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 0xf8b030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf8b4c0>, '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': '97facb8ba2ac28944cc45e6b000001d4-1503706434.912044-1353726718', '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:14:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 17:14:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 17:14:41 INFO - Using env: {'DISPLAY': ':0', 17:14:41 INFO - 'HOME': '/home/cltbld', 17:14:41 INFO - 'LANG': 'en_US.UTF-8', 17:14:41 INFO - 'LANGUAGE': 'en_US:en', 17:14:41 INFO - 'LOGNAME': 'cltbld', 17:14:41 INFO - 'MAIL': '/var/mail/cltbld', 17:14:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:14:41 INFO - 'MOZ_NO_REMOTE': '1', 17:14:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:14:41 INFO - 'NO_EM_RESTART': '1', 17:14:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:14:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:14:41 INFO - 'PWD': '/builds/slave/test', 17:14:41 INFO - 'SHELL': '/bin/bash', 17:14:41 INFO - 'SHLVL': '1', 17:14:41 INFO - 'TERM': 'linux', 17:14:41 INFO - 'TMOUT': '86400', 17:14:41 INFO - 'USER': 'cltbld', 17:14:41 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1503706434.912044-1353726718', 17:14:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:14:41 INFO - '_': '/tools/buildbot/bin/python'} 17:14:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:14:42 INFO - Downloading/unpacking blobuploader==1.2.4 17:14:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:14:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:14:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:14:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:14:43 INFO - Downloading blobuploader-1.2.4.tar.gz 17:14:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:14:43 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:14: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:14: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:14: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:14: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:14:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 17:14:43 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:14: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:14: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:14: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:14: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:14:43 INFO - Downloading docopt-0.6.1.tar.gz 17:14:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 17:14:43 INFO - Installing collected packages: blobuploader, requests, docopt 17:14:43 INFO - Running setup.py install for blobuploader 17:14:43 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:14:43 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 17:14:43 INFO - Running setup.py install for requests 17:14:44 INFO - Running setup.py install for docopt 17:14:44 INFO - Successfully installed blobuploader requests docopt 17:14:44 INFO - Cleaning up... 17:14:44 INFO - Return code: 0 17:14:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:14:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:14:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:14:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:14:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:14:44 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 0xc1e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xde9a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf8cf90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf8a130>, '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 0xf8b030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf8b4c0>, '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': '97facb8ba2ac28944cc45e6b000001d4-1503706434.912044-1353726718', '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:14:44 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:14:44 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:14:44 INFO - Using env: {'DISPLAY': ':0', 17:14:44 INFO - 'HOME': '/home/cltbld', 17:14:44 INFO - 'LANG': 'en_US.UTF-8', 17:14:44 INFO - 'LANGUAGE': 'en_US:en', 17:14:44 INFO - 'LOGNAME': 'cltbld', 17:14:44 INFO - 'MAIL': '/var/mail/cltbld', 17:14:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:14:44 INFO - 'MOZ_NO_REMOTE': '1', 17:14:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:14:44 INFO - 'NO_EM_RESTART': '1', 17:14:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:14:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:14:44 INFO - 'PWD': '/builds/slave/test', 17:14:44 INFO - 'SHELL': '/bin/bash', 17:14:44 INFO - 'SHLVL': '1', 17:14:44 INFO - 'TERM': 'linux', 17:14:44 INFO - 'TMOUT': '86400', 17:14:44 INFO - 'USER': 'cltbld', 17:14:44 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1503706434.912044-1353726718', 17:14:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:14:44 INFO - '_': '/tools/buildbot/bin/python'} 17:14:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:14:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:14:44 INFO - Running setup.py (path:/tmp/pip-nbgz7k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:14:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:14:44 INFO - Running setup.py (path:/tmp/pip-yKL913-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:14:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:14:44 INFO - Running setup.py (path:/tmp/pip-1ZoR6e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:14:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:14:44 INFO - Running setup.py (path:/tmp/pip-4CKYkr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:14:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:14:44 INFO - Running setup.py (path:/tmp/pip-cY1Kye-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:14:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:14:45 INFO - Running setup.py (path:/tmp/pip-5nL1tU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:14:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:14:45 INFO - Running setup.py (path:/tmp/pip-R_hGGw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:14:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:14:45 INFO - Running setup.py (path:/tmp/pip-IEBZ8W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:14:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:14:45 INFO - Running setup.py (path:/tmp/pip-31GSBY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:14:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:14:45 INFO - Running setup.py (path:/tmp/pip-ouLvgy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:14:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:14:45 INFO - Running setup.py (path:/tmp/pip-dkusCo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:14:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:14:45 INFO - Running setup.py (path:/tmp/pip-586eGB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:14:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:14:45 INFO - Running setup.py (path:/tmp/pip-LvkgG_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:14:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:14:45 INFO - Running setup.py (path:/tmp/pip-PaXMWn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:14:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:14:45 INFO - Running setup.py (path:/tmp/pip-ub7K9u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:14:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:14:45 INFO - Running setup.py (path:/tmp/pip-WGWWwR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:14:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:14:45 INFO - Running setup.py (path:/tmp/pip-L1iW8L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:14:45 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:14:45 INFO - Running setup.py install for manifestparser 17:14:45 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 17:14:45 INFO - Running setup.py install for mozcrash 17:14:46 INFO - Running setup.py install for mozdebug 17:14:46 INFO - Running setup.py install for mozdevice 17:14:46 INFO - Installing dm script to /builds/slave/test/build/venv/bin 17:14:46 INFO - Running setup.py install for mozfile 17:14:46 INFO - Running setup.py install for mozhttpd 17:14:46 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 17:14:46 INFO - Running setup.py install for mozinfo 17:14:46 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 17:14:46 INFO - Running setup.py install for mozInstall 17:14:46 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 17:14:46 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 17:14:46 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 17:14:46 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 17:14:46 INFO - Running setup.py install for mozleak 17:14:46 INFO - Running setup.py install for mozlog 17:14:46 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 17:14:46 INFO - Running setup.py install for moznetwork 17:14:46 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 17:14:46 INFO - Running setup.py install for mozprocess 17:14:47 INFO - Running setup.py install for mozprofile 17:14:47 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 17:14:47 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 17:14:47 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 17:14:47 INFO - Running setup.py install for mozrunner 17:14:47 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 17:14:47 INFO - Running setup.py install for mozscreenshot 17:14:47 INFO - Running setup.py install for moztest 17:14:47 INFO - Running setup.py install for mozversion 17:14:47 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 17:14:47 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:14:47 INFO - Cleaning up... 17:14:47 INFO - Return code: 0 17:14:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:14:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:14:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:14:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:14:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:14:47 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 0xc1e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xde9a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf8cf90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf8a130>, '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 0xf8b030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf8b4c0>, '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': '97facb8ba2ac28944cc45e6b000001d4-1503706434.912044-1353726718', '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:14:47 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:14:47 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:14:47 INFO - Using env: {'DISPLAY': ':0', 17:14:47 INFO - 'HOME': '/home/cltbld', 17:14:47 INFO - 'LANG': 'en_US.UTF-8', 17:14:47 INFO - 'LANGUAGE': 'en_US:en', 17:14:47 INFO - 'LOGNAME': 'cltbld', 17:14:47 INFO - 'MAIL': '/var/mail/cltbld', 17:14:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:14:47 INFO - 'MOZ_NO_REMOTE': '1', 17:14:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:14:47 INFO - 'NO_EM_RESTART': '1', 17:14:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:14:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:14:47 INFO - 'PWD': '/builds/slave/test', 17:14:47 INFO - 'SHELL': '/bin/bash', 17:14:47 INFO - 'SHLVL': '1', 17:14:47 INFO - 'TERM': 'linux', 17:14:47 INFO - 'TMOUT': '86400', 17:14:47 INFO - 'USER': 'cltbld', 17:14:47 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1503706434.912044-1353726718', 17:14:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:14:47 INFO - '_': '/tools/buildbot/bin/python'} 17:14:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:14:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:14:47 INFO - Running setup.py (path:/tmp/pip-Up8swm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:14:47 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:14:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:14:47 INFO - Running setup.py (path:/tmp/pip-0SsaCz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:14:47 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:14:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:14:48 INFO - Running setup.py (path:/tmp/pip-sKsEHN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:14:48 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:14:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:14:48 INFO - Running setup.py (path:/tmp/pip-MNOnGi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:14:48 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:14:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:14:48 INFO - Running setup.py (path:/tmp/pip-7JNDYI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:14:48 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:14:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:14:48 INFO - Running setup.py (path:/tmp/pip-0Ebfjr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:14:48 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:14:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:14:48 INFO - Running setup.py (path:/tmp/pip-juHbBT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:14:48 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:14:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:14:48 INFO - Running setup.py (path:/tmp/pip-ixmTGQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:14:48 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:14:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:14:48 INFO - Running setup.py (path:/tmp/pip-Za4kxG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:14:48 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:14:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:14:48 INFO - Running setup.py (path:/tmp/pip-opiv4i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:14:48 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:14:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:14:48 INFO - Running setup.py (path:/tmp/pip-n1qiq7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:14:48 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:14:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:14:48 INFO - Running setup.py (path:/tmp/pip-oqgY4j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:14:48 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:14:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:14:48 INFO - Running setup.py (path:/tmp/pip-77BzqE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:14:48 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:14:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:14:48 INFO - Running setup.py (path:/tmp/pip-dpYpRj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:14:48 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:14:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:14:48 INFO - Running setup.py (path:/tmp/pip-YNaA_D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:14:48 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:14:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:14:48 INFO - Running setup.py (path:/tmp/pip-mj5YbR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:14:48 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:14:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:14:48 INFO - Running setup.py (path:/tmp/pip-xmIW4u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:14:49 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:14:49 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:14:49 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:14:49 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:14:49 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:14:49 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:14:49 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:14:49 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:14:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:14:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:14:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:14:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:14:50 INFO - Downloading blessings-1.6.tar.gz 17:14:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 17:14:50 INFO - Installing collected packages: blessings 17:14:50 INFO - Running setup.py install for blessings 17:14:50 INFO - Successfully installed blessings 17:14:50 INFO - Cleaning up... 17:14:50 INFO - Return code: 0 17:14:50 INFO - Done creating virtualenv /builds/slave/test/build/venv. 17:14:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:14:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:14:50 INFO - Reading from file tmpfile_stdout 17:14:50 INFO - Current package versions: 17:14:50 INFO - argparse == 1.2.1 17:14:50 INFO - blessings == 1.6 17:14:50 INFO - blobuploader == 1.2.4 17:14:50 INFO - docopt == 0.6.1 17:14:50 INFO - functools32 == 3.2.3-2 17:14:50 INFO - jsonschema == 2.5.1 17:14:50 INFO - manifestparser == 1.1 17:14:50 INFO - mozInstall == 1.13 17:14:50 INFO - mozcrash == 1.0 17:14:50 INFO - mozdebug == 0.1 17:14:50 INFO - mozdevice == 0.51 17:14:50 INFO - mozfile == 1.2 17:14:50 INFO - mozhttpd == 0.7 17:14:50 INFO - mozinfo == 0.10 17:14:50 INFO - mozleak == 0.1 17:14:50 INFO - mozlog == 3.5 17:14:50 INFO - moznetwork == 0.27 17:14:50 INFO - mozprocess == 0.25 17:14:50 INFO - mozprofile == 0.28 17:14:50 INFO - mozrunner == 6.13 17:14:50 INFO - mozscreenshot == 0.1 17:14:50 INFO - mozsystemmonitor == 0.3 17:14:50 INFO - moztest == 0.8 17:14:50 INFO - mozversion == 1.4 17:14:50 INFO - psutil == 3.1.1 17:14:50 INFO - requests == 1.2.3 17:14:50 INFO - wsgiref == 0.1.2 17:14:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:14:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:14:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:14:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:14:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:14:50 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 0xc1e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xde9a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf8cf90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf8a130>, '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 0xf8b030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf8b4c0>, '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': '97facb8ba2ac28944cc45e6b000001d4-1503706434.912044-1353726718', '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:14:50 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:14:50 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:14:50 INFO - Using env: {'DISPLAY': ':0', 17:14:50 INFO - 'HOME': '/home/cltbld', 17:14:50 INFO - 'LANG': 'en_US.UTF-8', 17:14:50 INFO - 'LANGUAGE': 'en_US:en', 17:14:50 INFO - 'LOGNAME': 'cltbld', 17:14:50 INFO - 'MAIL': '/var/mail/cltbld', 17:14:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:14:50 INFO - 'MOZ_NO_REMOTE': '1', 17:14:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:14:50 INFO - 'NO_EM_RESTART': '1', 17:14:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:14:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:14:50 INFO - 'PWD': '/builds/slave/test', 17:14:50 INFO - 'SHELL': '/bin/bash', 17:14:50 INFO - 'SHLVL': '1', 17:14:50 INFO - 'TERM': 'linux', 17:14:50 INFO - 'TMOUT': '86400', 17:14:50 INFO - 'USER': 'cltbld', 17:14:50 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1503706434.912044-1353726718', 17:14:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:14:50 INFO - '_': '/tools/buildbot/bin/python'} 17:14:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:14:50 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:14:50 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:14:50 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:14:50 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:14:50 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:14:50 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:14:50 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:14:50 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:14:50 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:14:50 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 17:14:50 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:14:50 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:14:50 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:14:50 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:14:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 17:14:52 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:14:52 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:14:52 INFO - Installing collected packages: simplejson 17:14:52 INFO - Running setup.py install for simplejson 17:14:52 INFO - building 'simplejson._speedups' extension 17:14:52 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:14:52 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:14:53 INFO - Successfully installed simplejson 17:14:53 INFO - Cleaning up... 17:14:53 INFO - Return code: 0 17:14:53 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 17:14:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:14:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:14:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:14:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:14:53 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xc1e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xde9a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf8cf90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf8a130>, '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 0xf8b030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf8b4c0>, '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': '97facb8ba2ac28944cc45e6b000001d4-1503706434.912044-1353726718', '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:14:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 17:14:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 17:14:53 INFO - Using env: {'DISPLAY': ':0', 17:14:53 INFO - 'HOME': '/home/cltbld', 17:14:53 INFO - 'LANG': 'en_US.UTF-8', 17:14:53 INFO - 'LANGUAGE': 'en_US:en', 17:14:53 INFO - 'LOGNAME': 'cltbld', 17:14:53 INFO - 'MAIL': '/var/mail/cltbld', 17:14:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:14:53 INFO - 'MOZ_NO_REMOTE': '1', 17:14:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:14:53 INFO - 'NO_EM_RESTART': '1', 17:14:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:14:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:14:53 INFO - 'PWD': '/builds/slave/test', 17:14:53 INFO - 'SHELL': '/bin/bash', 17:14:53 INFO - 'SHLVL': '1', 17:14:53 INFO - 'TERM': 'linux', 17:14:53 INFO - 'TMOUT': '86400', 17:14:53 INFO - 'USER': 'cltbld', 17:14:53 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1503706434.912044-1353726718', 17:14:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:14:53 INFO - '_': '/tools/buildbot/bin/python'} 17:14:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:14:53 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 17:14:53 INFO - Cleaning up... 17:14:53 INFO - Return code: 0 17:14:53 INFO - Running post-action listener: _resource_record_post_action 17:14:53 INFO - Running post-action listener: _start_resource_monitoring 17:14:53 INFO - Starting resource monitoring. 17:14:53 INFO - [mozharness: 2017-08-26 00:14:53.377234Z] Finished create-virtualenv step (success) 17:14:53 INFO - [mozharness: 2017-08-26 00:14:53.378328Z] Running install step. 17:14:53 INFO - Running pre-action listener: _resource_record_pre_action 17:14:53 INFO - Running main action method: install 17:14:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:14:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:14:53 INFO - Reading from file tmpfile_stdout 17:14:53 INFO - Detecting whether we're running mozinstall >=1.0... 17:14:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 17:14:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 17:14:53 INFO - Reading from file tmpfile_stdout 17:14:53 INFO - Output received: 17:14:53 INFO - Usage: mozinstall [options] installer 17:14:53 INFO - Options: 17:14:53 INFO - -h, --help show this help message and exit 17:14:53 INFO - -d DEST, --destination=DEST 17:14:53 INFO - Directory to install application into. [default: 17:14:53 INFO - "/builds/slave/test"] 17:14:53 INFO - --app=APP Application being installed. [default: firefox] 17:14:53 INFO - mkdir: /builds/slave/test/build/application 17:14:53 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:14:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 17:15:11 INFO - Reading from file tmpfile_stdout 17:15:11 INFO - Output received: 17:15:11 INFO - /builds/slave/test/build/application/firefox/firefox 17:15:11 INFO - Running post-action listener: _resource_record_post_action 17:15:11 INFO - [mozharness: 2017-08-26 00:15:11.545267Z] Finished install step (success) 17:15:11 INFO - [mozharness: 2017-08-26 00:15:11.545584Z] Running setup-mitmproxy step. 17:15:11 INFO - Running pre-action listener: _resource_record_pre_action 17:15:11 INFO - Running main action method: setup_mitmproxy 17:15:11 INFO - Skipping: mitmproxy is not required 17:15:11 INFO - Running post-action listener: _resource_record_post_action 17:15:11 INFO - [mozharness: 2017-08-26 00:15:11.546700Z] Finished setup-mitmproxy step (success) 17:15:11 INFO - [mozharness: 2017-08-26 00:15:11.546918Z] Running run-tests step. 17:15:11 INFO - Running pre-action listener: _resource_record_pre_action 17:15:11 INFO - Running pre-action listener: _set_gcov_prefix 17:15:11 INFO - Running main action method: run_tests 17:15:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 17:15:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 17:15:11 INFO - Python 2.7.3 17:15:11 INFO - Return code: 0 17:15:11 INFO - grabbing minidump binary from tooltool 17:15:11 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:15:11 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 0xf8a130>, '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 0xf8b030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf8b4c0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 17:15:11 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:15:11 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:15:11 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:15:11 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:15:11 INFO - Return code: 0 17:15:11 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 17:15:11 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 17:15:11 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 17:15:11 INFO - ENV: RUST_BACKTRACE is now full 17:15:11 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 17:15:11 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 17:15:11 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-068', '--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:15:11 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-068 --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:15:11 INFO - Using env: {'DISPLAY': ':0', 17:15:11 INFO - 'HOME': '/home/cltbld', 17:15:11 INFO - 'LANG': 'en_US.UTF-8', 17:15:11 INFO - 'LANGUAGE': 'en_US:en', 17:15:11 INFO - 'LOGNAME': 'cltbld', 17:15:11 INFO - 'MAIL': '/var/mail/cltbld', 17:15:11 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:15:11 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 17:15:11 INFO - 'MOZ_ACCELERATED': '1', 17:15:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:15:11 INFO - 'MOZ_NO_REMOTE': '1', 17:15:11 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:15:11 INFO - 'MOZ_WEBRENDER': '1', 17:15:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:15:11 INFO - 'NO_EM_RESTART': '1', 17:15:11 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:15:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:15:11 INFO - 'PWD': '/builds/slave/test', 17:15:11 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:15:11 INFO - 'RUST_BACKTRACE': 'full', 17:15:11 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 17:15:11 INFO - 'SHELL': '/bin/bash', 17:15:11 INFO - 'SHLVL': '1', 17:15:11 INFO - 'TERM': 'linux', 17:15:11 INFO - 'TMOUT': '86400', 17:15:11 INFO - 'USER': 'cltbld', 17:15:11 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1503706434.912044-1353726718', 17:15:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:15:11 INFO - '_': '/tools/buildbot/bin/python'} 17:15:11 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-068', '--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:15:11 INFO - mozversion application_buildid: 20170825232216 17:15:11 INFO - mozversion application_changeset: 86a3c73984a23b1d524eadbd4a62b9b3fa27e46a 17:15:11 INFO - mozversion application_display_name: Nightly 17:15:11 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:15:11 INFO - mozversion application_name: Firefox 17:15:11 INFO - mozversion application_remotingname: firefox 17:15:11 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 17:15:11 INFO - mozversion application_vendor: Mozilla 17:15:11 INFO - mozversion application_version: 57.0a1 17:15:12 INFO - mozversion platform_buildid: 20170825232216 17:15:12 INFO - mozversion platform_changeset: 86a3c73984a23b1d524eadbd4a62b9b3fa27e46a 17:15:12 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 17:15:12 INFO - mozversion platform_version: 57.0a1 17:15:12 INFO - using testdate: 1503706511 17:15:12 INFO - actual date: 1503706511 17:15:12 INFO - starting webserver on 'localhost:53912' 17:15:12 INFO - SUITE-START | Running 2 tests 17:15:12 INFO - TEST-START | dromaeo_css 17:15:12 INFO - Initialising browser for dromaeo_css test... 17:15:12 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:53912/getInfo.html -profile /tmp/tmpg0bNgD/profile 17:15:12 INFO - TEST-INFO | started process 3479 (/builds/slave/test/build/application/firefox/firefox http://localhost:53912/getInfo.html) 17:15:22 INFO - TEST-INFO | 3479: exit 0 17:15:22 INFO - Browser initialized. 17:15:22 INFO - Running cycle 1/1 for dromaeo_css test... 17:15:22 INFO - Using env: {'DISPLAY': ':0', 17:15:22 INFO - 'HOME': '/home/cltbld', 17:15:22 INFO - 'JSGC_DISABLE_POISONING': '1', 17:15:22 INFO - 'LANG': 'en_US.UTF-8', 17:15:22 INFO - 'LANGUAGE': 'en_US:en', 17:15:22 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 17:15:22 INFO - 'LOGNAME': 'cltbld', 17:15:22 INFO - 'MAIL': '/var/mail/cltbld', 17:15:22 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:15:22 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 17:15:22 INFO - 'MOZ_ACCELERATED': '1', 17:15:22 INFO - 'MOZ_CRASHREPORTER': '1', 17:15:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:15:22 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 17:15:22 INFO - 'MOZ_NO_REMOTE': '1', 17:15:22 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:15:22 INFO - 'MOZ_WEBRENDER': '1', 17:15:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:15:22 INFO - 'NO_EM_RESTART': '1', 17:15:22 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:15:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:15:22 INFO - 'PWD': '/builds/slave/test', 17:15:22 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:15:22 INFO - 'RUST_BACKTRACE': 'full', 17:15:22 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 17:15:22 INFO - 'SHELL': '/bin/bash', 17:15:22 INFO - 'SHLVL': '1', 17:15:22 INFO - 'TERM': 'linux', 17:15:22 INFO - 'TMOUT': '86400', 17:15:22 INFO - 'USER': 'cltbld', 17:15:22 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1503706434.912044-1353726718', 17:15:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:15:22 INFO - '_': '/tools/buildbot/bin/python'} 17:15:22 INFO - TEST-INFO | started process 3711 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpg0bNgD/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 17:15:23 INFO - PID 3711 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 17:15:23 INFO - PID 3711 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 17:15:23 INFO - PID 3711 | 17:15:23 INFO - PID 3711 | (/builds/slave/test/build/application/firefox/firefox:3772): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 17:15:23 INFO - PID 3711 | 17:15:24 INFO - PID 3711 | 17:15:24 INFO - PID 3711 | (/builds/slave/test/build/application/firefox/firefox:3831): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 17:15:24 INFO - PID 3711 | 17:15:24 INFO - PID 3711 | __metrics Screen width/height:1600/1200 17:15:24 INFO - PID 3711 | colorDepth:24 17:15:24 INFO - PID 3711 | Browser inner width/height: 1024/768 17:15:24 INFO - PID 3711 | __metrics 17:15:34 INFO - PID 3711 | RSS: Main: 202424320 17:15:34 INFO - PID 3711 | 17:15:37 INFO - PID 3711 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 17:18:34 INFO - PID 3711 | Cycle 1(1): loaded http://localhost:53912/tests/dromaeo/cssquery-dojo.html (next: http://localhost:53912/tests/dromaeo/cssquery-ext.html) 17:18:34 INFO - PID 3711 | RSS: Main: 211263488 17:18:34 INFO - PID 3711 | 17:21:36 INFO - PID 3711 | Cycle 1(1): loaded http://localhost:53912/tests/dromaeo/cssquery-ext.html (next: http://localhost:53912/tests/dromaeo/cssquery-jquery.html) 17:21:37 INFO - PID 3711 | RSS: Main: 207482880 17:21:37 INFO - PID 3711 | 17:24:39 INFO - PID 3711 | Cycle 1(1): loaded http://localhost:53912/tests/dromaeo/cssquery-jquery.html (next: http://localhost:53912/tests/dromaeo/cssquery-mootools.html) 17:24:40 INFO - PID 3711 | RSS: Main: 210255872 17:24:40 INFO - PID 3711 | 17:27:42 INFO - PID 3711 | Cycle 1(1): loaded http://localhost:53912/tests/dromaeo/cssquery-mootools.html (next: http://localhost:53912/tests/dromaeo/cssquery-prototype.html) 17:27:42 INFO - PID 3711 | RSS: Main: 211722240 17:27:42 INFO - PID 3711 | 17:30:46 INFO - PID 3711 | Cycle 1(1): loaded http://localhost:53912/tests/dromaeo/cssquery-prototype.html (next: http://localhost:53912/tests/dromaeo/cssquery-yui.html) 17:30:47 INFO - PID 3711 | RSS: Main: 213225472 17:30:47 INFO - PID 3711 | 17:33:49 INFO - PID 3711 | Cycle 1(1): loaded http://localhost:53912/tests/dromaeo/cssquery-yui.html (next: http://localhost:53912/tests/dromaeo/cssquery-dojo.html) 17:33:49 INFO - PID 3711 | RSS: Main: 215781376 17:33:49 INFO - PID 3711 | 17:33:49 INFO - PID 3711 | __start_tp_report 17:33:49 INFO - PID 3711 | _x_x_mozilla_page_load 17:33:49 INFO - PID 3711 | _x_x_mozilla_page_load_details 17:33:49 INFO - PID 3711 | |i|pagename|runs| 17:33:49 INFO - PID 3711 | |0;dojo.html;3251.8048917064993;3264.987853765333;3276.9508457373126;3306.09082502312;3311.9668803311965;6233.875322493547;6249.125122482856;6259.906101408476;6308.4322410983;6322.715477803498;6084.72618732157;6130.325664176937;6223.0354295084335;6227.034809604507;6255.343188965161;5821.592488525805;5838.824835254949;5843.123531470271;5851.882962340751;5858.472737453629;5543.889122217553;5714.3999880012625;5774.220480235177;5815.069588865782;5825;5433.402325744165;5702.743376548024;5737.426257374271;5817.854553636151;5865.560082993771;5647.068233741469;5651.039323315005;5659.717014149276;5741.110127930177;5746.942530574683;5667.4615911488345;5668.093105103205;5668.404817494145;5686.630369026041;5696.857578560528;6110.114033465167;6188.81433556994;6221.688915554249;6224.564280500367;6228.844278893019;6643.269240383554;6694;6709;6739.764108256237;6815.727370905158;6438.903416448757;6596.109525214083;6598.93401065988;6763.5941843489545;6771.4582833373215;6132.540059495511;6142.692865356715;6155;6159.4456498915315;6234.875302493925;6724.394804467622;6809.080774095532;6897.62063086525;6903.068085808452;6903.240643529208;6538.738450461929;6569.671516424255;6575.967120164369;6578.2106147261675;6597.670116494252;6661.833454163588;6685.298043705438;6688.732450702015;6688.933110668929;6697.8995315070315;6554.180727409074;6644.069830223772;6649.434798042221;6654.50091243149;6655.267920528738;6617.272100068989;6704.631245281556;6733.528652994341;6784.151981002375;6796.76211332601;5292.126799078186;5339.5728341732565;5378.2470454136455;5379.300690910157;5401.459854014645;4556.3165525170825;4605.0559635274685;4625.213713668684;4652.860414187579;4673.953260467351;5840.649561026352;5868.149118377812;5912.3496415394275;5921.171036054956;5927.318358388754;6731.596104233761;6753.425958793459;6756.594604323756;6806.217285012287;6815.591064536144;5670.971645141748;5883.911741323884;5941.792037278675;5949.405059494016;5968.731407086691;5706.71466426677;5793.159991801165;5793.188953546507;5857.531397488191;5859.5019423349495;6362.9045564316575;6436.066770318278;6499.317571655003;6550.2794692583775;6562.278149403466;6732.730690772314;6777.525573210025;6795.490338224752;6806.659667016728;6822.829429264308;6458.418742313214;6508.544401892012;6558.147440832661;6578.80263592093;6593.571417857824;6608.1079054328975;6628;6684.56281899394;6725.024871393718;6755.290694477206;6635.867282654416;6732.091167692299;6769.255381908085;6808.863822723419;6823.727050918106;6353.96823015882;6454.903176452263;6459.063435801689;6534.575252608565;6615.66921653925;6544;6619.139511863427;6631.270560238467;6638.90041649366;6641;6453.1610890583925;6467.514936379696;6476.773312934025;6481.319461456479;6559.0817285581015;6555.115059466911;6580.8354791130605;6603.537752357289;6654.633995130314;6715;6355.332690067661;6368.617882927039;6412.39082287182;6421.93578064232;6462.515311351762 17:33:49 INFO - PID 3711 | |1;ext.html;835.8119423129797;847.6312803930294;864.0711235421921;881.8809460722803;885.0441523154989;39586.406203906925;40730.7963460183;40744.79627601865;40908;40978.7951060245;38004.23991520168;38128.23743525128;38190.61809381905;38221;38348;6961.965190174054;7083.468739844507;7096.822579435529;7097.929020709778;7109.751158709446;6991.89512157318;7078.64606769662;7081.716731330741;7084.504084714072;7101.218865924744;7047.612381319051;7632.160462349138;7874.212578742108;7882.408819338544;7944.126146123922;7773.0672319321475;7853.136155022943;7861.174576669425;7880.645370958292;7909.644066017015;7610.124835643916;7691.307782299564;7742.574158421285;7814;7818.726344577913;36281.81859090714;36835.447468287995;37065;37070.25859482822;37234.44148337777;3329.883454079096;3346.263821959165;3350.3466823969334;3352.094934367707;3366.764326497146;30069.548956765666;30119.247018824484;30153.396932061456;30201.69798302011;30365.544516832266;5137.691738495665;5141.948580514222;5146.408163061258;5150.742462876841;5194.402643695986;207785;209157;211123;212166.93916530474;212355;48674.756626216644;49007.264891026665;49029.754851225516;49171.262431063566;49186.754066230154;52806.20790688143;53176;53901.46098538965;54018.729906350214;54194;15203.391864325304;15219;15345.539633811004;15347.15590642503;15429.305681244146;12524.24854508714;12579;12601.243925364508;12602;12612.054095942842;14021.789673154912;14107.929460352838;14172.503962361474;14175.858241417454;14310.49913253031;5730.512906402919;5789.2763404574425;5814.244148260699;5858.648481091148;5926.585139040226;938.5823308627595;988.5255077562811;1005.9094681478703;1012.6860673191334;1025.2259544044348;22538.661920071212;22864.65703014456;23167.652485212737;23199;23295.3011409658;1012.1750773119884;1085.478970094359;1096.9616063437902;1104.917131215162;1111.8165502691966;11915.987141093105;12267.815982760265;15473.76209903205;15573.299201535958;15763.68472630541;225301;225334;227891;228086;228125;47267.527324727;48159;54815.72592137093;55186.724066379415;56023.71988140115;1419.2690764256415;1424.3732757586836;1428.2073449235565;1428.5714285714243;1431.2772050114625;26600.334991625365;26916.86541567358;27143;27144.864275678494;27297.72702272952;37355;37618.24763504769;37787.244255114194;37827;38052.809735951145;209882;210780;210950;211375;211842;32451.513227302083;34177.82911085428;34422.31155376929;34583.4812477808;34623.82688086644;41644.37533437061;42547.787261063495;43115.7844210777;43120.78439607782;43186.56813431825;30707.692923071376;30963;31148.68851311458;31277.218069548442;31390.843045784626;30087;30147;30500.389992199587;31042;31158.68841311558;35373.115672108404;35389.11527211737;38309;39224.2155156901;39309.21381572296;32563.34873302568;32894.34211315712;32977.505337420436;33259.83370083134;33770.155746106546;23265.18571849977;24093;24685.62971555392;25181.748182517942;25206.74793252044 17:33:49 INFO - PID 3711 | |2;jquery.html;536.9167778994255;541.8022421816036;543.9184122381645;545.9699716515593;571.5513322042198;21619.567608647838;22164.556708865814;22196.55606887863;22204.666929996063;22266.332010039703;20891;22766;22786.316410507712;22828.771712282873;22883.542329153406;6539.08452816606;6718.790617688814;6720.731170753164;6733;6741.191057073133;5991.940080599204;6592.604443733391;6594.3075977022445;6642.601443913357;6665.566738162004;6502.2197336319705;6511.544191906569;6538.0519824625635;6554.80335589933;6569.638669873155;6455.4835613150835;6550.443212326958;6562.475001999828;6569.868602627945;6580.276169621337;5998.070299103646;6045.12345709874;6050.062240352752;6051.0923361496125;6079.969600152015;26722.331941701417;27174.728252717614;27461.45077098447;27749.722502774915;28256.717432825615;2000.779914209436;2027.858049936505;2030.6649402848511;2032.4004418696372;2052.5381789097482;18711.15799788999;19327;19399.90300048505;19507.317243896538;19643.705344419846;2404.0864471500718;2409.116427495056;2445.1075357494565;2479.665245191894;2488.9004439822384;136414;138535.30732346376;138862;138916;139304.30347848195;14001.569615444281;14129.293535323193;14419.35112919921;14553.92723036378;15093.773593396108;22169.22407715722;22423.775762242494;22492.325230243117;22571.661425078637;22687;9499.335046546676;9634.084761947604;9665.178459830853;9722.708318750449;9794.755131121636;8795.164459376349;8847.955760221157;8865.113488651083;8956.68651597191;9019.549022548847;7613.352865006425;8619.956900215459;8620.439671421338;8819.382643215042;9234.215091717278;2473.319837044827;2563.948721025569;2565.987170064138;2624.357032527005;2706.2693072870607;942.5569982108398;946.5551190940247;949.8385274503213;951.2259635779222;966.7583104223944;18699.25202991865;19485.8051419484;19891.701624475645;19936.401907942785;20003.499912502302;999.4203362049993;1015.5227043289642;1017.0846238385512;1029.0584115534202;1030.3354336452946;10503.212259080447;10728.517216725266;10824.891751082389;10854.67435976922;10943.4528273586;457834;469732;477949;481067;486667;110606;131188;133101.33449332693;134710;134837;1204.8491459454237;1219.1100496637328;1228.7726770547476;1231.8398608180878;1237.5730373021293;19508.31720889762;20250.189992400137;20503;20723.067461963947;20963;59427.40572594219;63349.36650633619;63553;63843.042354363795;64420.35579644331;80705;81627;82235.5888220555;82356;82575.58712206641;19718.704219437008;20253.392398228076;20271.59456810826;20321.59356812885;20323.69514457314;22700.545989079794;22982;23039.193628222933;23147.76852231456;23259.651105233097;17492.82507174912;17621.11894405249;17899.19453624616;17908;17965.820341796414;16674;16970.151492425575;17299.135043247534;17330.39343622967;17400.303987840853;17833;19052.618947621246;19102.044897755335;19209.90395048016;19361.903190483958;19788;19807;19935.900320498306;20031.499212519222;20291.492712682302;18212.81787182164;18311.90844045771;18390.356337528123;18437.907810460863;18454.81545184531 17:33:49 INFO - PID 3711 | |3;mootools.html;1259.9811002834958;1290.9999999999998;1297.403194530516;1304.634702283361;1307.2875282970786;14045.36795844187;14641.267936603168;14651.047681900669;14668.559943201695;14669.559913202622;15065.47270845518;15211.239438028082;15342.616434589141;15344.539663810076;15362.231888405591;2652.3634327761392;2668.119520558211;2702.594610808375;2709.322669332667;2719.2657982344754;2637;2659.335166208448;2693.2189664997127;2721.6189733437336;2723.2647185259966;2763.9723602764016;2787.7212278772163;2795.2872017635473;2797.8181418207855;2803.0889960762734;2673.42521357909;2699.2442116207494;2703.2836298380944;2710.7153748856285;2733.248356701903;2642.40545877178;2645.854478003709;2658.096247275916;2668.132856821541;2669.2392668089697;14400.351984160634;14583.125012499178;14789.11265324084;14797.112173269637;14797.704045919021;7350.705971761123;7583.924160758377;7607.847843043163;7622.733204337877;7631.084269887593;5267.315249017642;5422.43064478228;5440.211169380457;5555.527780138693;5568.610197286192;1866.878652887558;1873.9250429982785;1890.1210936914263;1905.0568420211446;1912.024867317664;24828;25033.749662503506;25051;25056;25114.623280650805;6611.206655201407;6719.529632925648;6758.560693554903;6916.896246556305;6926.480513961373;16434.096124713018;16455.6708865822;16523.33906643745;16540.172991350384;16558.503244902673;7596.696132154762;7804.297613214838;7970.322522585529;8169.591520423955;8182.590870456573;7108.857822843514;7166.713331466787;7206.882933145371;7290.307420795016;7292.817679558054;5092.465291144451;5138.100832354323;5226.529612334908;5257.553107985787;5334.0665383557725;1367.705943222192;1372.835259768834;1489.3149151390264;1525.1764047414495;1537.2851623994895;878.9736307910773;886.499127992682;902.3007169443603;908.3369140527452;911.2209061252534;9143.405678630868;9199.954000229956;9353.485558294358;9386.296027797942;9466.857997130048;938.0759951447818;973.4597298499401;981.6613268422383;983.8573406856109;989.0356902020616;5266.762995665128;5323.334583177103;5430.049741295257;5462.688954650935;5492.862678433071;19736.70394944077;19829;20470.283540076027;20591.8970405147;20828.791712082988;19620;20258.594828103356;20266.493337666383;20307.375409967462;20352.18591256363;10222.182225421948;10469.790604187874;10515.737106572396;10628.840567391495;10923.890761092447;7881.330086942674;7934.325582325567;8105.392095592736;8108.189181081844;8113.18868113184;16670.333186672397;17364.652706945537;17496.95018298906;17690.292388304322;17707.822921770618;17698.380556680455;17762.11189440497;17798.377056803467;17822.5544361392;17967.281308747504;10852.294600850919;10902.236843421202;10903.509342079784;11021.779564408507;11025.944870275598;13169.407376667881;13210.075294729277;13217.603471895858;13513;13546.93226533861;9959.302848800515;10106.343087699275;10692.518836652525;10785.946070269598;11219.382933938712;10238.846417303896;10246.333988290737;10529.262951593315;10715.35707857531;10813.351198928089;9101.40840845343;9212.216961558343;9256.814863702823;9363.719088427359;9366.906330936603;7355.483387459405;7494.775156745306;7500.549967001997;7504.249575042452;7509.023826902468;7619.31426171625;7656.80857978555;7670.463067585216;7749.031371078615;7781.144074151725 17:33:49 INFO - PID 3711 | |4;prototype.html;504.4199170953401;507.65225820313077;519.1226826662939;524.9580033597313;531.0891820527796;6283.528735344845;6378.872422551546;6386.616802991818;6398.488120950325;6418.42234198922;6111.847203819907;6216.440520353167;6231.563790534665;6274.184356033709;6311.3688631136865;5167.586593072555;5213.983273261715;5219.686818790885;5225.399079105894;5231.712255825928;5163.432022477525;5181.844544663647;5182.248573956774;5229.7385130743505;5273.156294992802;4754.215554433515;5155.664881782691;5194.662346947455;5217.713025783581;5248.580113590922;4931.65478416511;4998.925016124761;5037.345145131109;5040.243963405482;5053.873653158664;4442.333649952533;4460.598546130864;4468.865934021952;4472.664550158751;4489.1919454498175;5921.763129474816;6101.267847858241;6127.356627554087;6132.570720049598;6183.6598987055695;5683.318001839764;5723.57073219506;5755.65466072037;5796.942030579725;5811;6581.93418065818;6618.867622647568;6625.138731964862;6633.966830165867;6656.36764507371;4811.927821082687;4888.680056384756;4898.118338699032;4902.142125128124;4924.285978533093;6719.563228390188;6901.930980690229;6931.480138989596;6971.965140174267;6998.825029374256;4878.658493905393;4974.104661160954;5026.623003274769;5058.190689489664;5067.721275329893;5056.241563765391;5067.771950262247;5073.213651883964;5138.665986710852;5143.305653736697;5681.886362272731;5698.6580805151;5702.8289151325525;5709.086546152679;5714.79998200061;6479.319671434431;6597.571157874723;6635.601863888182;6661.766838160641;6680.799576012728;1737.9131043447776;1764.6941263464944;1766.5760217547952;1787.3297513432462;1787.7497150398954;1158.4014060596323;1207.824865394513;1214.8663646998823;1246.0903540415547;1248.9063320251018;5826.446487583673;5840.430210707275;5906.629094470061;5913.379095194942;5929.377415371324;6762.188537375547;6792;6799.898001529981;6840.11078559794;6878.621675807879;5829.154772557956;5887.264091988502;5893.675847828325;5900.675462849498;5913.629231338096;3553.6091029986683;3604.9098772530897;3620.4931309616672;3622.438522029037;3640.8543658253902;6447.323031081763;6498.675066246668;6581.703823327962;6627.608202277575;6634.014693387899;6897.3447522485285;6950.54821436605;6961.389478945395;7028.2620324866175;7032.507724459341;6144.262688477412;6477.967610161919;6478.870422591522;6532.216134063943;6619.702113404908;6533.379328963741;6605.339466053356;6675.7997260082275;6706.698198580978;6756.6959486822225;6688.464922806066;6718.227403848425;6745.9662701686175;6751.156105486814;6783.152105986752;6495.2855185928565;6544.298482394516;6562.5406221564035;6634.867302654016;6642.435392991457;4972.756810797301;4988.476209997898;4993.975030124971;4999.475055119306;5041.2438134280155;6632.436242919213;6632.701528431136;6796.504768950823;6917.481188910952;6956;6335.809925702236;6424.775132870327;6445.25879523842;6663.900041499284;6676.132102826601;6450.580712253689;6495.967520162558;6509.349065093453;6575.769848055295;6630.5027122965;6419.967900160469;6448.161738973903;6495.610263384212;6510.674466276572;6516.218053833571;6501.902471463027;6585.473162147113;6633.583704392146;6637.834054148685;6661.500387470862;6425.742970281136;6428.421442070049;6429.7428102875365;6450.580712253689;6500.822224554827 17:33:49 INFO - PID 3711 | |5;yui.html;803.7709252862935;805.3195050182594;821.4085858182107;880.0847118996244;898.2723993565211;15589.766153507706;15905.045697258158;18303.99999999998;18806.717899231524;18945.43163705091;18338.816611833878;20181.39455816328;20208.292709755126;20302.69545956812;20404.387868363934;931.687884558672;944.6363150187193;947.9194268487187;948.5020364308724;950.7860731335463;903.5933829776594;907.3557773980482;953.4708236928502;953.8759961205034;964.5948701545333;992.8808542974817;1006.8238058339798;1007.0332480818423;1007.471077684218;1008.4856723071213;986.1814693804125;1005.708344580071;1005.8843233028223;1007.0584003956251;1007.4912169354502;721.3219573600799;729.923358047408;731.1628185727312;735.2463724682243;736.0835759479429;18073.27706891723;18736;18781;18900.905495472434;19045;19829.50426239341;20649.690254646193;21016;21127.04928278231;21243.893780530998;16736.58158546034;16972.151392430453;16987.915060424617;16997.745033824503;17080.31678732849;3674.5223120994365;3720.1815600567834;3726.6273372662786;3736.0286325555553;3745.325841348556;122878;124888;125142;128475.35762321221;132904;16591;16847.915760421365;16940.830591693924;17204.741928871077;17228.91385543064;28598.14205573836;29852;30033;30041.549376759365;30067.248318792204;8896.822063558693;9043.819123617492;9046.366754327266;9049.502277374675;9057.049009854003;4110.198511290329;4175.144095460423;4188.4136220929095;4194.2869712149;4195.832166713359;4851.393575803025;5014.272930425141;5015.623828212899;5022.573081288131;5023.572996295356;950.9667161649309;976.1409959235968;977.9608815647437;978.3445091788519;978.8335982882785;676.8307923019245;678.5657179405184;688.0161369242032;715.6493318274113;719.7085180501905;12164.452599633038;12309;12618.55835045769;13250.403731832092;13316;686.0943554508002;688.9483288753263;691.5574032619028;696.0568429777679;712.9073220481304;5814;5845.912311315334;5867.237259156283;5907.822765317047;5959.642421454727;108040;108977;109440;113188.43405782919;116845.4157729223;92199.78002199727;94975.52512237395;95816.52091739637;96933;100566;86158.56920715356;87387.56306218429;87834;87873.56063219643;89664;10608.89391106079;10728.51721672511;10748.89251107479;10910.290831095954;10929.453527323443;40745.20011390859;41255.7937210312;42159.7892010538;42327.36508952307;42465.36301955411;53172;53625.731871340395;54169.72915135399;54179.45820541902;54837.451625483234;16113.838861611233;16269.67460650804;16281;16364.181790910168;16586;17551.385701500385;17838.28646854111;17847.553811154306;17858.28566857363;17966.820331796516;15817.130057846929;15840.366385344458;16022.599435014217;16118.919405402898;16136.193190340671;16496.752548711538;16607.91696041512;16640.750388743938;16832.326706931584;17076.74384884203;15902.761458578361;15940.681186375976;15959.521214363587;16093.517194484184;16317.59206019859;11644.88355116438;11727.35499547533;11954.58158964432;12026.338551379758;12237.082218833444;11528.769424611628;11652.94173529127;11822.527098916291;11849.466773995364;11922.880771192524 17:33:49 INFO - PID 3711 | __end_tp_report 17:33:49 INFO - PID 3711 | __start_cc_report 17:33:49 INFO - PID 3711 | _x_x_mozilla_cycle_collect,364 17:33:49 INFO - PID 3711 | __end_cc_report 17:33:49 INFO - PID 3711 | __startTimestamp1503707629532__endTimestamp 17:33:49 INFO - PID 3711 | 17:33:49 INFO - PID 3711 | ------- Summary: start ------- 17:33:49 INFO - PID 3711 | Number of tests: 6 17:33:49 INFO - PID 3711 | 17:33:49 INFO - PID 3711 | [#0] dojo.html Cycles:165 Average:6174.57 Median:6438.90 stddev:719.22 (11.2%) stddev-sans-first:683.90 17:33:49 INFO - PID 3711 | Values: 3251.8 3265.0 3277.0 3306.1 3312.0 6233.9 6249.1 6259.9 6308.4 6322.7 6084.7 6130.3 6223.0 6227.0 6255.3 5821.6 5838.8 5843.1 5851.9 5858.5 5543.9 5714.4 5774.2 5815.1 5825.0 5433.4 5702.7 5737.4 5817.9 5865.6 5647.1 5651.0 5659.7 5741.1 5746.9 5667.5 5668.1 5668.4 5686.6 5696.9 6110.1 6188.8 6221.7 6224.6 6228.8 6643.3 6694.0 6709.0 6739.8 6815.7 6438.9 6596.1 6598.9 6763.6 6771.5 6132.5 6142.7 6155.0 6159.4 6234.9 6724.4 6809.1 6897.6 6903.1 6903.2 6538.7 6569.7 6576.0 6578.2 6597.7 6661.8 6685.3 6688.7 6688.9 6697.9 6554.2 6644.1 6649.4 6654.5 6655.3 6617.3 6704.6 6733.5 6784.2 6796.8 5292.1 5339.6 5378.2 5379.3 5401.5 4556.3 4605.1 4625.2 4652.9 4674.0 5840.6 5868.1 5912.3 5921.2 5927.3 6731.6 6753.4 6756.6 6806.2 6815.6 5671.0 5883.9 5941.8 5949.4 5968.7 5706.7 5793.2 5793.2 5857.5 5859.5 6362.9 6436.1 6499.3 6550.3 6562.3 6732.7 6777.5 6795.5 6806.7 6822.8 6458.4 6508.5 6558.1 6578.8 6593.6 6608.1 6628.0 6684.6 6725.0 6755.3 6635.9 6732.1 6769.3 6808.9 6823.7 6354.0 6454.9 6459.1 6534.6 6615.7 6544.0 6619.1 6631.3 6638.9 6641.0 6453.2 6467.5 6476.8 6481.3 6559.1 6555.1 6580.8 6603.5 6654.6 6715.0 6355.3 6368.6 6412.4 6421.9 6462.5 17:33:49 INFO - PID 3711 | 17:33:49 INFO - PID 3711 | [#1] ext.html Cycles:180 Average:38413.05 Median:34502.90 stddev:56045.62 (162.4%) stddev-sans-first:56131.82 17:33:49 INFO - PID 3711 | Values: 835.8 847.6 864.1 881.9 885.0 39586.4 40730.8 40744.8 40908.0 40978.8 38004.2 38128.2 38190.6 38221.0 38348.0 6962.0 7083.5 7096.8 7097.9 7109.8 6991.9 7078.6 7081.7 7084.5 7101.2 7047.6 7632.2 7874.2 7882.4 7944.1 7773.1 7853.1 7861.2 7880.6 7909.6 7610.1 7691.3 7742.6 7814.0 7818.7 36281.8 36835.4 37065.0 37070.3 37234.4 3329.9 3346.3 3350.3 3352.1 3366.8 30069.5 30119.2 30153.4 30201.7 30365.5 5137.7 5141.9 5146.4 5150.7 5194.4 207785.0 209157.0 211123.0 212166.9 212355.0 48674.8 49007.3 49029.8 49171.3 49186.8 52806.2 53176.0 53901.5 54018.7 54194.0 15203.4 15219.0 15345.5 15347.2 15429.3 12524.2 12579.0 12601.2 12602.0 12612.1 14021.8 14107.9 14172.5 14175.9 14310.5 5730.5 5789.3 5814.2 5858.6 5926.6 938.6 988.5 1005.9 1012.7 1025.2 22538.7 22864.7 23167.7 23199.0 23295.3 1012.2 1085.5 1097.0 1104.9 1111.8 11916.0 12267.8 15473.8 15573.3 15763.7 225301.0 225334.0 227891.0 228086.0 228125.0 47267.5 48159.0 54815.7 55186.7 56023.7 1419.3 1424.4 1428.2 1428.6 1431.3 26600.3 26916.9 27143.0 27144.9 27297.7 37355.0 37618.2 37787.2 37827.0 38052.8 209882.0 210780.0 210950.0 211375.0 211842.0 32451.5 34177.8 34422.3 34583.5 34623.8 41644.4 42547.8 43115.8 43120.8 43186.6 30707.7 30963.0 31148.7 31277.2 31390.8 30087.0 30147.0 30500.4 31042.0 31158.7 35373.1 35389.1 38309.0 39224.2 39309.2 32563.3 32894.3 32977.5 33259.8 33770.2 23265.2 24093.0 24685.6 25181.7 25206.7 17:33:49 INFO - PID 3711 | 17:33:49 INFO - PID 3711 | [#2] jquery.html Cycles:180 Average:35315.67 Median:22733.27 stddev:80827.84 (355.5%) stddev-sans-first:81012.40 17:33:49 INFO - PID 3711 | Values: 536.9 541.8 543.9 546.0 571.6 21619.6 22164.6 22196.6 22204.7 22266.3 20891.0 22766.0 22786.3 22828.8 22883.5 6539.1 6718.8 6720.7 6733.0 6741.2 5991.9 6592.6 6594.3 6642.6 6665.6 6502.2 6511.5 6538.1 6554.8 6569.6 6455.5 6550.4 6562.5 6569.9 6580.3 5998.1 6045.1 6050.1 6051.1 6080.0 26722.3 27174.7 27461.5 27749.7 28256.7 2000.8 2027.9 2030.7 2032.4 2052.5 18711.2 19327.0 19399.9 19507.3 19643.7 2404.1 2409.1 2445.1 2479.7 2488.9 136414.0 138535.3 138862.0 138916.0 139304.3 14001.6 14129.3 14419.4 14553.9 15093.8 22169.2 22423.8 22492.3 22571.7 22687.0 9499.3 9634.1 9665.2 9722.7 9794.8 8795.2 8848.0 8865.1 8956.7 9019.5 7613.4 8620.0 8620.4 8819.4 9234.2 2473.3 2563.9 2566.0 2624.4 2706.3 942.6 946.6 949.8 951.2 966.8 18699.3 19485.8 19891.7 19936.4 20003.5 999.4 1015.5 1017.1 1029.1 1030.3 10503.2 10728.5 10824.9 10854.7 10943.5 457834.0 469732.0 477949.0 481067.0 486667.0 110606.0 131188.0 133101.3 134710.0 134837.0 1204.8 1219.1 1228.8 1231.8 1237.6 19508.3 20250.2 20503.0 20723.1 20963.0 59427.4 63349.4 63553.0 63843.0 64420.4 80705.0 81627.0 82235.6 82356.0 82575.6 19718.7 20253.4 20271.6 20321.6 20323.7 22700.5 22982.0 23039.2 23147.8 23259.7 17492.8 17621.1 17899.2 17908.0 17965.8 16674.0 16970.2 17299.1 17330.4 17400.3 17833.0 19052.6 19102.0 19209.9 19361.9 19788.0 19807.0 19935.9 20031.5 20291.5 18212.8 18311.9 18390.4 18437.9 18454.8 17:33:49 INFO - PID 3711 | 17:33:49 INFO - PID 3711 | [#3] mootools.html Cycles:180 Average:9024.76 Median:2668.13 stddev:6246.68 (234.1%) stddev-sans-first:6236.96 17:33:49 INFO - PID 3711 | Values: 1260.0 1291.0 1297.4 1304.6 1307.3 14045.4 14641.3 14651.0 14668.6 14669.6 15065.5 15211.2 15342.6 15344.5 15362.2 2652.4 2668.1 2702.6 2709.3 2719.3 2637.0 2659.3 2693.2 2721.6 2723.3 2764.0 2787.7 2795.3 2797.8 2803.1 2673.4 2699.2 2703.3 2710.7 2733.2 2642.4 2645.9 2658.1 2668.1 2669.2 14400.4 14583.1 14789.1 14797.1 14797.7 7350.7 7583.9 7607.8 7622.7 7631.1 5267.3 5422.4 5440.2 5555.5 5568.6 1866.9 1873.9 1890.1 1905.1 1912.0 24828.0 25033.7 25051.0 25056.0 25114.6 6611.2 6719.5 6758.6 6916.9 6926.5 16434.1 16455.7 16523.3 16540.2 16558.5 7596.7 7804.3 7970.3 8169.6 8182.6 7108.9 7166.7 7206.9 7290.3 7292.8 5092.5 5138.1 5226.5 5257.6 5334.1 1367.7 1372.8 1489.3 1525.2 1537.3 879.0 886.5 902.3 908.3 911.2 9143.4 9200.0 9353.5 9386.3 9466.9 938.1 973.5 981.7 983.9 989.0 5266.8 5323.3 5430.0 5462.7 5492.9 19736.7 19829.0 20470.3 20591.9 20828.8 19620.0 20258.6 20266.5 20307.4 20352.2 10222.2 10469.8 10515.7 10628.8 10923.9 7881.3 7934.3 8105.4 8108.2 8113.2 16670.3 17364.7 17497.0 17690.3 17707.8 17698.4 17762.1 17798.4 17822.6 17967.3 10852.3 10902.2 10903.5 11021.8 11025.9 13169.4 13210.1 13217.6 13513.0 13546.9 9959.3 10106.3 10692.5 10785.9 11219.4 10238.8 10246.3 10529.3 10715.4 10813.4 9101.4 9212.2 9256.8 9363.7 9366.9 7355.5 7494.8 7500.5 7504.2 7509.0 7619.3 7656.8 7670.5 7749.0 7781.1 17:33:49 INFO - PID 3711 | 17:33:49 INFO - PID 3711 | [#4] prototype.html Cycles:180 Average:5563.45 Median:6163.96 stddev:1555.83 (25.2%) stddev-sans-first:1513.14 17:33:49 INFO - PID 3711 | Values: 504.4 507.7 519.1 525.0 531.1 6283.5 6378.9 6386.6 6398.5 6418.4 6111.8 6216.4 6231.6 6274.2 6311.4 5167.6 5214.0 5219.7 5225.4 5231.7 5163.4 5181.8 5182.2 5229.7 5273.2 4754.2 5155.7 5194.7 5217.7 5248.6 4931.7 4998.9 5037.3 5040.2 5053.9 4442.3 4460.6 4468.9 4472.7 4489.2 5921.8 6101.3 6127.4 6132.6 6183.7 5683.3 5723.6 5755.7 5796.9 5811.0 6581.9 6618.9 6625.1 6634.0 6656.4 4811.9 4888.7 4898.1 4902.1 4924.3 6719.6 6901.9 6931.5 6972.0 6998.8 4878.7 4974.1 5026.6 5058.2 5067.7 5056.2 5067.8 5073.2 5138.7 5143.3 5681.9 5698.7 5702.8 5709.1 5714.8 6479.3 6597.6 6635.6 6661.8 6680.8 1737.9 1764.7 1766.6 1787.3 1787.7 1158.4 1207.8 1214.9 1246.1 1248.9 5826.4 5840.4 5906.6 5913.4 5929.4 6762.2 6792.0 6799.9 6840.1 6878.6 5829.2 5887.3 5893.7 5900.7 5913.6 3553.6 3604.9 3620.5 3622.4 3640.9 6447.3 6498.7 6581.7 6627.6 6634.0 6897.3 6950.5 6961.4 7028.3 7032.5 6144.3 6478.0 6478.9 6532.2 6619.7 6533.4 6605.3 6675.8 6706.7 6756.7 6688.5 6718.2 6746.0 6751.2 6783.2 6495.3 6544.3 6562.5 6634.9 6642.4 4972.8 4988.5 4994.0 4999.5 5041.2 6632.4 6632.7 6796.5 6917.5 6956.0 6335.8 6424.8 6445.3 6663.9 6676.1 6450.6 6496.0 6509.3 6575.8 6630.5 6420.0 6448.2 6495.6 6510.7 6516.2 6501.9 6585.5 6633.6 6637.8 6661.5 6425.7 6428.4 6429.7 6450.6 6500.8 17:33:49 INFO - PID 3711 | 17:33:49 INFO - PID 3711 | [#5] yui.html Cycles:180 Average:22889.60 Median:20832.85 stddev:31849.04 (152.9%) stddev-sans-first:31895.21 17:33:49 INFO - PID 3711 | Values: 803.8 805.3 821.4 880.1 898.3 15589.8 15905.0 18304.0 18806.7 18945.4 18338.8 20181.4 20208.3 20302.7 20404.4 931.7 944.6 947.9 948.5 950.8 903.6 907.4 953.5 953.9 964.6 992.9 1006.8 1007.0 1007.5 1008.5 986.2 1005.7 1005.9 1007.1 1007.5 721.3 729.9 731.2 735.2 736.1 18073.3 18736.0 18781.0 18900.9 19045.0 19829.5 20649.7 21016.0 21127.0 21243.9 16736.6 16972.2 16987.9 16997.7 17080.3 3674.5 3720.2 3726.6 3736.0 3745.3 122878.0 124888.0 125142.0 128475.4 132904.0 16591.0 16847.9 16940.8 17204.7 17228.9 28598.1 29852.0 30033.0 30041.5 30067.2 8896.8 9043.8 9046.4 9049.5 9057.0 4110.2 4175.1 4188.4 4194.3 4195.8 4851.4 5014.3 5015.6 5022.6 5023.6 951.0 976.1 978.0 978.3 978.8 676.8 678.6 688.0 715.6 719.7 12164.5 12309.0 12618.6 13250.4 13316.0 686.1 688.9 691.6 696.1 712.9 5814.0 5845.9 5867.2 5907.8 5959.6 108040.0 108977.0 109440.0 113188.4 116845.4 92199.8 94975.5 95816.5 96933.0 100566.0 86158.6 87387.6 87834.0 87873.6 89664.0 10608.9 10728.5 10748.9 10910.3 10929.5 40745.2 41255.8 42159.8 42327.4 42465.4 53172.0 53625.7 54169.7 54179.5 54837.5 16113.8 16269.7 16281.0 16364.2 16586.0 17551.4 17838.3 17847.6 17858.3 17966.8 15817.1 15840.4 16022.6 16118.9 16136.2 16496.8 16607.9 16640.8 16832.3 17076.7 15902.8 15940.7 15959.5 16093.5 16317.6 11644.9 11727.4 11954.6 12026.3 12237.1 11528.8 11652.9 11822.5 11849.5 11922.9 17:33:49 INFO - PID 3711 | -------- Summary: end -------- 17:33:49 INFO - PID 3711 | 17:33:50 INFO - TEST-INFO | 3711: exit 0 17:33:50 INFO - TEST-OK | dromaeo_css | took 1118561ms 17:33:50 INFO - TEST-START | kraken 17:33:50 INFO - Initialising browser for kraken test... 17:33:50 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:53912/getInfo.html -profile /tmp/tmp1baew1/profile 17:33:50 INFO - TEST-INFO | started process 4141 (/builds/slave/test/build/application/firefox/firefox http://localhost:53912/getInfo.html) 17:33:57 INFO - TEST-INFO | 4141: exit 0 17:33:57 INFO - Browser initialized. 17:33:57 INFO - Running cycle 1/1 for kraken test... 17:33:57 INFO - Using env: {'DISPLAY': ':0', 17:33:57 INFO - 'HOME': '/home/cltbld', 17:33:57 INFO - 'JSGC_DISABLE_POISONING': '1', 17:33:57 INFO - 'LANG': 'en_US.UTF-8', 17:33:57 INFO - 'LANGUAGE': 'en_US:en', 17:33:57 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 17:33:57 INFO - 'LOGNAME': 'cltbld', 17:33:57 INFO - 'MAIL': '/var/mail/cltbld', 17:33:57 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:33:57 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 17:33:57 INFO - 'MOZ_ACCELERATED': '1', 17:33:57 INFO - 'MOZ_CRASHREPORTER': '1', 17:33:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:33:57 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 17:33:57 INFO - 'MOZ_NO_REMOTE': '1', 17:33:57 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:33:57 INFO - 'MOZ_WEBRENDER': '1', 17:33:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:33:57 INFO - 'NO_EM_RESTART': '1', 17:33:57 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:33:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:33:57 INFO - 'PWD': '/builds/slave/test', 17:33:57 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:33:57 INFO - 'RUST_BACKTRACE': 'full', 17:33:57 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 17:33:57 INFO - 'SHELL': '/bin/bash', 17:33:57 INFO - 'SHLVL': '1', 17:33:57 INFO - 'TERM': 'linux', 17:33:57 INFO - 'TMOUT': '86400', 17:33:57 INFO - 'USER': 'cltbld', 17:33:57 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1503706434.912044-1353726718', 17:33:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:33:57 INFO - '_': '/tools/buildbot/bin/python'} 17:33:57 INFO - TEST-INFO | started process 4324 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp1baew1/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 17:33:58 INFO - PID 4324 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 17:33:58 INFO - PID 4324 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 17:33:58 INFO - PID 4324 | 17:33:58 INFO - PID 4324 | (/builds/slave/test/build/application/firefox/firefox:4383): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 17:33:58 INFO - PID 4324 | 17:33:59 INFO - PID 4324 | 17:33:59 INFO - PID 4324 | (/builds/slave/test/build/application/firefox/firefox:4440): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 17:33:59 INFO - PID 4324 | 17:33:59 INFO - PID 4324 | __metrics Screen width/height:1600/1200 17:33:59 INFO - PID 4324 | colorDepth:24 17:33:59 INFO - PID 4324 | Browser inner width/height: 1024/768 17:33:59 INFO - PID 4324 | __metrics 17:34:09 INFO - PID 4324 | RSS: Main: 202895360 17:34:09 INFO - PID 4324 | 17:34:11 INFO - PID 4324 | Cycle 1(1): loaded http://localhost:53912/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:53912/tests/kraken/driver.html?testName=audio-beat-detection) 17:34:12 INFO - PID 4324 | RSS: Main: 208957440 17:34:12 INFO - PID 4324 | 17:34:12 INFO - PID 4324 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 17:34:15 INFO - PID 4324 | Cycle 1(1): loaded http://localhost:53912/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:53912/tests/kraken/driver.html?testName=audio-dft) 17:34:16 INFO - PID 4324 | RSS: Main: 211107840 17:34:16 INFO - PID 4324 | 17:34:19 INFO - PID 4324 | Cycle 1(1): loaded http://localhost:53912/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:53912/tests/kraken/driver.html?testName=audio-fft) 17:34:19 INFO - PID 4324 | RSS: Main: 204828672 17:34:19 INFO - PID 4324 | 17:34:21 INFO - PID 4324 | Cycle 1(1): loaded http://localhost:53912/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:53912/tests/kraken/driver.html?testName=audio-oscillator) 17:34:22 INFO - PID 4324 | RSS: Main: 204816384 17:34:22 INFO - PID 4324 | 17:34:24 INFO - PID 4324 | Cycle 1(1): loaded http://localhost:53912/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:53912/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:34:25 INFO - PID 4324 | RSS: Main: 206651392 17:34:25 INFO - PID 4324 | 17:34:28 INFO - PID 4324 | Cycle 1(1): loaded http://localhost:53912/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:53912/tests/kraken/driver.html?testName=imaging-darkroom) 17:34:29 INFO - PID 4324 | RSS: Main: 207093760 17:34:29 INFO - PID 4324 | 17:34:32 INFO - PID 4324 | Cycle 1(1): loaded http://localhost:53912/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:53912/tests/kraken/driver.html?testName=imaging-desaturate) 17:34:33 INFO - PID 4324 | RSS: Main: 206970880 17:34:33 INFO - PID 4324 | 17:34:36 INFO - PID 4324 | Cycle 1(1): loaded http://localhost:53912/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:53912/tests/kraken/driver.html?testName=json-parse-financial) 17:34:36 INFO - PID 4324 | RSS: Main: 207720448 17:34:36 INFO - PID 4324 | 17:34:38 INFO - PID 4324 | Cycle 1(1): loaded http://localhost:53912/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:53912/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:34:39 INFO - PID 4324 | RSS: Main: 206909440 17:34:39 INFO - PID 4324 | 17:34:40 INFO - PID 4324 | Cycle 1(1): loaded http://localhost:53912/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:53912/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:34:41 INFO - PID 4324 | RSS: Main: 207265792 17:34:41 INFO - PID 4324 | 17:34:43 INFO - PID 4324 | Cycle 1(1): loaded http://localhost:53912/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:53912/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:34:44 INFO - PID 4324 | RSS: Main: 207413248 17:34:44 INFO - PID 4324 | 17:34:47 INFO - PID 4324 | Cycle 1(1): loaded http://localhost:53912/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:53912/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:34:47 INFO - PID 4324 | RSS: Main: 208506880 17:34:47 INFO - PID 4324 | 17:34:50 INFO - PID 4324 | Cycle 1(1): loaded http://localhost:53912/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:53912/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:34:51 INFO - PID 4324 | RSS: Main: 208449536 17:34:51 INFO - PID 4324 | 17:34:53 INFO - PID 4324 | Cycle 1(1): loaded http://localhost:53912/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:53912/tests/kraken/driver.html?testName=ai-astar) 17:34:53 INFO - PID 4324 | RSS: Main: 210714624 17:34:53 INFO - PID 4324 | 17:34:53 INFO - PID 4324 | __start_tp_report 17:34:53 INFO - PID 4324 | _x_x_mozilla_page_load 17:34:53 INFO - PID 4324 | _x_x_mozilla_page_load_details 17:34:53 INFO - PID 4324 | |i|pagename|runs| 17:34:53 INFO - PID 4324 | |0;ai-astar;98;113;100;114;111;111;112;105;111;115 17:34:53 INFO - PID 4324 | |1;audio-beat-detection;144;116;154;189;121;145;152;120;176;125 17:34:53 INFO - PID 4324 | |2;audio-dft;165;162;164;163;165;167;166;172;169;164 17:34:53 INFO - PID 4324 | |3;audio-fft;78;87;81;170;81;74;74;98;100;80 17:34:53 INFO - PID 4324 | |4;audio-oscillator;85;80;130;79;79;79;135;86;81;135 17:34:53 INFO - PID 4324 | |5;imaging-gaussian-blur;117;114;114;114;115;121;124;116;124;115 17:34:53 INFO - PID 4324 | |6;imaging-darkroom;148;152;155;153;155;148;148;150;156;156 17:34:53 INFO - PID 4324 | |7;imaging-desaturate;94;94;94;96;100;94;95;94;94;94 17:34:53 INFO - PID 4324 | |8;json-parse-financial;55;52;51;55;53;52;52;52;55;52 17:34:53 INFO - PID 4324 | |9;json-stringify-tinderbox;57;58;56;56;56;56;60;56;62;57 17:34:53 INFO - PID 4324 | |10;stanford-crypto-aes;82;77;76;89;87;81;90;74;83;76 17:34:53 INFO - PID 4324 | |11;stanford-crypto-ccm;139;117;140;143;127;129;121;131;118;155 17:34:53 INFO - PID 4324 | |12;stanford-crypto-pbkdf2;180;167;170;171;192;169;171;181;182;173 17:34:53 INFO - PID 4324 | |13;stanford-crypto-sha256-iterative;65;65;66;64;64;69;66;65;65;63 17:34:53 INFO - PID 4324 | __end_tp_report 17:34:53 INFO - PID 4324 | __start_cc_report 17:34:53 INFO - PID 4324 | _x_x_mozilla_cycle_collect,990 17:34:53 INFO - PID 4324 | __end_cc_report 17:34:53 INFO - PID 4324 | __startTimestamp1503707693584__endTimestamp 17:34:53 INFO - PID 4324 | 17:34:53 INFO - PID 4324 | ------- Summary: start ------- 17:34:53 INFO - PID 4324 | Number of tests: 14 17:34:53 INFO - PID 4324 | 17:34:53 INFO - PID 4324 | [#0] ai-astar Cycles:10 Average:109.00 Median:112.50 stddev:5.93 (5.3%) stddev-sans-first:4.76 17:34:53 INFO - PID 4324 | Values: 98.0 113.0 100.0 114.0 111.0 111.0 112.0 105.0 111.0 115.0 17:34:53 INFO - PID 4324 | 17:34:53 INFO - PID 4324 | [#1] audio-beat-detection Cycles:10 Average:144.20 Median:148.50 stddev:24.64 (16.6%) stddev-sans-first:26.13 17:34:53 INFO - PID 4324 | Values: 144.0 116.0 154.0 189.0 121.0 145.0 152.0 120.0 176.0 125.0 17:34:53 INFO - PID 4324 | 17:34:53 INFO - PID 4324 | [#2] audio-dft Cycles:10 Average:165.70 Median:165.50 stddev:2.98 (1.8%) stddev-sans-first:3.15 17:34:53 INFO - PID 4324 | Values: 165.0 162.0 164.0 163.0 165.0 167.0 166.0 172.0 169.0 164.0 17:34:53 INFO - PID 4324 | 17:34:53 INFO - PID 4324 | [#3] audio-fft Cycles:10 Average:92.30 Median:80.50 stddev:28.75 (35.7%) stddev-sans-first:30.02 17:34:53 INFO - PID 4324 | Values: 78.0 87.0 81.0 170.0 81.0 74.0 74.0 98.0 100.0 80.0 17:34:53 INFO - PID 4324 | 17:34:53 INFO - PID 4324 | [#4] audio-oscillator Cycles:10 Average:96.90 Median:79.50 stddev:25.30 (31.8%) stddev-sans-first:26.46 17:34:53 INFO - PID 4324 | Values: 85.0 80.0 130.0 79.0 79.0 79.0 135.0 86.0 81.0 135.0 17:34:53 INFO - PID 4324 | 17:34:53 INFO - PID 4324 | [#5] imaging-gaussian-blur Cycles:10 Average:117.40 Median:116.50 stddev:4.06 (3.5%) stddev-sans-first:4.30 17:34:53 INFO - PID 4324 | Values: 117.0 114.0 114.0 114.0 115.0 121.0 124.0 116.0 124.0 115.0 17:34:53 INFO - PID 4324 | 17:34:53 INFO - PID 4324 | [#6] imaging-darkroom Cycles:10 Average:152.10 Median:154.00 stddev:3.38 (2.2%) stddev-sans-first:3.24 17:34:53 INFO - PID 4324 | Values: 148.0 152.0 155.0 153.0 155.0 148.0 148.0 150.0 156.0 156.0 17:34:53 INFO - PID 4324 | 17:34:53 INFO - PID 4324 | [#7] imaging-desaturate Cycles:10 Average:94.90 Median:94.00 stddev:1.91 (2.0%) stddev-sans-first:2.00 17:34:53 INFO - PID 4324 | Values: 94.0 94.0 94.0 96.0 100.0 94.0 95.0 94.0 94.0 94.0 17:34:53 INFO - PID 4324 | 17:34:53 INFO - PID 4324 | [#8] json-parse-financial Cycles:10 Average:52.90 Median:52.50 stddev:1.52 (2.9%) stddev-sans-first:1.41 17:34:53 INFO - PID 4324 | Values: 55.0 52.0 51.0 55.0 53.0 52.0 52.0 52.0 55.0 52.0 17:34:53 INFO - PID 4324 | 17:34:53 INFO - PID 4324 | [#9] json-stringify-tinderbox Cycles:10 Average:57.40 Median:57.00 stddev:2.07 (3.6%) stddev-sans-first:2.19 17:34:53 INFO - PID 4324 | Values: 57.0 58.0 56.0 56.0 56.0 56.0 60.0 56.0 62.0 57.0 17:34:53 INFO - PID 4324 | 17:34:53 INFO - PID 4324 | [#10] stanford-crypto-aes Cycles:10 Average:81.50 Median:82.50 stddev:5.76 (7.0%) stddev-sans-first:6.11 17:34:53 INFO - PID 4324 | Values: 82.0 77.0 76.0 89.0 87.0 81.0 90.0 74.0 83.0 76.0 17:34:53 INFO - PID 4324 | 17:34:53 INFO - PID 4324 | [#11] stanford-crypto-ccm Cycles:10 Average:132.00 Median:135.00 stddev:12.20 (9.0%) stddev-sans-first:12.68 17:34:53 INFO - PID 4324 | Values: 139.0 117.0 140.0 143.0 127.0 129.0 121.0 131.0 118.0 155.0 17:34:53 INFO - PID 4324 | 17:34:53 INFO - PID 4324 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:175.60 Median:176.50 stddev:7.86 (4.5%) stddev-sans-first:8.18 17:34:53 INFO - PID 4324 | Values: 180.0 167.0 170.0 171.0 192.0 169.0 171.0 181.0 182.0 173.0 17:34:53 INFO - PID 4324 | 17:34:53 INFO - PID 4324 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.20 Median:65.00 stddev:1.62 (2.5%) stddev-sans-first:1.72 17:34:53 INFO - PID 4324 | Values: 65.0 65.0 66.0 64.0 64.0 69.0 66.0 65.0 65.0 63.0 17:34:53 INFO - PID 4324 | -------- Summary: end -------- 17:34:53 INFO - PID 4324 | 17:34:53 INFO - PID 4324 | 17:34:53 INFO - PID 4324 | ###!!! [Parent][MessageChannel] Error: (msgtype=0x150082,name=PBrowser::Msg_Destroy) Closed channel: cannot send/recv 17:34:53 INFO - PID 4324 | 17:34:53 INFO - PID 4324 | 17:34:53 INFO - PID 4324 | ###!!! [Child][MessageChannel] Error: (msgtype=0x150001,name=PBrowser::Msg_AsyncMessage) Closed channel: cannot send/recv 17:34:53 INFO - PID 4324 | 17:34:54 INFO - PID 4324 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 17:34:54 INFO - TEST-INFO | 4324: exit 0 17:34:54 INFO - TEST-OK | kraken | took 64113ms 17:34:54 INFO - SUITE-END | took 1182s 17:34:54 INFO - Completed test suite (00:19:42) 17:34:54 INFO - javascript benchmark 17:34:54 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8484.200864669316, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3251.8048917064993, 3264.987853765333, 3276.9508457373126, 3306.09082502312, 3311.9668803311965, 6233.875322493547, 6249.125122482856, 6259.906101408476, 6308.4322410983, 6322.715477803498, 6084.72618732157, 6130.325664176937, 6223.0354295084335, 6227.034809604507, 6255.343188965161, 5821.592488525805, 5838.824835254949, 5843.123531470271, 5851.882962340751, 5858.472737453629, 5543.889122217553, 5714.3999880012625, 5774.220480235177, 5815.069588865782, 5825.0, 5433.402325744165, 5702.743376548024, 5737.426257374271, 5817.854553636151, 5865.560082993771, 5647.068233741469, 5651.039323315005, 5659.717014149276, 5741.110127930177, 5746.942530574683, 5667.4615911488345, 5668.093105103205, 5668.404817494145, 5686.630369026041, 5696.857578560528, 6110.114033465167, 6188.81433556994, 6221.688915554249, 6224.564280500367, 6228.844278893019, 6643.269240383554, 6694.0, 6709.0, 6739.764108256237, 6815.727370905158, 6438.903416448757, 6596.109525214083, 6598.93401065988, 6763.5941843489545, 6771.4582833373215, 6132.540059495511, 6142.692865356715, 6155.0, 6159.4456498915315, 6234.875302493925, 6724.394804467622, 6809.080774095532, 6897.62063086525, 6903.068085808452, 6903.240643529208, 6538.738450461929, 6569.671516424255, 6575.967120164369, 6578.2106147261675, 6597.670116494252, 6661.833454163588, 6685.298043705438, 6688.732450702015, 6688.933110668929, 6697.8995315070315, 6554.180727409074, 6644.069830223772, 6649.434798042221, 6654.50091243149, 6655.267920528738, 6617.272100068989, 6704.631245281556, 6733.528652994341, 6784.151981002375, 6796.76211332601, 5292.126799078186, 5339.5728341732565, 5378.2470454136455, 5379.300690910157, 5401.459854014645, 4556.3165525170825, 4605.0559635274685, 4625.213713668684, 4652.860414187579, 4673.953260467351, 5840.649561026352, 5868.149118377812, 5912.3496415394275, 5921.171036054956, 5927.318358388754, 6731.596104233761, 6753.425958793459, 6756.594604323756, 6806.217285012287, 6815.591064536144, 5670.971645141748, 5883.911741323884, 5941.792037278675, 5949.405059494016, 5968.731407086691, 5706.71466426677, 5793.159991801165, 5793.188953546507, 5857.531397488191, 5859.5019423349495, 6362.9045564316575, 6436.066770318278, 6499.317571655003, 6550.2794692583775, 6562.278149403466, 6732.730690772314, 6777.525573210025, 6795.490338224752, 6806.659667016728, 6822.829429264308, 6458.418742313214, 6508.544401892012, 6558.147440832661, 6578.80263592093, 6593.571417857824, 6608.1079054328975, 6628.0, 6684.56281899394, 6725.024871393718, 6755.290694477206, 6635.867282654416, 6732.091167692299, 6769.255381908085, 6808.863822723419, 6823.727050918106, 6353.96823015882, 6454.903176452263, 6459.063435801689, 6534.575252608565, 6615.66921653925, 6544.0, 6619.139511863427, 6631.270560238467, 6638.90041649366, 6641.0, 6453.1610890583925, 6467.514936379696, 6476.773312934025, 6481.319461456479, 6559.0817285581015, 6555.115059466911, 6580.8354791130605, 6603.537752357289, 6654.633995130314, 6715.0, 6355.332690067661, 6368.617882927039, 6412.39082287182, 6421.93578064232, 6462.515311351762], "value": 6122.401224532378, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [835.8119423129797, 847.6312803930294, 864.0711235421921, 881.8809460722803, 885.0441523154989, 39586.406203906925, 40730.7963460183, 40744.79627601865, 40908.0, 40978.7951060245, 38004.23991520168, 38128.23743525128, 38190.61809381905, 38221.0, 38348.0, 6961.965190174054, 7083.468739844507, 7096.822579435529, 7097.929020709778, 7109.751158709446, 6991.89512157318, 7078.64606769662, 7081.716731330741, 7084.504084714072, 7101.218865924744, 7047.612381319051, 7632.160462349138, 7874.212578742108, 7882.408819338544, 7944.126146123922, 7773.0672319321475, 7853.136155022943, 7861.174576669425, 7880.645370958292, 7909.644066017015, 7610.124835643916, 7691.307782299564, 7742.574158421285, 7814.0, 7818.726344577913, 36281.81859090714, 36835.447468287995, 37065.0, 37070.25859482822, 37234.44148337777, 3329.883454079096, 3346.263821959165, 3350.3466823969334, 3352.094934367707, 3366.764326497146, 30069.548956765666, 30119.247018824484, 30153.396932061456, 30201.69798302011, 30365.544516832266, 5137.691738495665, 5141.948580514222, 5146.408163061258, 5150.742462876841, 5194.402643695986, 207785.0, 209157.0, 211123.0, 212166.93916530474, 212355.0, 48674.756626216644, 49007.264891026665, 49029.754851225516, 49171.262431063566, 49186.754066230154, 52806.20790688143, 53176.0, 53901.46098538965, 54018.729906350214, 54194.0, 15203.391864325304, 15219.0, 15345.539633811004, 15347.15590642503, 15429.305681244146, 12524.24854508714, 12579.0, 12601.243925364508, 12602.0, 12612.054095942842, 14021.789673154912, 14107.929460352838, 14172.503962361474, 14175.858241417454, 14310.49913253031, 5730.512906402919, 5789.2763404574425, 5814.244148260699, 5858.648481091148, 5926.585139040226, 938.5823308627595, 988.5255077562811, 1005.9094681478703, 1012.6860673191334, 1025.2259544044348, 22538.661920071212, 22864.65703014456, 23167.652485212737, 23199.0, 23295.3011409658, 1012.1750773119884, 1085.478970094359, 1096.9616063437902, 1104.917131215162, 1111.8165502691966, 11915.987141093105, 12267.815982760265, 15473.76209903205, 15573.299201535958, 15763.68472630541, 225301.0, 225334.0, 227891.0, 228086.0, 228125.0, 47267.527324727, 48159.0, 54815.72592137093, 55186.724066379415, 56023.71988140115, 1419.2690764256415, 1424.3732757586836, 1428.2073449235565, 1428.5714285714243, 1431.2772050114625, 26600.334991625365, 26916.86541567358, 27143.0, 27144.864275678494, 27297.72702272952, 37355.0, 37618.24763504769, 37787.244255114194, 37827.0, 38052.809735951145, 209882.0, 210780.0, 210950.0, 211375.0, 211842.0, 32451.513227302083, 34177.82911085428, 34422.31155376929, 34583.4812477808, 34623.82688086644, 41644.37533437061, 42547.787261063495, 43115.7844210777, 43120.78439607782, 43186.56813431825, 30707.692923071376, 30963.0, 31148.68851311458, 31277.218069548442, 31390.843045784626, 30087.0, 30147.0, 30500.389992199587, 31042.0, 31158.68841311558, 35373.115672108404, 35389.11527211737, 38309.0, 39224.2155156901, 39309.21381572296, 32563.34873302568, 32894.34211315712, 32977.505337420436, 33259.83370083134, 33770.155746106546, 23265.18571849977, 24093.0, 24685.62971555392, 25181.748182517942, 25206.74793252044], "value": 17172.73853746175, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [536.9167778994255, 541.8022421816036, 543.9184122381645, 545.9699716515593, 571.5513322042198, 21619.567608647838, 22164.556708865814, 22196.55606887863, 22204.666929996063, 22266.332010039703, 20891.0, 22766.0, 22786.316410507712, 22828.771712282873, 22883.542329153406, 6539.08452816606, 6718.790617688814, 6720.731170753164, 6733.0, 6741.191057073133, 5991.940080599204, 6592.604443733391, 6594.3075977022445, 6642.601443913357, 6665.566738162004, 6502.2197336319705, 6511.544191906569, 6538.0519824625635, 6554.80335589933, 6569.638669873155, 6455.4835613150835, 6550.443212326958, 6562.475001999828, 6569.868602627945, 6580.276169621337, 5998.070299103646, 6045.12345709874, 6050.062240352752, 6051.0923361496125, 6079.969600152015, 26722.331941701417, 27174.728252717614, 27461.45077098447, 27749.722502774915, 28256.717432825615, 2000.779914209436, 2027.858049936505, 2030.6649402848511, 2032.4004418696372, 2052.5381789097482, 18711.15799788999, 19327.0, 19399.90300048505, 19507.317243896538, 19643.705344419846, 2404.0864471500718, 2409.116427495056, 2445.1075357494565, 2479.665245191894, 2488.9004439822384, 136414.0, 138535.30732346376, 138862.0, 138916.0, 139304.30347848195, 14001.569615444281, 14129.293535323193, 14419.35112919921, 14553.92723036378, 15093.773593396108, 22169.22407715722, 22423.775762242494, 22492.325230243117, 22571.661425078637, 22687.0, 9499.335046546676, 9634.084761947604, 9665.178459830853, 9722.708318750449, 9794.755131121636, 8795.164459376349, 8847.955760221157, 8865.113488651083, 8956.68651597191, 9019.549022548847, 7613.352865006425, 8619.956900215459, 8620.439671421338, 8819.382643215042, 9234.215091717278, 2473.319837044827, 2563.948721025569, 2565.987170064138, 2624.357032527005, 2706.2693072870607, 942.5569982108398, 946.5551190940247, 949.8385274503213, 951.2259635779222, 966.7583104223944, 18699.25202991865, 19485.8051419484, 19891.701624475645, 19936.401907942785, 20003.499912502302, 999.4203362049993, 1015.5227043289642, 1017.0846238385512, 1029.0584115534202, 1030.3354336452946, 10503.212259080447, 10728.517216725266, 10824.891751082389, 10854.67435976922, 10943.4528273586, 457834.0, 469732.0, 477949.0, 481067.0, 486667.0, 110606.0, 131188.0, 133101.33449332693, 134710.0, 134837.0, 1204.8491459454237, 1219.1100496637328, 1228.7726770547476, 1231.8398608180878, 1237.5730373021293, 19508.31720889762, 20250.189992400137, 20503.0, 20723.067461963947, 20963.0, 59427.40572594219, 63349.36650633619, 63553.0, 63843.042354363795, 64420.35579644331, 80705.0, 81627.0, 82235.5888220555, 82356.0, 82575.58712206641, 19718.704219437008, 20253.392398228076, 20271.59456810826, 20321.59356812885, 20323.69514457314, 22700.545989079794, 22982.0, 23039.193628222933, 23147.76852231456, 23259.651105233097, 17492.82507174912, 17621.11894405249, 17899.19453624616, 17908.0, 17965.820341796414, 16674.0, 16970.151492425575, 17299.135043247534, 17330.39343622967, 17400.303987840853, 17833.0, 19052.618947621246, 19102.044897755335, 19209.90395048016, 19361.903190483958, 19788.0, 19807.0, 19935.900320498306, 20031.499212519222, 20291.492712682302, 18212.81787182164, 18311.90844045771, 18390.356337528123, 18437.907810460863, 18454.81545184531], "value": 12145.388317380912, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1259.9811002834958, 1290.9999999999998, 1297.403194530516, 1304.634702283361, 1307.2875282970786, 14045.36795844187, 14641.267936603168, 14651.047681900669, 14668.559943201695, 14669.559913202622, 15065.47270845518, 15211.239438028082, 15342.616434589141, 15344.539663810076, 15362.231888405591, 2652.3634327761392, 2668.119520558211, 2702.594610808375, 2709.322669332667, 2719.2657982344754, 2637.0, 2659.335166208448, 2693.2189664997127, 2721.6189733437336, 2723.2647185259966, 2763.9723602764016, 2787.7212278772163, 2795.2872017635473, 2797.8181418207855, 2803.0889960762734, 2673.42521357909, 2699.2442116207494, 2703.2836298380944, 2710.7153748856285, 2733.248356701903, 2642.40545877178, 2645.854478003709, 2658.096247275916, 2668.132856821541, 2669.2392668089697, 14400.351984160634, 14583.125012499178, 14789.11265324084, 14797.112173269637, 14797.704045919021, 7350.705971761123, 7583.924160758377, 7607.847843043163, 7622.733204337877, 7631.084269887593, 5267.315249017642, 5422.43064478228, 5440.211169380457, 5555.527780138693, 5568.610197286192, 1866.878652887558, 1873.9250429982785, 1890.1210936914263, 1905.0568420211446, 1912.024867317664, 24828.0, 25033.749662503506, 25051.0, 25056.0, 25114.623280650805, 6611.206655201407, 6719.529632925648, 6758.560693554903, 6916.896246556305, 6926.480513961373, 16434.096124713018, 16455.6708865822, 16523.33906643745, 16540.172991350384, 16558.503244902673, 7596.696132154762, 7804.297613214838, 7970.322522585529, 8169.591520423955, 8182.590870456573, 7108.857822843514, 7166.713331466787, 7206.882933145371, 7290.307420795016, 7292.817679558054, 5092.465291144451, 5138.100832354323, 5226.529612334908, 5257.553107985787, 5334.0665383557725, 1367.705943222192, 1372.835259768834, 1489.3149151390264, 1525.1764047414495, 1537.2851623994895, 878.9736307910773, 886.499127992682, 902.3007169443603, 908.3369140527452, 911.2209061252534, 9143.405678630868, 9199.954000229956, 9353.485558294358, 9386.296027797942, 9466.857997130048, 938.0759951447818, 973.4597298499401, 981.6613268422383, 983.8573406856109, 989.0356902020616, 5266.762995665128, 5323.334583177103, 5430.049741295257, 5462.688954650935, 5492.862678433071, 19736.70394944077, 19829.0, 20470.283540076027, 20591.8970405147, 20828.791712082988, 19620.0, 20258.594828103356, 20266.493337666383, 20307.375409967462, 20352.18591256363, 10222.182225421948, 10469.790604187874, 10515.737106572396, 10628.840567391495, 10923.890761092447, 7881.330086942674, 7934.325582325567, 8105.392095592736, 8108.189181081844, 8113.18868113184, 16670.333186672397, 17364.652706945537, 17496.95018298906, 17690.292388304322, 17707.822921770618, 17698.380556680455, 17762.11189440497, 17798.377056803467, 17822.5544361392, 17967.281308747504, 10852.294600850919, 10902.236843421202, 10903.509342079784, 11021.779564408507, 11025.944870275598, 13169.407376667881, 13210.075294729277, 13217.603471895858, 13513.0, 13546.93226533861, 9959.302848800515, 10106.343087699275, 10692.518836652525, 10785.946070269598, 11219.382933938712, 10238.846417303896, 10246.333988290737, 10529.262951593315, 10715.35707857531, 10813.351198928089, 9101.40840845343, 9212.216961558343, 9256.814863702823, 9363.719088427359, 9366.906330936603, 7355.483387459405, 7494.775156745306, 7500.549967001997, 7504.249575042452, 7509.023826902468, 7619.31426171625, 7656.80857978555, 7670.463067585216, 7749.031371078615, 7781.144074151725], "value": 6544.652340669177, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [504.4199170953401, 507.65225820313077, 519.1226826662939, 524.9580033597313, 531.0891820527796, 6283.528735344845, 6378.872422551546, 6386.616802991818, 6398.488120950325, 6418.42234198922, 6111.847203819907, 6216.440520353167, 6231.563790534665, 6274.184356033709, 6311.3688631136865, 5167.586593072555, 5213.983273261715, 5219.686818790885, 5225.399079105894, 5231.712255825928, 5163.432022477525, 5181.844544663647, 5182.248573956774, 5229.7385130743505, 5273.156294992802, 4754.215554433515, 5155.664881782691, 5194.662346947455, 5217.713025783581, 5248.580113590922, 4931.65478416511, 4998.925016124761, 5037.345145131109, 5040.243963405482, 5053.873653158664, 4442.333649952533, 4460.598546130864, 4468.865934021952, 4472.664550158751, 4489.1919454498175, 5921.763129474816, 6101.267847858241, 6127.356627554087, 6132.570720049598, 6183.6598987055695, 5683.318001839764, 5723.57073219506, 5755.65466072037, 5796.942030579725, 5811.0, 6581.93418065818, 6618.867622647568, 6625.138731964862, 6633.966830165867, 6656.36764507371, 4811.927821082687, 4888.680056384756, 4898.118338699032, 4902.142125128124, 4924.285978533093, 6719.563228390188, 6901.930980690229, 6931.480138989596, 6971.965140174267, 6998.825029374256, 4878.658493905393, 4974.104661160954, 5026.623003274769, 5058.190689489664, 5067.721275329893, 5056.241563765391, 5067.771950262247, 5073.213651883964, 5138.665986710852, 5143.305653736697, 5681.886362272731, 5698.6580805151, 5702.8289151325525, 5709.086546152679, 5714.79998200061, 6479.319671434431, 6597.571157874723, 6635.601863888182, 6661.766838160641, 6680.799576012728, 1737.9131043447776, 1764.6941263464944, 1766.5760217547952, 1787.3297513432462, 1787.7497150398954, 1158.4014060596323, 1207.824865394513, 1214.8663646998823, 1246.0903540415547, 1248.9063320251018, 5826.446487583673, 5840.430210707275, 5906.629094470061, 5913.379095194942, 5929.377415371324, 6762.188537375547, 6792.0, 6799.898001529981, 6840.11078559794, 6878.621675807879, 5829.154772557956, 5887.264091988502, 5893.675847828325, 5900.675462849498, 5913.629231338096, 3553.6091029986683, 3604.9098772530897, 3620.4931309616672, 3622.438522029037, 3640.8543658253902, 6447.323031081763, 6498.675066246668, 6581.703823327962, 6627.608202277575, 6634.014693387899, 6897.3447522485285, 6950.54821436605, 6961.389478945395, 7028.2620324866175, 7032.507724459341, 6144.262688477412, 6477.967610161919, 6478.870422591522, 6532.216134063943, 6619.702113404908, 6533.379328963741, 6605.339466053356, 6675.7997260082275, 6706.698198580978, 6756.6959486822225, 6688.464922806066, 6718.227403848425, 6745.9662701686175, 6751.156105486814, 6783.152105986752, 6495.2855185928565, 6544.298482394516, 6562.5406221564035, 6634.867302654016, 6642.435392991457, 4972.756810797301, 4988.476209997898, 4993.975030124971, 4999.475055119306, 5041.2438134280155, 6632.436242919213, 6632.701528431136, 6796.504768950823, 6917.481188910952, 6956.0, 6335.809925702236, 6424.775132870327, 6445.25879523842, 6663.900041499284, 6676.132102826601, 6450.580712253689, 6495.967520162558, 6509.349065093453, 6575.769848055295, 6630.5027122965, 6419.967900160469, 6448.161738973903, 6495.610263384212, 6510.674466276572, 6516.218053833571, 6501.902471463027, 6585.473162147113, 6633.583704392146, 6637.834054148685, 6661.500387470862, 6425.742970281136, 6428.421442070049, 6429.7428102875365, 6450.580712253689, 6500.822224554827], "value": 5103.735601845493, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [803.7709252862935, 805.3195050182594, 821.4085858182107, 880.0847118996244, 898.2723993565211, 15589.766153507706, 15905.045697258158, 18303.99999999998, 18806.717899231524, 18945.43163705091, 18338.816611833878, 20181.39455816328, 20208.292709755126, 20302.69545956812, 20404.387868363934, 931.687884558672, 944.6363150187193, 947.9194268487187, 948.5020364308724, 950.7860731335463, 903.5933829776594, 907.3557773980482, 953.4708236928502, 953.8759961205034, 964.5948701545333, 992.8808542974817, 1006.8238058339798, 1007.0332480818423, 1007.471077684218, 1008.4856723071213, 986.1814693804125, 1005.708344580071, 1005.8843233028223, 1007.0584003956251, 1007.4912169354502, 721.3219573600799, 729.923358047408, 731.1628185727312, 735.2463724682243, 736.0835759479429, 18073.27706891723, 18736.0, 18781.0, 18900.905495472434, 19045.0, 19829.50426239341, 20649.690254646193, 21016.0, 21127.04928278231, 21243.893780530998, 16736.58158546034, 16972.151392430453, 16987.915060424617, 16997.745033824503, 17080.31678732849, 3674.5223120994365, 3720.1815600567834, 3726.6273372662786, 3736.0286325555553, 3745.325841348556, 122878.0, 124888.0, 125142.0, 128475.35762321221, 132904.0, 16591.0, 16847.915760421365, 16940.830591693924, 17204.741928871077, 17228.91385543064, 28598.14205573836, 29852.0, 30033.0, 30041.549376759365, 30067.248318792204, 8896.822063558693, 9043.819123617492, 9046.366754327266, 9049.502277374675, 9057.049009854003, 4110.198511290329, 4175.144095460423, 4188.4136220929095, 4194.2869712149, 4195.832166713359, 4851.393575803025, 5014.272930425141, 5015.623828212899, 5022.573081288131, 5023.572996295356, 950.9667161649309, 976.1409959235968, 977.9608815647437, 978.3445091788519, 978.8335982882785, 676.8307923019245, 678.5657179405184, 688.0161369242032, 715.6493318274113, 719.7085180501905, 12164.452599633038, 12309.0, 12618.55835045769, 13250.403731832092, 13316.0, 686.0943554508002, 688.9483288753263, 691.5574032619028, 696.0568429777679, 712.9073220481304, 5814.0, 5845.912311315334, 5867.237259156283, 5907.822765317047, 5959.642421454727, 108040.0, 108977.0, 109440.0, 113188.43405782919, 116845.4157729223, 92199.78002199727, 94975.52512237395, 95816.52091739637, 96933.0, 100566.0, 86158.56920715356, 87387.56306218429, 87834.0, 87873.56063219643, 89664.0, 10608.89391106079, 10728.51721672511, 10748.89251107479, 10910.290831095954, 10929.453527323443, 40745.20011390859, 41255.7937210312, 42159.7892010538, 42327.36508952307, 42465.36301955411, 53172.0, 53625.731871340395, 54169.72915135399, 54179.45820541902, 54837.451625483234, 16113.838861611233, 16269.67460650804, 16281.0, 16364.181790910168, 16586.0, 17551.385701500385, 17838.28646854111, 17847.553811154306, 17858.28566857363, 17966.820331796516, 15817.130057846929, 15840.366385344458, 16022.599435014217, 16118.919405402898, 16136.193190340671, 16496.752548711538, 16607.91696041512, 16640.750388743938, 16832.326706931584, 17076.74384884203, 15902.761458578361, 15940.681186375976, 15959.521214363587, 16093.517194484184, 16317.59206019859, 11644.88355116438, 11727.35499547533, 11954.58158964432, 12026.338551379758, 12237.082218833444, 11528.769424611628, 11652.94173529127, 11822.527098916291, 11849.466773995364, 11922.880771192524], "value": 8743.638343346509, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1537.1000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 113.0, 100.0, 114.0, 111.0, 111.0, 112.0, 105.0, 111.0, 115.0], "value": 109.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 116.0, 154.0, 189.0, 121.0, 145.0, 152.0, 120.0, 176.0, 125.0], "value": 144.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 162.0, 164.0, 163.0, 165.0, 167.0, 166.0, 172.0, 169.0, 164.0], "value": 165.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 87.0, 81.0, 170.0, 81.0, 74.0, 74.0, 98.0, 100.0, 80.0], "value": 92.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 80.0, 130.0, 79.0, 79.0, 79.0, 135.0, 86.0, 81.0, 135.0], "value": 96.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 114.0, 114.0, 114.0, 115.0, 121.0, 124.0, 116.0, 124.0, 115.0], "value": 117.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 152.0, 155.0, 153.0, 155.0, 148.0, 148.0, 150.0, 156.0, 156.0], "value": 152.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 96.0, 100.0, 94.0, 95.0, 94.0, 94.0, 94.0], "value": 94.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 52.0, 51.0, 55.0, 53.0, 52.0, 52.0, 52.0, 55.0, 52.0], "value": 52.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 58.0, 56.0, 56.0, 56.0, 56.0, 60.0, 56.0, 62.0, 57.0], "value": 57.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 77.0, 76.0, 89.0, 87.0, 81.0, 90.0, 74.0, 83.0, 76.0], "value": 81.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 117.0, 140.0, 143.0, 127.0, 129.0, 121.0, 131.0, 118.0, 155.0], "value": 132.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [180.0, 167.0, 170.0, 171.0, 192.0, 169.0, 171.0, 181.0, 182.0, 173.0], "value": 175.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 66.0, 64.0, 64.0, 69.0, 66.0, 65.0, 65.0, 63.0], "value": 65.2, "unit": "score"}]}]} 17:34:54 INFO - Return code: 0 17:34:54 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 17:34:54 INFO - # TBPL SUCCESS # 17:34:54 INFO - Running post-action listener: _package_coverage_data 17:34:54 INFO - Running post-action listener: _resource_record_post_action 17:34:54 INFO - [mozharness: 2017-08-26 00:34:54.898741Z] Finished run-tests step (success) 17:34:54 INFO - Running post-run listener: _resource_record_post_run 17:34:54 INFO - Total resource usage - Wall time: 1201s; CPU: 13.0%; Read bytes: 12996608; Write bytes: 427175936; Read time: 6048; Write time: 368712 17:34:54 INFO - TinderboxPrint: CPU usage
13.3% 17:34:54 INFO - TinderboxPrint: I/O read bytes / time
12,996,608 / 6,048 17:34:54 INFO - TinderboxPrint: I/O write bytes / time
427,175,936 / 368,712 17:34:54 INFO - TinderboxPrint: CPU idle
8,296.5 (86.7%) 17:34:54 INFO - TinderboxPrint: CPU user
1,203.5 (12.6%) 17:34:54 INFO - TinderboxPrint: Swap in / out
0 / 0 17:34:54 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 105332736; Read time: 0; Write time: 73280 17:34:54 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 17:34:55 INFO - run-tests - Wall time: 1183s; CPU: 13.0%; Read bytes: 10625024; Write bytes: 291717120; Read time: 5140; Write time: 290592 17:34:55 INFO - Running post-run listener: _upload_blobber_files 17:34:55 INFO - Blob upload gear active. 17:34:55 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:34:55 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:34:55 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:34:55 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:34:55 INFO - (blobuploader) - INFO - Open directory for files ... 17:34:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 17:34:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:34:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:34:57 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 17:34:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:34:57 INFO - (blobuploader) - INFO - Done attempting. 17:34:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 17:34:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:34:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:34:58 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 17:34:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:34:58 INFO - (blobuploader) - INFO - Done attempting. 17:34:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 17:34:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:34:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:35:06 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 17:35:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:35:06 INFO - (blobuploader) - INFO - Done attempting. 17:35:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 17:35:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:35:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:35:07 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 17:35:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:35:07 INFO - (blobuploader) - INFO - Done attempting. 17:35:07 INFO - (blobuploader) - INFO - Iteration through files over. 17:35:07 INFO - Return code: 0 17:35:07 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:35:07 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:35:07 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3c5dbe586d62953d88748a7b23b4c14aaf977a3c268e5c4bf28cabb52290986810a25722513ea1deb8824d55f43546cc2d0d6280c7d1c208a9dde04e18b77766", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2733487724c9724ee8aa7013002bbf1abff602f8822799f731b66c808b5019168b700219d6e2f219a19cf3a86ee6ff9a719a87891c918c141db37caf8b96cf76", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b3c39e5901833db848ceed3b8f8c0e998aa29ac4e7e35df488af8f6d32d38e5ffc4f422c20a74199bc12cfa52982420a1f0a796f0cd023b83b859fcc35604010", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8652cc9741178c361e53bbea2a16a4849286363e3427f13619254808cf10cca2afdaa9c0859059ca0c2a2c00da5e7eef2e9d48d433035cd14755a2abe7b10f1d"} 17:35:07 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:35:07 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:35:07 INFO - Contents: 17:35:07 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3c5dbe586d62953d88748a7b23b4c14aaf977a3c268e5c4bf28cabb52290986810a25722513ea1deb8824d55f43546cc2d0d6280c7d1c208a9dde04e18b77766", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2733487724c9724ee8aa7013002bbf1abff602f8822799f731b66c808b5019168b700219d6e2f219a19cf3a86ee6ff9a719a87891c918c141db37caf8b96cf76", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b3c39e5901833db848ceed3b8f8c0e998aa29ac4e7e35df488af8f6d32d38e5ffc4f422c20a74199bc12cfa52982420a1f0a796f0cd023b83b859fcc35604010", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8652cc9741178c361e53bbea2a16a4849286363e3427f13619254808cf10cca2afdaa9c0859059ca0c2a2c00da5e7eef2e9d48d433035cd14755a2abe7b10f1d"} 17:35:07 INFO - Running post-run listener: copy_logs_to_upload_dir 17:35:07 INFO - Copying logs to upload dir... 17:35:07 INFO - mkdir: /builds/slave/test/build/upload/logs 17:35:07 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1267.326089 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2017-08-25 17:35:07.208505) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-25 17:35:07.212117) ========= 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=97facb8ba2ac28944cc45e6b000001d4-1503706434.912044-1353726718 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3c5dbe586d62953d88748a7b23b4c14aaf977a3c268e5c4bf28cabb52290986810a25722513ea1deb8824d55f43546cc2d0d6280c7d1c208a9dde04e18b77766", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2733487724c9724ee8aa7013002bbf1abff602f8822799f731b66c808b5019168b700219d6e2f219a19cf3a86ee6ff9a719a87891c918c141db37caf8b96cf76", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b3c39e5901833db848ceed3b8f8c0e998aa29ac4e7e35df488af8f6d32d38e5ffc4f422c20a74199bc12cfa52982420a1f0a796f0cd023b83b859fcc35604010", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8652cc9741178c361e53bbea2a16a4849286363e3427f13619254808cf10cca2afdaa9c0859059ca0c2a2c00da5e7eef2e9d48d433035cd14755a2abe7b10f1d"} build_url:https://queue.taskcluster.net/v1/task/CvxZbX7nQM278U8qCtb6OQ/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011253 build_url: 'https://queue.taskcluster.net/v1/task/CvxZbX7nQM278U8qCtb6OQ/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3c5dbe586d62953d88748a7b23b4c14aaf977a3c268e5c4bf28cabb52290986810a25722513ea1deb8824d55f43546cc2d0d6280c7d1c208a9dde04e18b77766", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2733487724c9724ee8aa7013002bbf1abff602f8822799f731b66c808b5019168b700219d6e2f219a19cf3a86ee6ff9a719a87891c918c141db37caf8b96cf76", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b3c39e5901833db848ceed3b8f8c0e998aa29ac4e7e35df488af8f6d32d38e5ffc4f422c20a74199bc12cfa52982420a1f0a796f0cd023b83b859fcc35604010", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8652cc9741178c361e53bbea2a16a4849286363e3427f13619254808cf10cca2afdaa9c0859059ca0c2a2c00da5e7eef2e9d48d433035cd14755a2abe7b10f1d"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-25 17:35:07.239235) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-25 17:35:07.239603) ========= 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=97facb8ba2ac28944cc45e6b000001d4-1503706434.912044-1353726718 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004396 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-25 17:35:07.323762) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-25 17:35:07.324085) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-25 17:35:07.324453) ========= ========= Total master_lag: 0.22 =========