builder: mozilla-central_ubuntu64_hw_qr_test-dromaeojs-e10s slave: talos-linux64-ix-066 starttime: 1503579376.84 results: success (0) revision: 892c8916ba32b7733e06bfbfdd4083ffae3ca028 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-08-24 05:56:16.840598) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-08-24 05:56:16.841043) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-24 05:56:16.841339) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-24 05:56:16.857321) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-24 05:56:16.857633) ========= 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=82c74bd3bc9a11c5833d2010000001e6-1503579376.400570-620425387 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-08-24 05:56:17-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 39.1M=0s 2017-08-24 05:56:17 (39.1 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.120178 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-24 05:56:16.993115) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-24 05:56:16.993460) ========= 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=82c74bd3bc9a11c5833d2010000001e6-1503579376.400570-620425387 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016735 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-24 05:56:17.044413) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-08-24 05:56:17.044794) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 892c8916ba32b7733e06bfbfdd4083ffae3ca028 --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 892c8916ba32b7733e06bfbfdd4083ffae3ca028 --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=82c74bd3bc9a11c5833d2010000001e6-1503579376.400570-620425387 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-08-24 05:56:17,347 truncating revision to first 12 chars 2017-08-24 05:56:17,347 Setting DEBUG logging. 2017-08-24 05:56:17,347 attempt 1/10 2017-08-24 05:56:17,347 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/892c8916ba32?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-08-24 05:56:17,836 unpacking tar archive at: mozilla-central-892c8916ba32/testing/mozharness/ program finished with exit code 0 elapsedTime=1.376060 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-08-24 05:56:18.437609) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-24 05:56:18.438156) ========= script_repo_revision: 892c8916ba32b7733e06bfbfdd4083ffae3ca028 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-24 05:56:18.438797) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-24 05:56:18.439241) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-24 05:56:18.453152) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 57 secs) (at 2017-08-24 05:56:18.453750) ========= /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=82c74bd3bc9a11c5833d2010000001e6-1503579376.400570-620425387 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 05:56:18 INFO - MultiFileLogger online at 20170824 05:56:18 in /builds/slave/test 05:56:18 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 05:56:18 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:56:18 INFO - {'append_to_log': False, 05:56:18 INFO - 'base_work_dir': '/builds/slave/test', 05:56:18 INFO - 'blob_upload_branch': 'Firefox', 05:56:18 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:56:18 INFO - 'branch': 'Firefox', 05:56:18 INFO - 'buildbot_json_path': 'buildprops.json', 05:56:18 INFO - 'code_coverage': False, 05:56:18 INFO - 'config_files': ('talos/linux_config.py',), 05:56:18 INFO - 'default_actions': ('clobber', 05:56:18 INFO - 'read-buildbot-config', 05:56:18 INFO - 'download-and-extract', 05:56:18 INFO - 'populate-webroot', 05:56:18 INFO - 'create-virtualenv', 05:56:18 INFO - 'install', 05:56:18 INFO - 'setup-mitmproxy', 05:56:18 INFO - 'run-tests'), 05:56:18 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:56:18 INFO - 'disable_ccov_upload': False, 05:56:18 INFO - 'download_minidump_stackwalk': True, 05:56:18 INFO - 'download_symbols': 'ondemand', 05:56:18 INFO - 'enable_stylo': False, 05:56:18 INFO - 'enable_webrender': False, 05:56:18 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 05:56:18 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:56:18 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:56:18 INFO - 'gecko_profile': False, 05:56:18 INFO - 'gecko_profile_interval': 0, 05:56:18 INFO - 'installer_path': 'installer.exe', 05:56:18 INFO - 'log_level': 'info', 05:56:18 INFO - 'log_name': 'talos', 05:56:18 INFO - 'log_to_console': True, 05:56:18 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 05:56:18 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 05:56:18 INFO - 'opt_config_files': (), 05:56:18 INFO - 'pip_index': False, 05:56:18 INFO - 'suite': 'dromaeojs-e10s', 05:56:18 INFO - 'system_bits': '32', 05:56:18 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:56:18 INFO - 'title': 'talos-linux64-ix-066', 05:56:18 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:56:18 INFO - 'use_talos_json': True, 05:56:18 INFO - 'verify': 'False', 05:56:18 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:56:18 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:56:18 INFO - 'work_dir': 'build'} 05:56:18 INFO - [mozharness: 2017-08-24 12:56:18.894857Z] Running clobber step. 05:56:18 INFO - Running pre-action listener: _resource_record_pre_action 05:56:18 INFO - Running main action method: clobber 05:56:18 INFO - rmtree: /builds/slave/test/build 05:56:18 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:56:20 INFO - Running post-action listener: _resource_record_post_action 05:56:20 INFO - [mozharness: 2017-08-24 12:56:20.505855Z] Finished clobber step (success) 05:56:20 INFO - [mozharness: 2017-08-24 12:56:20.505954Z] Running read-buildbot-config step. 05:56:20 INFO - Running pre-action listener: _resource_record_pre_action 05:56:20 INFO - Running main action method: read_buildbot_config 05:56:20 INFO - Using buildbot properties: 05:56:20 INFO - { 05:56:20 INFO - "project": "", 05:56:20 INFO - "product": "firefox", 05:56:20 INFO - "who": "ffxbld@noreply.mozilla.org", 05:56:20 INFO - "installer_path": "public/build/target.tar.bz2", 05:56:20 INFO - "builddir": "mozilla-central_ubuntu64_hw_qr_test-dromaeojs-e10s", 05:56:20 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 05:56:20 INFO - "buildername": "Ubuntu HW 12.04 x64 qr mozilla-central talos dromaeojs-e10s", 05:56:20 INFO - "stage_platform": "linux64-qr", 05:56:20 INFO - "basedir": "/builds/slave/test", 05:56:20 INFO - "buildnumber": 14, 05:56:20 INFO - "platform": "ubuntu64_hw_qr", 05:56:20 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 05:56:20 INFO - "slavebuilddir": "test", 05:56:20 INFO - "taskId": "Sfv0DLgGTimXUhf-8xY-Lg", 05:56:20 INFO - "branch": "mozilla-central", 05:56:20 INFO - "script_repo_revision": "production", 05:56:20 INFO - "revision": "892c8916ba32b7733e06bfbfdd4083ffae3ca028", 05:56:20 INFO - "slavename": "talos-linux64-ix-066", 05:56:20 INFO - "repo_path": "mozilla-central" 05:56:20 INFO - } 05:56:20 INFO - Finding installer, test and symbols from parent task. 05:56:20 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Sfv0DLgGTimXUhf-8xY-Lg'}, attempt #1 05:56:21 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Sfv0DLgGTimXUhf-8xY-Lg'}, attempt #1 05:56:21 INFO - Task dependencies: ZAmByIIwSJKylSbIRUWeYQ 05:56:21 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ZAmByIIwSJKylSbIRUWeYQ'}, attempt #1 05:56:21 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/ZAmByIIwSJKylSbIRUWeYQ/artifacts/public/build/target.tar.bz2 05:56:21 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/ZAmByIIwSJKylSbIRUWeYQ/artifacts/public/build/target.test_packages.json 05:56:21 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/ZAmByIIwSJKylSbIRUWeYQ/artifacts/public/build/target.crashreporter-symbols.zip 05:56:21 INFO - Running post-action listener: _resource_record_post_action 05:56:21 INFO - [mozharness: 2017-08-24 12:56:21.932073Z] Finished read-buildbot-config step (success) 05:56:21 INFO - [mozharness: 2017-08-24 12:56:21.932290Z] Running download-and-extract step. 05:56:21 INFO - Running pre-action listener: _resource_record_pre_action 05:56:21 INFO - Running main action method: download_and_extract 05:56:21 INFO - mkdir: /builds/slave/test/build/tests 05:56:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:56:21 INFO - trying https://queue.taskcluster.net/v1/task/ZAmByIIwSJKylSbIRUWeYQ/artifacts/public/build/target.test_packages.json 05:56:21 INFO - Downloading https://queue.taskcluster.net/v1/task/ZAmByIIwSJKylSbIRUWeYQ/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 05:56:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ZAmByIIwSJKylSbIRUWeYQ/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 05:56:22 INFO - Downloaded 989 bytes. 05:56:22 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 05:56:22 INFO - Using the following test package requirements: 05:56:22 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 05:56:22 INFO - u'common': [u'target.common.tests.zip'], 05:56:22 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 05:56:22 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 05:56:22 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 05:56:22 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 05:56:22 INFO - u'mozbase': [u'target.common.tests.zip'], 05:56:22 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 05:56:22 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 05:56:22 INFO - u'web-platform': [u'target.common.tests.zip', 05:56:22 INFO - u'target.web-platform.tests.tar.gz'], 05:56:22 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 05:56:22 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 05:56:22 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ZAmByIIwSJKylSbIRUWeYQ/artifacts/public/build/target.common.tests.zip 05:56:22 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ZAmByIIwSJKylSbIRUWeYQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 05:56:22 INFO - Fetch https://queue.taskcluster.net/v1/task/ZAmByIIwSJKylSbIRUWeYQ/artifacts/public/build/target.common.tests.zip into memory 05:56:23 INFO - Content-Length response header: 54979621 05:56:23 INFO - Bytes received: 54979621 05:56:34 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 05:56:34 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ZAmByIIwSJKylSbIRUWeYQ/artifacts/public/build/target.common.tests.zip 05:56:34 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ZAmByIIwSJKylSbIRUWeYQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 05:56:34 INFO - Fetch https://queue.taskcluster.net/v1/task/ZAmByIIwSJKylSbIRUWeYQ/artifacts/public/build/target.common.tests.zip into memory 05:56:35 INFO - Content-Length response header: 54979621 05:56:35 INFO - Bytes received: 54979621 05:56:47 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ZAmByIIwSJKylSbIRUWeYQ/artifacts/public/build/target.talos.tests.zip 05:56:47 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ZAmByIIwSJKylSbIRUWeYQ/artifacts/public/build/target.talos.tests.zip'}, attempt #1 05:56:47 INFO - Fetch https://queue.taskcluster.net/v1/task/ZAmByIIwSJKylSbIRUWeYQ/artifacts/public/build/target.talos.tests.zip into memory 05:56:48 INFO - Content-Length response header: 14496384 05:56:48 INFO - Bytes received: 14496384 05:56:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:56:48 INFO - trying https://queue.taskcluster.net/v1/task/ZAmByIIwSJKylSbIRUWeYQ/artifacts/public/build/target.tar.bz2 05:56:48 INFO - Downloading https://queue.taskcluster.net/v1/task/ZAmByIIwSJKylSbIRUWeYQ/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 05:56:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ZAmByIIwSJKylSbIRUWeYQ/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 05:56:49 INFO - Downloaded 60928178 bytes. 05:56:49 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ZAmByIIwSJKylSbIRUWeYQ/artifacts/public/build/target.tar.bz2 05:56:49 INFO - mkdir: /builds/slave/test/properties 05:56:49 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:56:49 INFO - Writing to file /builds/slave/test/properties/build_url 05:56:49 INFO - Contents: 05:56:49 INFO - build_url:https://queue.taskcluster.net/v1/task/ZAmByIIwSJKylSbIRUWeYQ/artifacts/public/build/target.tar.bz2 05:56:49 INFO - Running post-action listener: _resource_record_post_action 05:56:49 INFO - Running post-action listener: find_tests_for_verification 05:56:49 INFO - Running post-action listener: set_extra_try_arguments 05:56:49 INFO - [mozharness: 2017-08-24 12:56:49.864154Z] Finished download-and-extract step (success) 05:56:49 INFO - [mozharness: 2017-08-24 12:56:49.864251Z] Running populate-webroot step. 05:56:49 INFO - Running pre-action listener: _resource_record_pre_action 05:56:49 INFO - Running main action method: populate_webroot 05:56:49 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:56:49 INFO - 'chromez-stylo-e10s': {'talos_options': ['--stylo'], 05:56:49 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:56:49 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:56:49 INFO - 'dromaeojs-stylo-e10s': {'talos_options': ['--stylo'], 05:56:49 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:56:49 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 05:56:49 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:56:49 INFO - 'g1-stylo-e10s': {'pagesets_name': 'tp5n.zip', 05:56:49 INFO - 'talos_options': ['--stylo'], 05:56:49 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:56:49 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 05:56:49 INFO - 'tests': ['damp', 'tps']}, 05:56:49 INFO - 'g2-stylo-e10s': {'pagesets_name': 'tp5n.zip', 05:56:49 INFO - 'talos_options': ['--stylo'], 05:56:49 INFO - 'tests': ['damp', 'tps']}, 05:56:49 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:56:49 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 05:56:49 INFO - 'g4-stylo-e10s': {'talos_options': ['--stylo'], 05:56:49 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 05:56:49 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 05:56:49 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 05:56:49 INFO - 'g5-stylo-e10s': {'pagesets_name': 'tp5n.zip', 05:56:49 INFO - 'talos_options': ['--stylo'], 05:56:49 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 05:56:49 INFO - 'other-e10s': {'tests': ['a11yr', 05:56:49 INFO - 'ts_paint', 05:56:49 INFO - 'tpaint', 05:56:49 INFO - 'sessionrestore', 05:56:49 INFO - 'sessionrestore_many_windows', 05:56:49 INFO - 'sessionrestore_no_auto_restore', 05:56:49 INFO - 'tabpaint']}, 05:56:49 INFO - 'other-stylo-e10s': {'talos_options': ['--stylo'], 05:56:49 INFO - 'tests': ['a11yr', 05:56:49 INFO - 'ts_paint', 05:56:49 INFO - 'tpaint', 05:56:49 INFO - 'sessionrestore', 05:56:49 INFO - 'sessionrestore_many_windows', 05:56:49 INFO - 'sessionrestore_no_auto_restore', 05:56:49 INFO - 'tabpaint']}, 05:56:49 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 05:56:49 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 05:56:49 INFO - 'perf-reftest-singletons-stylo-e10s': {'talos_options': ['--stylo'], 05:56:49 INFO - 'tests': ['bloom_basic_singleton']}, 05:56:49 INFO - 'perf-reftest-stylo-e10s': {'talos_options': ['--stylo'], 05:56:49 INFO - 'tests': ['bloom_basic']}, 05:56:49 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:56:49 INFO - 'tsvgr_opacity', 05:56:49 INFO - 'tart', 05:56:49 INFO - 'tscrollx', 05:56:49 INFO - 'tsvg_static']}, 05:56:49 INFO - 'svgr-stylo-e10s': {'talos_options': ['--stylo'], 05:56:49 INFO - 'tests': ['tsvgx', 05:56:49 INFO - 'tsvgr_opacity', 05:56:49 INFO - 'tart', 05:56:49 INFO - 'tscrollx', 05:56:49 INFO - 'tsvg_static']}, 05:56:49 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 05:56:49 INFO - 'tp5o-stylo-e10s': {'pagesets_name': 'tp5n.zip', 05:56:49 INFO - 'talos_options': ['--stylo'], 05:56:49 INFO - 'tests': ['tp5o']}, 05:56:49 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 05:56:49 INFO - 'talos_options': ['--mitmproxy', 05:56:49 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 05:56:49 INFO - '--firstNonBlankPaint'], 05:56:49 INFO - 'tests': ['tp6_google', 05:56:49 INFO - 'tp6_youtube', 05:56:49 INFO - 'tp6_amazon', 05:56:49 INFO - 'tp6_facebook']}, 05:56:49 INFO - 'tp6-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 05:56:49 INFO - 'talos_options': ['--stylo', 05:56:49 INFO - '--mitmproxy', 05:56:49 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 05:56:49 INFO - '--firstNonBlankPaint'], 05:56:49 INFO - 'tests': ['tp6_google', 05:56:49 INFO - 'tp6_youtube', 05:56:49 INFO - 'tp6_amazon', 05:56:49 INFO - 'tp6_facebook']}, 05:56:49 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 05:56:49 INFO - 'talos_options': ['--stylo', 05:56:49 INFO - '--stylo-threads=1', 05:56:49 INFO - '--mitmproxy', 05:56:49 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 05:56:49 INFO - '--firstNonBlankPaint'], 05:56:49 INFO - 'tests': ['tp6_google', 05:56:49 INFO - 'tp6_youtube', 05:56:49 INFO - 'tp6_amazon', 05:56:49 INFO - 'tp6_facebook']}, 05:56:49 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 05:56:49 INFO - 'talos_options': ['--xperf_path', 05:56:49 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:56:49 INFO - 'tests': ['tp5n']}, 05:56:49 INFO - 'xperf-stylo-e10s': {'pagesets_name': 'tp5n.zip', 05:56:49 INFO - 'talos_options': ['--stylo', 05:56:49 INFO - '--xperf_path', 05:56:49 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:56:49 INFO - 'tests': ['tp5n']}}} 05:56:49 INFO - Running post-action listener: _resource_record_post_action 05:56:49 INFO - [mozharness: 2017-08-24 12:56:49.878399Z] Finished populate-webroot step (success) 05:56:49 INFO - [mozharness: 2017-08-24 12:56:49.878488Z] Running create-virtualenv step. 05:56:49 INFO - Running pre-action listener: _resource_record_pre_action 05:56:49 INFO - Running main action method: create_virtualenv 05:56:49 INFO - Creating virtualenv /builds/slave/test/build/venv 05:56:49 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 05:56:49 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 05:56:49 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 05:56:49 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:56:49 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:56:50 INFO - Installing distribute.............................................................................................................................................................................................done. 05:56:50 INFO - Installing pip...............done. 05:56:50 INFO - Return code: 0 05:56:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 05:56:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 05:56:50 INFO - Reading from file tmpfile_stdout 05:56:50 INFO - Output received: 05:56:50 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 05:56:50 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:56: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')]} 05:56:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:56: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')]} 05:56:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:56:50 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 0x29ab1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b76a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2d19f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d17130>, '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 0x2d18030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d184c0>, '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': '82c74bd3bc9a11c5833d2010000001e6-1503579376.400570-620425387', '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 05:56:50 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 05:56:50 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 05:56:50 INFO - Using env: {'DISPLAY': ':0', 05:56:50 INFO - 'HOME': '/home/cltbld', 05:56:50 INFO - 'LANG': 'en_US.UTF-8', 05:56:50 INFO - 'LANGUAGE': 'en_US:en', 05:56:50 INFO - 'LOGNAME': 'cltbld', 05:56:50 INFO - 'MAIL': '/var/mail/cltbld', 05:56:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:56:50 INFO - 'MOZ_NO_REMOTE': '1', 05:56:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:56:50 INFO - 'NO_EM_RESTART': '1', 05:56:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:56:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:56:50 INFO - 'PWD': '/builds/slave/test', 05:56:50 INFO - 'SHELL': '/bin/bash', 05:56:50 INFO - 'SHLVL': '1', 05:56:50 INFO - 'TERM': 'linux', 05:56:50 INFO - 'TMOUT': '86400', 05:56:50 INFO - 'USER': 'cltbld', 05:56:50 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1503579376.400570-620425387', 05:56:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:56:50 INFO - '_': '/tools/buildbot/bin/python'} 05:56:52 INFO - Ignoring indexes: http://pypi.python.org/simple/ 05:56:52 INFO - Downloading/unpacking pip>=1.5 05:56:52 INFO - Running setup.py egg_info for package pip 05:56:52 INFO - warning: no files found matching 'pip/cacert.pem' 05:56:52 INFO - warning: no files found matching '*.html' under directory 'docs' 05:56:52 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 05:56:52 INFO - no previously-included directories found matching 'docs/_build/_sources' 05:56:52 INFO - Installing collected packages: pip 05:56:52 INFO - Found existing installation: pip 1.1 05:56:52 INFO - Uninstalling pip: 05:56:52 INFO - Successfully uninstalled pip 05:56:52 INFO - Running setup.py install for pip 05:56:52 INFO - warning: no files found matching 'pip/cacert.pem' 05:56:52 INFO - warning: no files found matching '*.html' under directory 'docs' 05:56:52 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 05:56:52 INFO - no previously-included directories found matching 'docs/_build/_sources' 05:56:52 INFO - Installing pip script to /builds/slave/test/build/venv/bin 05:56:52 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 05:56:52 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 05:56:52 INFO - Successfully installed pip 05:56:52 INFO - Cleaning up... 05:56:52 INFO - Return code: 0 05:56:52 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:56:52 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')]} 05:56:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:56:52 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')]} 05:56:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:56:52 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 0x29ab1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b76a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2d19f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d17130>, '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 0x2d18030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d184c0>, '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': '82c74bd3bc9a11c5833d2010000001e6-1503579376.400570-620425387', '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 05:56:52 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 05:56:52 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 05:56:52 INFO - Using env: {'DISPLAY': ':0', 05:56:52 INFO - 'HOME': '/home/cltbld', 05:56:52 INFO - 'LANG': 'en_US.UTF-8', 05:56:52 INFO - 'LANGUAGE': 'en_US:en', 05:56:52 INFO - 'LOGNAME': 'cltbld', 05:56:52 INFO - 'MAIL': '/var/mail/cltbld', 05:56:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:56:52 INFO - 'MOZ_NO_REMOTE': '1', 05:56:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:56:52 INFO - 'NO_EM_RESTART': '1', 05:56:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:56:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:56:52 INFO - 'PWD': '/builds/slave/test', 05:56:52 INFO - 'SHELL': '/bin/bash', 05:56:52 INFO - 'SHLVL': '1', 05:56:52 INFO - 'TERM': 'linux', 05:56:52 INFO - 'TMOUT': '86400', 05:56:52 INFO - 'USER': 'cltbld', 05:56:52 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1503579376.400570-620425387', 05:56:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:56:52 INFO - '_': '/tools/buildbot/bin/python'} 05:56:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:56:52 INFO - Downloading/unpacking psutil>=3.1.1 05:56:52 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 05:56:52 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 05:56:52 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 05:56:52 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 05:56:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:56:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:56:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:56:53 INFO - Installing collected packages: psutil 05:56:53 INFO - Running setup.py install for psutil 05:56:53 INFO - building 'psutil._psutil_linux' extension 05:56:53 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 05:56:53 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 05:56:53 INFO - building 'psutil._psutil_posix' extension 05:56:53 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 05:56:53 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 05:56:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:56:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:56:54 INFO - Successfully installed psutil 05:56:54 INFO - Cleaning up... 05:56:54 INFO - Return code: 0 05:56:54 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:56:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:56:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:56:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:56:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:56:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29ab1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b76a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2d19f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d17130>, '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 0x2d18030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d184c0>, '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': '82c74bd3bc9a11c5833d2010000001e6-1503579376.400570-620425387', '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 05:56:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 05:56:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 05:56:54 INFO - Using env: {'DISPLAY': ':0', 05:56:54 INFO - 'HOME': '/home/cltbld', 05:56:54 INFO - 'LANG': 'en_US.UTF-8', 05:56:54 INFO - 'LANGUAGE': 'en_US:en', 05:56:54 INFO - 'LOGNAME': 'cltbld', 05:56:54 INFO - 'MAIL': '/var/mail/cltbld', 05:56:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:56:54 INFO - 'MOZ_NO_REMOTE': '1', 05:56:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:56:54 INFO - 'NO_EM_RESTART': '1', 05:56:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:56:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:56:54 INFO - 'PWD': '/builds/slave/test', 05:56:54 INFO - 'SHELL': '/bin/bash', 05:56:54 INFO - 'SHLVL': '1', 05:56:54 INFO - 'TERM': 'linux', 05:56:54 INFO - 'TMOUT': '86400', 05:56:54 INFO - 'USER': 'cltbld', 05:56:54 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1503579376.400570-620425387', 05:56:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:56:54 INFO - '_': '/tools/buildbot/bin/python'} 05:56:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:56:54 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:56:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:56:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:56:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:56:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:56:55 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:56:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:56:55 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:56:55 INFO - Installing collected packages: mozsystemmonitor 05:56:55 INFO - Running setup.py install for mozsystemmonitor 05:56:55 INFO - Successfully installed mozsystemmonitor 05:56:55 INFO - Cleaning up... 05:56:55 INFO - Return code: 0 05:56:55 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:56:55 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')]} 05:56:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:56:55 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')]} 05:56:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:56:55 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 0x29ab1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b76a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2d19f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d17130>, '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 0x2d18030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d184c0>, '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': '82c74bd3bc9a11c5833d2010000001e6-1503579376.400570-620425387', '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 05:56:55 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 05:56:55 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 05:56:55 INFO - Using env: {'DISPLAY': ':0', 05:56:55 INFO - 'HOME': '/home/cltbld', 05:56:55 INFO - 'LANG': 'en_US.UTF-8', 05:56:55 INFO - 'LANGUAGE': 'en_US:en', 05:56:55 INFO - 'LOGNAME': 'cltbld', 05:56:55 INFO - 'MAIL': '/var/mail/cltbld', 05:56:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:56:55 INFO - 'MOZ_NO_REMOTE': '1', 05:56:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:56:55 INFO - 'NO_EM_RESTART': '1', 05:56:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:56:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:56:55 INFO - 'PWD': '/builds/slave/test', 05:56:55 INFO - 'SHELL': '/bin/bash', 05:56:55 INFO - 'SHLVL': '1', 05:56:55 INFO - 'TERM': 'linux', 05:56:55 INFO - 'TMOUT': '86400', 05:56:55 INFO - 'USER': 'cltbld', 05:56:55 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1503579376.400570-620425387', 05:56:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:56:55 INFO - '_': '/tools/buildbot/bin/python'} 05:56:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:56:55 INFO - Downloading/unpacking jsonschema==2.5.1 05:56:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:56:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:56:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:56:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:56:56 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:56:56 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:56:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:56:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:56:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:56:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:56:56 INFO - Downloading functools32-3.2.3-2.tar.gz 05:56:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:56:57 INFO - warning: no files found matching '*.txt' 05:56:57 INFO - no previously-included directories found matching 'build' 05:56:57 INFO - no previously-included directories found matching 'dist' 05:56:57 INFO - no previously-included directories found matching '.git*' 05:56:57 INFO - Installing collected packages: jsonschema, functools32 05:56:57 INFO - Running setup.py install for functools32 05:56:57 INFO - warning: no files found matching '*.txt' 05:56:57 INFO - no previously-included directories found matching 'build' 05:56:57 INFO - no previously-included directories found matching 'dist' 05:56:57 INFO - no previously-included directories found matching '.git*' 05:56:57 INFO - Successfully installed jsonschema functools32 05:56:57 INFO - Cleaning up... 05:56:57 INFO - Return code: 0 05:56:57 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:56:57 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')]} 05:56:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:56:57 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')]} 05:56:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:56:57 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 0x29ab1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b76a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2d19f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d17130>, '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 0x2d18030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d184c0>, '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': '82c74bd3bc9a11c5833d2010000001e6-1503579376.400570-620425387', '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 05:56:57 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 05:56:57 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 05:56:57 INFO - Using env: {'DISPLAY': ':0', 05:56:57 INFO - 'HOME': '/home/cltbld', 05:56:57 INFO - 'LANG': 'en_US.UTF-8', 05:56:57 INFO - 'LANGUAGE': 'en_US:en', 05:56:57 INFO - 'LOGNAME': 'cltbld', 05:56:57 INFO - 'MAIL': '/var/mail/cltbld', 05:56:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:56:57 INFO - 'MOZ_NO_REMOTE': '1', 05:56:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:56:57 INFO - 'NO_EM_RESTART': '1', 05:56:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:56:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:56:57 INFO - 'PWD': '/builds/slave/test', 05:56:57 INFO - 'SHELL': '/bin/bash', 05:56:57 INFO - 'SHLVL': '1', 05:56:57 INFO - 'TERM': 'linux', 05:56:57 INFO - 'TMOUT': '86400', 05:56:57 INFO - 'USER': 'cltbld', 05:56:57 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1503579376.400570-620425387', 05:56:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:56:57 INFO - '_': '/tools/buildbot/bin/python'} 05:56:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:56:57 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:56:57 INFO - Cleaning up... 05:56:57 INFO - Return code: 0 05:56:57 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:56:57 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')]} 05:56:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:56:57 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')]} 05:56:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:56:57 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 0x29ab1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b76a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2d19f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d17130>, '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 0x2d18030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d184c0>, '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': '82c74bd3bc9a11c5833d2010000001e6-1503579376.400570-620425387', '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 05:56:57 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 05:56:57 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 05:56:57 INFO - Using env: {'DISPLAY': ':0', 05:56:57 INFO - 'HOME': '/home/cltbld', 05:56:57 INFO - 'LANG': 'en_US.UTF-8', 05:56:57 INFO - 'LANGUAGE': 'en_US:en', 05:56:57 INFO - 'LOGNAME': 'cltbld', 05:56:57 INFO - 'MAIL': '/var/mail/cltbld', 05:56:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:56:57 INFO - 'MOZ_NO_REMOTE': '1', 05:56:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:56:57 INFO - 'NO_EM_RESTART': '1', 05:56:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:56:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:56:57 INFO - 'PWD': '/builds/slave/test', 05:56:57 INFO - 'SHELL': '/bin/bash', 05:56:57 INFO - 'SHLVL': '1', 05:56:57 INFO - 'TERM': 'linux', 05:56:57 INFO - 'TMOUT': '86400', 05:56:57 INFO - 'USER': 'cltbld', 05:56:57 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1503579376.400570-620425387', 05:56:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:56:57 INFO - '_': '/tools/buildbot/bin/python'} 05:56:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:56:57 INFO - Downloading/unpacking blobuploader==1.2.4 05:56:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:56:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:56:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:56:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:56:58 INFO - Downloading blobuploader-1.2.4.tar.gz 05:56:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:56:58 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:56:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:56:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:56:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:56:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:56:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:56:59 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:56:59 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 05:56:59 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 05:56:59 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 05:56:59 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 05:56:59 INFO - Downloading docopt-0.6.1.tar.gz 05:56:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:56:59 INFO - Installing collected packages: blobuploader, requests, docopt 05:56:59 INFO - Running setup.py install for blobuploader 05:56:59 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:56:59 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:56:59 INFO - Running setup.py install for requests 05:56:59 INFO - Running setup.py install for docopt 05:56:59 INFO - Successfully installed blobuploader requests docopt 05:56:59 INFO - Cleaning up... 05:56:59 INFO - Return code: 0 05:56:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:56:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:56:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:56:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:56:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:56:59 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 0x29ab1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b76a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2d19f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d17130>, '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 0x2d18030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d184c0>, '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': '82c74bd3bc9a11c5833d2010000001e6-1503579376.400570-620425387', '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 05:56:59 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 05:56:59 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 05:56:59 INFO - Using env: {'DISPLAY': ':0', 05:56:59 INFO - 'HOME': '/home/cltbld', 05:56:59 INFO - 'LANG': 'en_US.UTF-8', 05:56:59 INFO - 'LANGUAGE': 'en_US:en', 05:56:59 INFO - 'LOGNAME': 'cltbld', 05:56:59 INFO - 'MAIL': '/var/mail/cltbld', 05:56:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:56:59 INFO - 'MOZ_NO_REMOTE': '1', 05:56:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:56:59 INFO - 'NO_EM_RESTART': '1', 05:56:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:56:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:56:59 INFO - 'PWD': '/builds/slave/test', 05:56:59 INFO - 'SHELL': '/bin/bash', 05:56:59 INFO - 'SHLVL': '1', 05:56:59 INFO - 'TERM': 'linux', 05:56:59 INFO - 'TMOUT': '86400', 05:56:59 INFO - 'USER': 'cltbld', 05:56:59 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1503579376.400570-620425387', 05:56:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:56:59 INFO - '_': '/tools/buildbot/bin/python'} 05:57:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:57:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:57:00 INFO - Running setup.py (path:/tmp/pip-JKNnlQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:57:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:57:00 INFO - Running setup.py (path:/tmp/pip-Rr3F7v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:57:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:57:00 INFO - Running setup.py (path:/tmp/pip-nPIBA1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:57:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:57:00 INFO - Running setup.py (path:/tmp/pip-ANJE2N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:57:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:57:00 INFO - Running setup.py (path:/tmp/pip-qTCXPh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:57:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:57:00 INFO - Running setup.py (path:/tmp/pip-L4abvM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:57:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:57:00 INFO - Running setup.py (path:/tmp/pip-aX_R1j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:57:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:57:00 INFO - Running setup.py (path:/tmp/pip-lbJ05l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:57:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:57:00 INFO - Running setup.py (path:/tmp/pip-GPPohy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:57:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:57:00 INFO - Running setup.py (path:/tmp/pip-wnI1e3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:57:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:57:00 INFO - Running setup.py (path:/tmp/pip-t9FPTJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:57:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:57:00 INFO - Running setup.py (path:/tmp/pip-ehSC1W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:57:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:57:01 INFO - Running setup.py (path:/tmp/pip-y9CN9a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:57:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:57:01 INFO - Running setup.py (path:/tmp/pip-efjABc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:57:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:57:01 INFO - Running setup.py (path:/tmp/pip-MhsREy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:57:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:57:01 INFO - Running setup.py (path:/tmp/pip-Q0ve5Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:57:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:57:01 INFO - Running setup.py (path:/tmp/pip-B3qLOw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:57:01 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:57:01 INFO - Running setup.py install for manifestparser 05:57:01 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:57:01 INFO - Running setup.py install for mozcrash 05:57:01 INFO - Running setup.py install for mozdebug 05:57:01 INFO - Running setup.py install for mozdevice 05:57:01 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:57:01 INFO - Running setup.py install for mozfile 05:57:01 INFO - Running setup.py install for mozhttpd 05:57:01 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:57:01 INFO - Running setup.py install for mozinfo 05:57:02 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:57:02 INFO - Running setup.py install for mozInstall 05:57:02 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:57:02 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:57:02 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:57:02 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:57:02 INFO - Running setup.py install for mozleak 05:57:02 INFO - Running setup.py install for mozlog 05:57:02 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:57:02 INFO - Running setup.py install for moznetwork 05:57:02 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:57:02 INFO - Running setup.py install for mozprocess 05:57:02 INFO - Running setup.py install for mozprofile 05:57:02 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:57:02 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:57:02 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:57:02 INFO - Running setup.py install for mozrunner 05:57:02 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:57:02 INFO - Running setup.py install for mozscreenshot 05:57:02 INFO - Running setup.py install for moztest 05:57:03 INFO - Running setup.py install for mozversion 05:57:03 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:57:03 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:57:03 INFO - Cleaning up... 05:57:03 INFO - Return code: 0 05:57:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:57:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:57:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:57:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:57:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:57:03 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 0x29ab1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b76a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2d19f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d17130>, '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 0x2d18030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d184c0>, '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': '82c74bd3bc9a11c5833d2010000001e6-1503579376.400570-620425387', '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 05:57:03 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 05:57:03 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 05:57:03 INFO - Using env: {'DISPLAY': ':0', 05:57:03 INFO - 'HOME': '/home/cltbld', 05:57:03 INFO - 'LANG': 'en_US.UTF-8', 05:57:03 INFO - 'LANGUAGE': 'en_US:en', 05:57:03 INFO - 'LOGNAME': 'cltbld', 05:57:03 INFO - 'MAIL': '/var/mail/cltbld', 05:57:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:57:03 INFO - 'MOZ_NO_REMOTE': '1', 05:57:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:57:03 INFO - 'NO_EM_RESTART': '1', 05:57:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:57:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:57:03 INFO - 'PWD': '/builds/slave/test', 05:57:03 INFO - 'SHELL': '/bin/bash', 05:57:03 INFO - 'SHLVL': '1', 05:57:03 INFO - 'TERM': 'linux', 05:57:03 INFO - 'TMOUT': '86400', 05:57:03 INFO - 'USER': 'cltbld', 05:57:03 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1503579376.400570-620425387', 05:57:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:57:03 INFO - '_': '/tools/buildbot/bin/python'} 05:57:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:57:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:57:03 INFO - Running setup.py (path:/tmp/pip-SC477i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:57:03 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)) 05:57:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:57:03 INFO - Running setup.py (path:/tmp/pip-BKi6Tn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:57:03 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)) 05:57:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:57:03 INFO - Running setup.py (path:/tmp/pip-V4z3n6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:57:03 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)) 05:57:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:57:03 INFO - Running setup.py (path:/tmp/pip-ZvtFGl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:57:03 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)) 05:57:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:57:03 INFO - Running setup.py (path:/tmp/pip-jE1IC3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:57:03 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)) 05:57:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:57:03 INFO - Running setup.py (path:/tmp/pip-mItVa1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:57:03 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)) 05:57:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:57:03 INFO - Running setup.py (path:/tmp/pip-QjQED_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:57:03 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)) 05:57:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:57:03 INFO - Running setup.py (path:/tmp/pip-z_x_Ko-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:57:03 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)) 05:57:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:57:03 INFO - Running setup.py (path:/tmp/pip-WndO_O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:57:03 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)) 05:57:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:57:03 INFO - Running setup.py (path:/tmp/pip-kc_9DH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:57:04 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)) 05:57:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:57:04 INFO - Running setup.py (path:/tmp/pip-kImwdU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:57:04 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)) 05:57:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:57:04 INFO - Running setup.py (path:/tmp/pip-XI95mY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:57:04 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)) 05:57:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:57:04 INFO - Running setup.py (path:/tmp/pip-mrK3iu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:57:04 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)) 05:57:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:57:04 INFO - Running setup.py (path:/tmp/pip-HHkTi9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:57:04 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)) 05:57:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:57:04 INFO - Running setup.py (path:/tmp/pip-k3mPST-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:57:04 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)) 05:57:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:57:04 INFO - Running setup.py (path:/tmp/pip-bx0ybI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:57:04 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)) 05:57:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:57:04 INFO - Running setup.py (path:/tmp/pip-CxNhB5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:57:04 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)) 05:57:04 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)) 05:57:04 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)) 05:57:04 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)) 05:57:04 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)) 05:57:04 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)) 05:57:04 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)) 05:57:04 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:57:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:57:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:57:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:57:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:57:05 INFO - Downloading blessings-1.6.tar.gz 05:57:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:57:05 INFO - Installing collected packages: blessings 05:57:05 INFO - Running setup.py install for blessings 05:57:05 INFO - Successfully installed blessings 05:57:05 INFO - Cleaning up... 05:57:05 INFO - Return code: 0 05:57:05 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:57:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:57:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:57:06 INFO - Reading from file tmpfile_stdout 05:57:06 INFO - Current package versions: 05:57:06 INFO - argparse == 1.2.1 05:57:06 INFO - blessings == 1.6 05:57:06 INFO - blobuploader == 1.2.4 05:57:06 INFO - docopt == 0.6.1 05:57:06 INFO - functools32 == 3.2.3-2 05:57:06 INFO - jsonschema == 2.5.1 05:57:06 INFO - manifestparser == 1.1 05:57:06 INFO - mozInstall == 1.13 05:57:06 INFO - mozcrash == 1.0 05:57:06 INFO - mozdebug == 0.1 05:57:06 INFO - mozdevice == 0.51 05:57:06 INFO - mozfile == 1.2 05:57:06 INFO - mozhttpd == 0.7 05:57:06 INFO - mozinfo == 0.10 05:57:06 INFO - mozleak == 0.1 05:57:06 INFO - mozlog == 3.5 05:57:06 INFO - moznetwork == 0.27 05:57:06 INFO - mozprocess == 0.25 05:57:06 INFO - mozprofile == 0.28 05:57:06 INFO - mozrunner == 6.13 05:57:06 INFO - mozscreenshot == 0.1 05:57:06 INFO - mozsystemmonitor == 0.3 05:57:06 INFO - moztest == 0.8 05:57:06 INFO - mozversion == 1.4 05:57:06 INFO - psutil == 3.1.1 05:57:06 INFO - requests == 1.2.3 05:57:06 INFO - wsgiref == 0.1.2 05:57:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:57:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:57:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:57:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:57:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:57:06 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 0x29ab1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b76a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2d19f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d17130>, '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 0x2d18030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d184c0>, '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': '82c74bd3bc9a11c5833d2010000001e6-1503579376.400570-620425387', '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 05:57:06 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 05:57:06 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 05:57:06 INFO - Using env: {'DISPLAY': ':0', 05:57:06 INFO - 'HOME': '/home/cltbld', 05:57:06 INFO - 'LANG': 'en_US.UTF-8', 05:57:06 INFO - 'LANGUAGE': 'en_US:en', 05:57:06 INFO - 'LOGNAME': 'cltbld', 05:57:06 INFO - 'MAIL': '/var/mail/cltbld', 05:57:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:57:06 INFO - 'MOZ_NO_REMOTE': '1', 05:57:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:57:06 INFO - 'NO_EM_RESTART': '1', 05:57:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:57:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:57:06 INFO - 'PWD': '/builds/slave/test', 05:57:06 INFO - 'SHELL': '/bin/bash', 05:57:06 INFO - 'SHLVL': '1', 05:57:06 INFO - 'TERM': 'linux', 05:57:06 INFO - 'TMOUT': '86400', 05:57:06 INFO - 'USER': 'cltbld', 05:57:06 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1503579376.400570-620425387', 05:57:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:57:06 INFO - '_': '/tools/buildbot/bin/python'} 05:57:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:57:06 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)) 05:57:06 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)) 05:57:06 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)) 05:57:06 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)) 05:57:06 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)) 05:57:06 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)) 05:57:06 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)) 05:57:06 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)) 05:57:06 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)) 05:57:06 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 05:57:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:57:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:57:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:57:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:57:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 05:57:07 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)) 05:57:07 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)) 05:57:07 INFO - Installing collected packages: simplejson 05:57:07 INFO - Running setup.py install for simplejson 05:57:07 INFO - building 'simplejson._speedups' extension 05:57:07 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 05:57:08 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 05:57:08 INFO - Successfully installed simplejson 05:57:08 INFO - Cleaning up... 05:57:08 INFO - Return code: 0 05:57:08 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:57:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:57:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:57:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:57:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:57:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29ab1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b76a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2d19f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d17130>, '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 0x2d18030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d184c0>, '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': '82c74bd3bc9a11c5833d2010000001e6-1503579376.400570-620425387', '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 05:57:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 05:57:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 05:57:08 INFO - Using env: {'DISPLAY': ':0', 05:57:08 INFO - 'HOME': '/home/cltbld', 05:57:08 INFO - 'LANG': 'en_US.UTF-8', 05:57:08 INFO - 'LANGUAGE': 'en_US:en', 05:57:08 INFO - 'LOGNAME': 'cltbld', 05:57:08 INFO - 'MAIL': '/var/mail/cltbld', 05:57:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:57:08 INFO - 'MOZ_NO_REMOTE': '1', 05:57:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:57:08 INFO - 'NO_EM_RESTART': '1', 05:57:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:57:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:57:08 INFO - 'PWD': '/builds/slave/test', 05:57:08 INFO - 'SHELL': '/bin/bash', 05:57:08 INFO - 'SHLVL': '1', 05:57:08 INFO - 'TERM': 'linux', 05:57:08 INFO - 'TMOUT': '86400', 05:57:08 INFO - 'USER': 'cltbld', 05:57:08 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1503579376.400570-620425387', 05:57:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:57:08 INFO - '_': '/tools/buildbot/bin/python'} 05:57:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:57:08 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:57:08 INFO - Cleaning up... 05:57:08 INFO - Return code: 0 05:57:08 INFO - Running post-action listener: _resource_record_post_action 05:57:08 INFO - Running post-action listener: _start_resource_monitoring 05:57:08 INFO - Starting resource monitoring. 05:57:08 INFO - [mozharness: 2017-08-24 12:57:08.814643Z] Finished create-virtualenv step (success) 05:57:08 INFO - [mozharness: 2017-08-24 12:57:08.815171Z] Running install step. 05:57:08 INFO - Running pre-action listener: _resource_record_pre_action 05:57:08 INFO - Running main action method: install 05:57:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:57:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:57:09 INFO - Reading from file tmpfile_stdout 05:57:09 INFO - Detecting whether we're running mozinstall >=1.0... 05:57:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:57:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:57:09 INFO - Reading from file tmpfile_stdout 05:57:09 INFO - Output received: 05:57:09 INFO - Usage: mozinstall [options] installer 05:57:09 INFO - Options: 05:57:09 INFO - -h, --help show this help message and exit 05:57:09 INFO - -d DEST, --destination=DEST 05:57:09 INFO - Directory to install application into. [default: 05:57:09 INFO - "/builds/slave/test"] 05:57:09 INFO - --app=APP Application being installed. [default: firefox] 05:57:09 INFO - mkdir: /builds/slave/test/build/application 05:57:09 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'] 05:57:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 05:57:26 INFO - Reading from file tmpfile_stdout 05:57:26 INFO - Output received: 05:57:26 INFO - /builds/slave/test/build/application/firefox/firefox 05:57:26 INFO - Running post-action listener: _resource_record_post_action 05:57:26 INFO - [mozharness: 2017-08-24 12:57:26.784414Z] Finished install step (success) 05:57:26 INFO - [mozharness: 2017-08-24 12:57:26.784698Z] Running setup-mitmproxy step. 05:57:26 INFO - Running pre-action listener: _resource_record_pre_action 05:57:26 INFO - Running main action method: setup_mitmproxy 05:57:26 INFO - Skipping: mitmproxy is not required 05:57:26 INFO - Running post-action listener: _resource_record_post_action 05:57:26 INFO - [mozharness: 2017-08-24 12:57:26.785893Z] Finished setup-mitmproxy step (success) 05:57:26 INFO - [mozharness: 2017-08-24 12:57:26.786112Z] Running run-tests step. 05:57:26 INFO - Running pre-action listener: _resource_record_pre_action 05:57:26 INFO - Running pre-action listener: _set_gcov_prefix 05:57:26 INFO - Running main action method: run_tests 05:57:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:57:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:57:26 INFO - Python 2.7.3 05:57:26 INFO - Return code: 0 05:57:26 INFO - grabbing minidump binary from tooltool 05:57:26 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')]} 05:57:26 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 0x2d17130>, '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 0x2d18030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d184c0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:57:26 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 05:57:26 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 05:57:26 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 05:57:26 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:57:26 INFO - Return code: 0 05:57:26 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 05:57:26 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:57:26 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:57:26 INFO - ENV: RUST_BACKTRACE is now full 05:57:26 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 05:57:26 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:57:26 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/ZAmByIIwSJKylSbIRUWeYQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-066', '--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 05:57:26 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/ZAmByIIwSJKylSbIRUWeYQ/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-066 --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 05:57:26 INFO - Using env: {'DISPLAY': ':0', 05:57:26 INFO - 'HOME': '/home/cltbld', 05:57:26 INFO - 'LANG': 'en_US.UTF-8', 05:57:26 INFO - 'LANGUAGE': 'en_US:en', 05:57:26 INFO - 'LOGNAME': 'cltbld', 05:57:26 INFO - 'MAIL': '/var/mail/cltbld', 05:57:26 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:57:26 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 05:57:26 INFO - 'MOZ_ACCELERATED': '1', 05:57:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:57:26 INFO - 'MOZ_NO_REMOTE': '1', 05:57:26 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:57:26 INFO - 'MOZ_WEBRENDER': '1', 05:57:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:57:26 INFO - 'NO_EM_RESTART': '1', 05:57:26 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:57:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:57:26 INFO - 'PWD': '/builds/slave/test', 05:57:26 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:57:26 INFO - 'RUST_BACKTRACE': 'full', 05:57:26 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 05:57:26 INFO - 'SHELL': '/bin/bash', 05:57:26 INFO - 'SHLVL': '1', 05:57:26 INFO - 'TERM': 'linux', 05:57:26 INFO - 'TMOUT': '86400', 05:57:26 INFO - 'USER': 'cltbld', 05:57:26 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1503579376.400570-620425387', 05:57:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:57:26 INFO - '_': '/tools/buildbot/bin/python'} 05:57:26 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/ZAmByIIwSJKylSbIRUWeYQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-066', '--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 05:57:27 INFO - mozversion application_buildid: 20170824120051 05:57:27 INFO - mozversion application_changeset: 892c8916ba32b7733e06bfbfdd4083ffae3ca028 05:57:27 INFO - mozversion application_display_name: Nightly 05:57:27 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:57:27 INFO - mozversion application_name: Firefox 05:57:27 INFO - mozversion application_remotingname: firefox 05:57:27 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:57:27 INFO - mozversion application_vendor: Mozilla 05:57:27 INFO - mozversion application_version: 57.0a1 05:57:27 INFO - mozversion platform_buildid: 20170824120051 05:57:27 INFO - mozversion platform_changeset: 892c8916ba32b7733e06bfbfdd4083ffae3ca028 05:57:27 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:57:27 INFO - mozversion platform_version: 57.0a1 05:57:27 INFO - using testdate: 1503579447 05:57:27 INFO - actual date: 1503579447 05:57:27 INFO - starting webserver on 'localhost:49474' 05:57:27 INFO - SUITE-START | Running 2 tests 05:57:27 INFO - TEST-START | dromaeo_css 05:57:27 INFO - Initialising browser for dromaeo_css test... 05:57:27 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:49474/getInfo.html -profile /tmp/tmpyTH3mH/profile 05:57:27 INFO - TEST-INFO | started process 13358 (/builds/slave/test/build/application/firefox/firefox http://localhost:49474/getInfo.html) 05:57:33 INFO - TEST-INFO | 13358: exit 0 05:57:34 INFO - Browser initialized. 05:57:34 INFO - Running cycle 1/1 for dromaeo_css test... 05:57:34 INFO - TEST-INFO | started process 13541 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpyTH3mH/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 05:57:34 INFO - PID 13541 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 05:57:35 INFO - PID 13541 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 05:57:35 INFO - PID 13541 | 05:57:35 INFO - PID 13541 | (/builds/slave/test/build/application/firefox/firefox:13600): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 05:57:35 INFO - PID 13541 | 05:57:35 INFO - PID 13541 | 05:57:35 INFO - PID 13541 | (/builds/slave/test/build/application/firefox/firefox:13654): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 05:57:35 INFO - PID 13541 | 05:57:36 INFO - PID 13541 | __metrics Screen width/height:1600/1200 05:57:36 INFO - PID 13541 | colorDepth:24 05:57:36 INFO - PID 13541 | Browser inner width/height: 1024/768 05:57:36 INFO - PID 13541 | __metrics 05:57:45 INFO - PID 13541 | RSS: Main: 202780672 05:57:45 INFO - PID 13541 | 05:57:49 INFO - PID 13541 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 06:00:44 INFO - PID 13541 | Cycle 1(1): loaded http://localhost:49474/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49474/tests/dromaeo/cssquery-ext.html) 06:00:45 INFO - PID 13541 | RSS: Main: 215240704 06:00:45 INFO - PID 13541 | 06:03:47 INFO - PID 13541 | Cycle 1(1): loaded http://localhost:49474/tests/dromaeo/cssquery-ext.html (next: http://localhost:49474/tests/dromaeo/cssquery-jquery.html) 06:03:48 INFO - PID 13541 | RSS: Main: 212389888 06:03:48 INFO - PID 13541 | 06:06:50 INFO - PID 13541 | Cycle 1(1): loaded http://localhost:49474/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49474/tests/dromaeo/cssquery-mootools.html) 06:06:50 INFO - PID 13541 | RSS: Main: 211554304 06:06:50 INFO - PID 13541 | 06:09:52 INFO - PID 13541 | Cycle 1(1): loaded http://localhost:49474/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49474/tests/dromaeo/cssquery-prototype.html) 06:09:53 INFO - PID 13541 | RSS: Main: 215719936 06:09:53 INFO - PID 13541 | 06:12:57 INFO - PID 13541 | Cycle 1(1): loaded http://localhost:49474/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49474/tests/dromaeo/cssquery-yui.html) 06:12:57 INFO - PID 13541 | RSS: Main: 217681920 06:12:57 INFO - PID 13541 | 06:16:00 INFO - PID 13541 | Cycle 1(1): loaded http://localhost:49474/tests/dromaeo/cssquery-yui.html (next: http://localhost:49474/tests/dromaeo/cssquery-dojo.html) 06:16:00 INFO - PID 13541 | RSS: Main: 217411584 06:16:00 INFO - PID 13541 | 06:16:00 INFO - PID 13541 | __start_tp_report 06:16:00 INFO - PID 13541 | _x_x_mozilla_page_load 06:16:00 INFO - PID 13541 | _x_x_mozilla_page_load_details 06:16:00 INFO - PID 13541 | |i|pagename|runs| 06:16:00 INFO - PID 13541 | |0;dojo.html;3182.3317103408267;3220.3881262560126;3271.934561308774;3297.3075654112645;3317.253617935963;6128.877422451559;6135.969320153404;6155.353687862777;6164.568480206387;6181.783637572691;5997.070454079614;6045.304789949158;6063.302720187169;6088.999999999989;6099.5120390368775;5601.3558440779225;5602.859928501779;5625.6905870177125;5638.238837756912;5665.320161580596;5689.573282003846;5715.2855893013375;5718.142278658193;5721.113227449752;5724.398938111501;5700.059490184135;5703.144528320764;5704.800331988381;5705.144228365737;5738.942610573903;5649.152627105926;5717.656940583578;5727.11229759388;5727.255456790632;5738.254026976508;5539.113741801333;5614.831555053355;5677.37548869624;5717.399673034354;5780.277465316835;6017.308009578911;6049.907008662609;6101.145839582462;6137.693115344215;6186.288576813679;6446.194225721785;6476.481881449473;6524.11924390206;6552.901706474407;6553.672316384162;6620.3710647488415;6661.900071498883;6697.732090716366;6742;6745.763898263586;6154.5384096192965;6184.350643182446;6216.036514340284;6257.7184026718905;6262.749490020439;6553.148090748172;6806.93193068063;6813.216480104752;6875.759348422881;6885.896711549331;6330.208723909512;6362.618242905441;6403.783281176561;6409.230892292955;6420.807375778734;6440.194975628046;6457.483401327882;6513.413792758674;6540.70566824494;6573.868522629521;6456.806295811133;6468.256150542654;6528.967355163289;6529.2817790043055;6574.211094668671;6553.737850486023;6614.040964060185;6631.900521492182;6699.430548403343;6704.497162712719;5119.718415487184;5209.453007434241;5241.449647787003;5311.601629877775;5317.122674758699;4565.406497155415;4594.770261486912;4610.538946105363;4629.305604159335;4653.511381304982;5696.8860622787315;5699.059655156852;5754.942450575524;5777.0467872801355;5783.826485205451;6531.248906375733;6536.150300460909;6540.313267106981;6620.602763834186;6628.270890202074;5533.1976863354585;5602.747876345574;5689.544836413077;5790.710464476843;5804;5703.344115426779;5747.597668163188;5751.971240143773;5759.308882934075;5780.248567686174;6455.870882582322;6466.805995820133;6545.214574251216;6559.639219842859;6560.671966401661;6486.708098135501;6539.247986481616;6613.14029176204;6625.17185351831;6724.025016372696;6407.519436042408;6468.353164683494;6516.543841931209;6544.607323560602;6552.115464412243;6368.458681011981;6522.412982831378;6544.116544266653;6553.639549824806;6568.211814582281;6425.8714825704155;6575;6605.438537724347;6700.49746269022;6702.765403210864;6282.403171698681;6340.4293613575;6469.870602587827;6494.09082728409;6511.055896894829;6437.002264648987;6438.935610643834;6454.386833250834;6465.903011454738;6479.416852483299;6272.749090036347;6318.304986451579;6341.27075386318;6445.806625801233;6458.54790164684;6344.619322840645;6392.616443013248;6461.61230326182;6509.609423434609;6542.476601871744;6243.875122497616;6308.211473565804;6309.895768240448;6330.556861019869;6333.429991300805 06:16:00 INFO - PID 13541 | |1;ext.html;804.1355542791497;824.7855557555041;845.6110189312916;865.4158443050943;872.8603423452247;38834.41748373773;39444.013899652484;39458.408123878165;39518.20963580727;39556.20887582246;35861.82069089651;36066;36270.093247668825;36278.45582316261;36348.27303453929;6942.687579058955;7019.82450438738;7041.436685065182;7062.1525416950035;7066.187388450317;6913.066735990652;6923.861522769542;6960.303969603025;6998.125234345707;6998.4751143664425;6975;6977.511574189809;7329.926700732978;7479.813004674872;7495.400367970577;7383.040204773372;7428.925710742931;7438.479306448524;7446.66490007951;7453.329200371965;7594.430417718693;7785.1436342002335;7797.610119494003;7798;7809.492382995144;37040.81479592585;37450;37520.43719344212;37542.62457375446;37549.436758448646;3475.565554305712;3517.2789578136676;3538.8230588470733;3540.132667496454;3542.3800834853796;30732;30802.691973080207;30879.228019299473;31038.84480577605;31096.844515777502;5088.931324421876;5213.635045546814;5229.686218826844;5231.973840130813;5266.4733526647415;161738;173448;173531;173687.13156434262;174842;46538.30192547115;46621.06757864824;46749;46750.29874551884;46767.532324676315;48291.51708482941;48293.75853120712;48441.5155848437;48784.51215487799;48945;16049.197540122947;16216.918915405347;16349.509514714578;16380.918095409446;16402.589935251715;13172.407241674147;13223.47106115764;13323.866761332456;13374;13419.530316439073;13757.243351615558;14016.299185040707;14019.08875923082;14111.364988575539;14311.212883291519;5673.716314184274;5719.771209151588;5744.569157313218;5752.626079304916;5765.682887441231;965.923264980298;996.6113215845826;1001.5242759689235;1004.2468148888333;1025.897410258983;22065.227717029826;22180;22400.775992240197;22685.546289074457;22836.65745013826;1087.244365166202;1088.8257878739364;1095.5782023920847;1099.290957332516;1099.8075336816025;11728.94135529317;11858.584949526725;11954.58158964432;11964.28214307127;12008.459619316976;203408;203886;206029;206214;206574;43825.78087109608;51511;51776.74111629418;51918.22122668163;52055.21917171245;1318.2288361308742;1401.4184113592949;1435.1747745046432;1437.7483940310613;1462.6416527950507;26544;26574.601380978915;26748.465030698888;26801.86599066992;27196;35964.46053309255;36069.27861442809;36106.27787444184;36195.63804361923;36247.45628815517;167961;168554;168662;169143;169673.15163424105;33426.164345890575;33956.830215848764;34743;34793.13017174591;34869.825650871586;43300;44549.10901782011;44655.55344446514;44855.551444485136;44940;31597.77384770681;32354.676453235166;32467;32497.35005299928;32635.18412039718;31902;32069.67930320667;32387.35225295528;32629.510557342135;32631.18422039373;35929.10177245485;35945.1013724659;36008.81995590005;36193.27613447769;36198;31383.215419613778;31418.842905786238;31700.36599268048;31725;32234;23112.191073312355;23511.529769404857;23820.76179238255;23853.64219536743;23938.64092038586 06:16:00 INFO - PID 13541 | |2;jquery.html;557.5818136397702;562.9324481062273;565.3131445293967;569.9772009119635;572.6392372805134;21385.465363365904;22294.108235670552;22356;22386;22427;23081.192158274465;24660.13689520867;25112.74887251127;25270.36824079399;25354;6377.776777812789;6553.737850485975;6554.80335589933;6590.769323073693;6604.207495100594;6508.674566271691;6528.41244288014;6539.607623542579;6554.442872355856;6562.8687426251445;6023.096535519663;6422.678866056702;6452.774152904649;6491.221053473566;6496.870062598745;6536.901946470759;6537.346265373449;6559.245686746014;6569.57297775643;6585.769498067569;6058.575899687024;6065.4844338231305;6075.149479072933;6111.938880611181;6138.570300078996;27085.1874443767;28057;28484.572731409044;28492.857535712188;28506.714932850613;1930.855185861066;1941;1945.046927005771;1964.0965156028244;1967.9409617711349;18574.257029718796;19187.32844350455;19228.903855480774;19247.326343577908;19411.126499307564;2306.169778879602;2339.3215967369442;2401.279616115176;2408.4701365699516;2438.4147804526788;128822.3558882209;133555.99999999904;134609;134791.32604337015;135001;13885.37515811791;14175.503857364944;14245.643858903402;14831.332590033475;15213.31540080699;22578;22580.774192257868;22623;22641;22666.773332266795;9452;9668.468234247042;9833.26250531213;9866.062724041205;9890.752731181634;8859;8924.910750892537;8953.59708813105;9007.23438507721;9106.271498280254;8970.596323165473;9039.63841446348;9072.501012444247;9151.17639412456;9414;2592.1745297625303;2627.9474410511684;2636.973630263711;2665.7067722550505;2731.590261460757;921.9032001639865;943.4999450291219;968.6874285343399;989.1443901025705;989.3223142147652;19063.428097157106;19095.045247737835;19332.613347733248;19711.211551537777;19806.910619915754;1009.0363702663939;1028.6039874648313;1031.097789434245;1037.7820657661825;1043.9217058720626;10682.839757403644;10752.301100428447;10819.918008199274;10822;10832.67501974942;460334;469753;474086;475379;476680;115961;116460;117674;117772.41113794377;118763;1132.3432409202642;1143.9370834604176;1159.8550181227347;1161.1001473857827;1168.1414160591958;16953.660926781642;17029.82970170332;17174.39889603858;17380.47858564245;17709.734353984957;51390.48609514006;52050;52313.738431307596;53351.466485336205;54325;67575;67822;67998.3200167992;68893;69860.30139698742;13718.862811371759;13920.651983700489;14205.928970355082;14218.217998010208;14224.075435096685;22057.33827985099;22209.888950555145;22310.10759569599;22362.217322393637;22565;17406;17447.38934137299;17840;18036.278548858423;18096.72854907151;16064.67870642604;16320.347186112422;16353.219952608322;16601.335946562485;17012.319507219574;18185.45443636693;18471.815281847008;18622.06889655539;18632;18654.34709785151;13852.515161969282;13860.306984650928;13983.930080349533;13986.531414201363;13992;17551.034693092002;17667;17824.287028519233;17934.192961317032;18100.637987240443 06:16:00 INFO - PID 13541 | |3;mootools.html;1300.336828217609;1324.4702119152337;1350.3518311210617;1354.2145555577765;1363.093542794042;13944.511942082028;14362.425502979868;14470.131792092481;14505.129692218474;14506.709865802677;14617.269136543156;15576.221188940535;15636;15665.84334156658;15673.843261567381;2677.06302794022;2750.614913912054;2751.463464624399;2753.903613373532;2762.737539933713;2583.0313632387856;2601.8959241630405;2635.6705411823523;2659.494696007762;2674.3581540430255;2668.5863691127906;2704.634874291965;2723.2919440945298;2728.5947736915505;2731.235254128847;2640.3267166872515;2673.1980405878257;2683.2889284339667;2684.141074856047;2694.7844172466157;2589.2879458148973;2613.2290974162665;2619.816612837102;2626.290901456596;2633.6049592561076;14246.145231286053;14403.423863045466;14461.349239284258;14490.20303883286;14531.491397801132;7732.613369331567;8016.959915200387;8056.113827478973;8056.315213206826;8072.1120676726105;5384.246205531229;5459.481349271852;5546.473085056913;5564.610477266593;5587.944120558783;1848.9075546222634;1857.5913299074182;1870.7661542307212;1874.4564076417823;1907.895065771382;24663.753362466327;24842;24876.751232487626;24881.875590622112;24909.128180513777;6707.463402927754;6708.42978346846;6765.458763299022;6802.693878775467;6890.104286442831;16712.74930876038;16757.41349052777;16779.07715075658;16805.915970420316;16844.073575953196;7514.098308202942;7746.806329841686;7953.483023603448;8221.342292616577;8233.46482478637;7280.235575264627;7332.706691732377;7356.37470814976;7456.291652293024;7459.850802984019;5063.3417655704525;5103.897922041539;5192.61055420845;5220.869478263074;5294.867628309246;1332.4803326702595;1339.4173534512518;1479.2751551739784;1522.2388805597202;1546.6056155680456;877.9692710755093;882.2765332427476;890.9242714369221;896.4397251717677;898.883145191121;8166.510009399455;8300.958495207486;8378.078411374743;8407.747767566983;8490.915090849136;939.7509659940122;946.4936259101348;966.2366730283138;971.9951400242953;979.1677074486772;4485;4559.226518785463;4625.606823419979;4680.7191568506;4685.976570117127;20451.693224601346;20985;21389.144434222457;21420.464488387915;21583.2445864394;19371.225150993803;20725.79274207269;20727.170913163605;20892.059857306456;20933.16267349289;11050.944745276222;11219.887801122048;11228.045616122557;11246.49390777417;11252.043576296106;7569.886451703119;8041.276285134366;8462;8573.91426085756;8761.502971653048;18156.636867262845;18222.36221732233;18246.635067298314;18471.722924155878;18499.81500184981;18319.54201144982;18407;18713.345032923782;18726.438206853818;19450.221991120197;11973.401329933642;12119.878801211875;12151.696207594881;12153.210041347284;12156.75686486283;13540.32298385057;13675.17948923028;13808.861911380758;13902.860971390157;13903.165810051429;9799.314048016571;10834.458277086273;11428.085753139896;11429.885701142883;11589.536418543164;10803.891961080288;11082.722931926766;11091.50088246047;11091.944540277247;11236.887631123584;8987.46075235488;9153.816923661623;9162.908370916206;9165.541722913695;9189.632414703337;7324.48728589015;7329;7335.889961650686;7344.522606030591;7345.3021962913435;7255.52839065459;7283.672234749343;7349.265073492608;7397.371223446067;7482.925170748223 06:16:00 INFO - PID 13541 | |4;prototype.html;497.4144299433639;502.94216165140995;519.794681100965;523.3196844102665;526.3657292961981;6136.631802091866;6246.3753624637575;6299.968500157504;6315.147455093561;6331.92357299259;6091.451769340759;6126.877462450749;6140.1096840958035;6172.567920245585;6248.15649887265;4907.509249075099;4914.778834952435;4947.109520286347;4984.152694042022;5028.421731500887;4884.975575122137;4945.876353091184;4951.3810773653295;4960.826371077013;5000.449950505442;4710.481846996828;4885.755712214375;4904.509549045102;4953.405591329028;4999.250112483121;4885.145099607555;4903.681260718042;4925.507449255082;4956.776945037482;4976.502349765013;4331.436913201263;4363.825446982117;4412.360207769888;4416.977915110404;4465.352523884051;6067.120267561223;6069.663179675857;6091.329953705089;6138.9693051534905;6148;5665.433456654343;5678.971605141989;5682.119271512922;5711.343195532525;5715.399883012307;6431.5819471734185;6459.903101453482;6460.806175814686;6499.837504062436;6614.933850661528;4622.411449588241;4766.73782941938;4772.785224664864;4780.211265141247;4796.704889679767;6781.559198652072;6866.793996180172;6893.4140598049225;6896.551724137858;6907.620080895549;4892.241702536113;4925.335079764186;4926.802927882916;4946.35697359341;4964.429090654548;4965.851024469272;4966.279889416014;4973.850784476472;5042.319286896222;5043.167877300204;5474.974259524001;5492.368377636543;5593.322003398964;5608.242887210256;5615.859603509945;6543;6576.44100251484;6601.174853143357;6615.60306381609;6678.666066696645;1644.0382376309753;1681.5039563328846;1713.4088739384893;1737.7480265361453;1744.6043165467574;1141.520561364229;1141.6860363399965;1188.5661733467396;1192.8270400791837;1205.8664855036238;5654.349749778746;5726.024521540062;5738.942610573924;5746.626469279567;5760;6453.354664533509;6526.510511711545;6611.595028560295;6617.834554136089;6647.900281495781;5781.19063331134;5790.160426738184;5800.564957628195;5805.506531944748;5810.331811841608;3541.4771648191527;3545.3441113393587;3575.1419659281587;3578.499010138583;3580.480830279647;6494.337321698081;6516.641584712792;6556.639834509998;6557.409833114947;6591.110200123018;6819;6890.414314783299;6920.515563910579;6927.411170050602;7000.719971201095;6464.838379040561;6513.609183449103;6541.8691626168165;6542.738290468423;6584.5061620378665;6672.332766723289;6680;6720.428763554957;6735.383507958153;6795.592264464148;6651.600903945779;6679;6694.59832410057;6749.628770417477;6760.966195168992;6522.706478208397;6577.572457790228;6608.933910660832;6613.404793568602;6663.700133493908;4878.63410244226;4911.238757992517;4948.406191257072;4952.678075925053;4959.404871415453;6668;6711.530192886451;6730.764423245163;6735.2927942567285;6772.187337519529;6408.198975128109;6437.549371543947;6457.741690332334;6571.3757193066585;6592.670366481752;6339.714712837841;6499;6537.5750576212395;6556.18047744032;6567.737290508326;6227.906581401192;6321.431071203614;6400.935990640221;6422.421982021454;6521.217453905561;6410.230772307353;6524.739010439529;6533.738650453929;6556.901646475211;6601.867962640817;6295.55931084838;6340.968295158495;6351.523635727432;6374.553781235269;6427.614343139427 06:16:00 INFO - PID 13541 | |5;yui.html;861.8793368928348;886.7384121684103;896.5786080542147;902.5171533229721;914.4193437167398;16907.49277521673;17669.028203448837;17769.111544422776;17809.91095044526;19729.309474168407;16743.16284185792;16775.24511396987;16781.41265055723;16879;16925.830741692578;941.5809964565753;950.7385468996047;957.7174733453613;957.7701351675618;964.0504103458088;874.2175752701329;944.4994153098869;950.2493030505892;951.6431338248157;952.5046975572698;995.3032876986102;1009.4296722351847;1009.8333774927128;1011.1354791653141;1014.7463134216446;988.0761488008735;1003.859459675646;1006.7483129217695;1010.8382658774598;1011.2567263061644;729.3108012927786;729.5914287998738;737.2958824322759;738.6454501839094;739.7521830186894;17245.568860778454;17297.740533892003;17373.044482553454;17404.825951740448;18710.812891871043;20179;20212.89893550523;20780.168793248253;20792.16831326745;20806.375808725763;16587.751183732253;16661.166941652868;16929.407470738464;16944.915275423667;17011.659766804594;3696.7597106188014;3717.8698745544048;3759.9436008459893;3765.1151979284846;3767.416050512175;119931;121509.39245303717;124096.37951810274;131033.34483327523;132886;16490.17549122539;17083.40208092711;17443.56391090233;17481.388151414638;17481.825181748274;30001;30351.241218969262;30355.69644303573;30363.696363036528;30403.543946840815;9292.210162136158;9425.528723563795;9477.952610236905;9483.336166468282;9527.47598882068;4154.044569748975;4253.21315556623;4274.059706864485;4276.722013069203;4280.636145927569;4828.686135401188;5643.943560564342;5690.260266165372;5693.146028095819;5718.370979192286;1048.5700862646431;1079.6599071292517;1085.4464223246043;1103.3490240757992;1106.5684383090602;670.0786418674322;674.20780582815;692.0760784352946;704.3871831506622;704.4012589299055;11477.942610286895;11737.706557335963;11854.885021597533;11992.460339284753;12104.636860894187;686.2759788423261;691.8754624167598;699.5278187223604;703.6305939381865;705.6048612776862;5474.124140137559;5506;5642.548596112301;5679.630823996427;5715.999700052557;103190.48404758079;109093;110565;111916;115232;98912.50543747235;101672.49163754134;102836;103443.4827825856;105414.47292763487;84596;87668;87996.5600171995;89041.55479222562;90485.54757226304;10267.435291059064;10427;10461.47692615351;10613.787724245627;10751.086157676373;39675.2064958705;40092.59907401005;40102.79948600355;40210.1957960845;40411.39382909318;50700.74649626728;50806.74596626993;50930.49069509257;53346.73326633342;68432.6578367105;18272.177752000927;18330.81669183291;18385.63228735391;18871.622567548297;18876;17474;18105.185266662767;18706.906465467586;20712.275070372463;21825.56348872982;19124.235030598622;19377;19462.12420441112;19994.400167994983;20242.291519797327;17984.460466186036;17985.730214046536;18194.818051819842;18306.26774928988;18667.533311667317;19005.23979040878;19476.220951162362;19566.315178968667;19838.90080549588;20203.19187232553;11646.475908584303;12281.324527151093;13362;13602.795958060835;13954.162750235018;14393;14538.636534086732;14624.756895663988;14792;15227.390904364145 06:16:00 INFO - PID 13541 | __end_tp_report 06:16:00 INFO - PID 13541 | __start_cc_report 06:16:00 INFO - PID 13541 | _x_x_mozilla_cycle_collect,358 06:16:00 INFO - PID 13541 | __end_cc_report 06:16:00 INFO - PID 13541 | __startTimestamp1503580560324__endTimestamp 06:16:00 INFO - PID 13541 | 06:16:00 INFO - PID 13541 | ------- Summary: start ------- 06:16:00 INFO - PID 13541 | Number of tests: 6 06:16:00 INFO - PID 13541 | 06:16:00 INFO - PID 13541 | [#0] dojo.html Cycles:165 Average:6077.77 Median:6330.56 stddev:694.68 (11.0%) stddev-sans-first:658.63 06:16:00 INFO - PID 13541 | Values: 3182.3 3220.4 3271.9 3297.3 3317.3 6128.9 6136.0 6155.4 6164.6 6181.8 5997.1 6045.3 6063.3 6089.0 6099.5 5601.4 5602.9 5625.7 5638.2 5665.3 5689.6 5715.3 5718.1 5721.1 5724.4 5700.1 5703.1 5704.8 5705.1 5738.9 5649.2 5717.7 5727.1 5727.3 5738.3 5539.1 5614.8 5677.4 5717.4 5780.3 6017.3 6049.9 6101.1 6137.7 6186.3 6446.2 6476.5 6524.1 6552.9 6553.7 6620.4 6661.9 6697.7 6742.0 6745.8 6154.5 6184.4 6216.0 6257.7 6262.7 6553.1 6806.9 6813.2 6875.8 6885.9 6330.2 6362.6 6403.8 6409.2 6420.8 6440.2 6457.5 6513.4 6540.7 6573.9 6456.8 6468.3 6529.0 6529.3 6574.2 6553.7 6614.0 6631.9 6699.4 6704.5 5119.7 5209.5 5241.4 5311.6 5317.1 4565.4 4594.8 4610.5 4629.3 4653.5 5696.9 5699.1 5754.9 5777.0 5783.8 6531.2 6536.2 6540.3 6620.6 6628.3 5533.2 5602.7 5689.5 5790.7 5804.0 5703.3 5747.6 5752.0 5759.3 5780.2 6455.9 6466.8 6545.2 6559.6 6560.7 6486.7 6539.2 6613.1 6625.2 6724.0 6407.5 6468.4 6516.5 6544.6 6552.1 6368.5 6522.4 6544.1 6553.6 6568.2 6425.9 6575.0 6605.4 6700.5 6702.8 6282.4 6340.4 6469.9 6494.1 6511.1 6437.0 6438.9 6454.4 6465.9 6479.4 6272.7 6318.3 6341.3 6445.8 6458.5 6344.6 6392.6 6461.6 6509.6 6542.5 6243.9 6308.2 6309.9 6330.6 6333.4 06:16:00 INFO - PID 13541 | 06:16:00 INFO - PID 13541 | [#1] ext.html Cycles:180 Average:35158.63 Median:3539.48 stddev:47144.87 (1332.0%) stddev-sans-first:47206.55 06:16:00 INFO - PID 13541 | Values: 804.1 824.8 845.6 865.4 872.9 38834.4 39444.0 39458.4 39518.2 39556.2 35861.8 36066.0 36270.1 36278.5 36348.3 6942.7 7019.8 7041.4 7062.2 7066.2 6913.1 6923.9 6960.3 6998.1 6998.5 6975.0 6977.5 7329.9 7479.8 7495.4 7383.0 7428.9 7438.5 7446.7 7453.3 7594.4 7785.1 7797.6 7798.0 7809.5 37040.8 37450.0 37520.4 37542.6 37549.4 3475.6 3517.3 3538.8 3540.1 3542.4 30732.0 30802.7 30879.2 31038.8 31096.8 5088.9 5213.6 5229.7 5232.0 5266.5 161738.0 173448.0 173531.0 173687.1 174842.0 46538.3 46621.1 46749.0 46750.3 46767.5 48291.5 48293.8 48441.5 48784.5 48945.0 16049.2 16216.9 16349.5 16380.9 16402.6 13172.4 13223.5 13323.9 13374.0 13419.5 13757.2 14016.3 14019.1 14111.4 14311.2 5673.7 5719.8 5744.6 5752.6 5765.7 965.9 996.6 1001.5 1004.2 1025.9 22065.2 22180.0 22400.8 22685.5 22836.7 1087.2 1088.8 1095.6 1099.3 1099.8 11728.9 11858.6 11954.6 11964.3 12008.5 203408.0 203886.0 206029.0 206214.0 206574.0 43825.8 51511.0 51776.7 51918.2 52055.2 1318.2 1401.4 1435.2 1437.7 1462.6 26544.0 26574.6 26748.5 26801.9 27196.0 35964.5 36069.3 36106.3 36195.6 36247.5 167961.0 168554.0 168662.0 169143.0 169673.2 33426.2 33956.8 34743.0 34793.1 34869.8 43300.0 44549.1 44655.6 44855.6 44940.0 31597.8 32354.7 32467.0 32497.4 32635.2 31902.0 32069.7 32387.4 32629.5 32631.2 35929.1 35945.1 36008.8 36193.3 36198.0 31383.2 31418.8 31700.4 31725.0 32234.0 23112.2 23511.5 23820.8 23853.6 23938.6 06:16:00 INFO - PID 13541 | 06:16:00 INFO - PID 13541 | [#2] jquery.html Cycles:180 Average:33699.36 Median:22579.39 stddev:79564.77 (352.4%) stddev-sans-first:79749.06 06:16:00 INFO - PID 13541 | Values: 557.6 562.9 565.3 570.0 572.6 21385.5 22294.1 22356.0 22386.0 22427.0 23081.2 24660.1 25112.7 25270.4 25354.0 6377.8 6553.7 6554.8 6590.8 6604.2 6508.7 6528.4 6539.6 6554.4 6562.9 6023.1 6422.7 6452.8 6491.2 6496.9 6536.9 6537.3 6559.2 6569.6 6585.8 6058.6 6065.5 6075.1 6111.9 6138.6 27085.2 28057.0 28484.6 28492.9 28506.7 1930.9 1941.0 1945.0 1964.1 1967.9 18574.3 19187.3 19228.9 19247.3 19411.1 2306.2 2339.3 2401.3 2408.5 2438.4 128822.4 133556.0 134609.0 134791.3 135001.0 13885.4 14175.5 14245.6 14831.3 15213.3 22578.0 22580.8 22623.0 22641.0 22666.8 9452.0 9668.5 9833.3 9866.1 9890.8 8859.0 8924.9 8953.6 9007.2 9106.3 8970.6 9039.6 9072.5 9151.2 9414.0 2592.2 2627.9 2637.0 2665.7 2731.6 921.9 943.5 968.7 989.1 989.3 19063.4 19095.0 19332.6 19711.2 19806.9 1009.0 1028.6 1031.1 1037.8 1043.9 10682.8 10752.3 10819.9 10822.0 10832.7 460334.0 469753.0 474086.0 475379.0 476680.0 115961.0 116460.0 117674.0 117772.4 118763.0 1132.3 1143.9 1159.9 1161.1 1168.1 16953.7 17029.8 17174.4 17380.5 17709.7 51390.5 52050.0 52313.7 53351.5 54325.0 67575.0 67822.0 67998.3 68893.0 69860.3 13718.9 13920.7 14205.9 14218.2 14224.1 22057.3 22209.9 22310.1 22362.2 22565.0 17406.0 17447.4 17840.0 18036.3 18096.7 16064.7 16320.3 16353.2 16601.3 17012.3 18185.5 18471.8 18622.1 18632.0 18654.3 13852.5 13860.3 13983.9 13986.5 13992.0 17551.0 17667.0 17824.3 17934.2 18100.6 06:16:00 INFO - PID 13541 | 06:16:00 INFO - PID 13541 | [#3] mootools.html Cycles:180 Average:9150.51 Median:2634.64 stddev:6427.53 (244.0%) stddev-sans-first:6418.50 06:16:00 INFO - PID 13541 | Values: 1300.3 1324.5 1350.4 1354.2 1363.1 13944.5 14362.4 14470.1 14505.1 14506.7 14617.3 15576.2 15636.0 15665.8 15673.8 2677.1 2750.6 2751.5 2753.9 2762.7 2583.0 2601.9 2635.7 2659.5 2674.4 2668.6 2704.6 2723.3 2728.6 2731.2 2640.3 2673.2 2683.3 2684.1 2694.8 2589.3 2613.2 2619.8 2626.3 2633.6 14246.1 14403.4 14461.3 14490.2 14531.5 7732.6 8017.0 8056.1 8056.3 8072.1 5384.2 5459.5 5546.5 5564.6 5587.9 1848.9 1857.6 1870.8 1874.5 1907.9 24663.8 24842.0 24876.8 24881.9 24909.1 6707.5 6708.4 6765.5 6802.7 6890.1 16712.7 16757.4 16779.1 16805.9 16844.1 7514.1 7746.8 7953.5 8221.3 8233.5 7280.2 7332.7 7356.4 7456.3 7459.9 5063.3 5103.9 5192.6 5220.9 5294.9 1332.5 1339.4 1479.3 1522.2 1546.6 878.0 882.3 890.9 896.4 898.9 8166.5 8301.0 8378.1 8407.7 8490.9 939.8 946.5 966.2 972.0 979.2 4485.0 4559.2 4625.6 4680.7 4686.0 20451.7 20985.0 21389.1 21420.5 21583.2 19371.2 20725.8 20727.2 20892.1 20933.2 11050.9 11219.9 11228.0 11246.5 11252.0 7569.9 8041.3 8462.0 8573.9 8761.5 18156.6 18222.4 18246.6 18471.7 18499.8 18319.5 18407.0 18713.3 18726.4 19450.2 11973.4 12119.9 12151.7 12153.2 12156.8 13540.3 13675.2 13808.9 13902.9 13903.2 9799.3 10834.5 11428.1 11429.9 11589.5 10803.9 11082.7 11091.5 11091.9 11236.9 8987.5 9153.8 9162.9 9165.5 9189.6 7324.5 7329.0 7335.9 7344.5 7345.3 7255.5 7283.7 7349.3 7397.4 7482.9 06:16:00 INFO - PID 13541 | 06:16:00 INFO - PID 13541 | [#4] prototype.html Cycles:180 Average:5486.34 Median:6137.80 stddev:1558.15 (25.4%) stddev-sans-first:1516.86 06:16:00 INFO - PID 13541 | Values: 497.4 502.9 519.8 523.3 526.4 6136.6 6246.4 6300.0 6315.1 6331.9 6091.5 6126.9 6140.1 6172.6 6248.2 4907.5 4914.8 4947.1 4984.2 5028.4 4885.0 4945.9 4951.4 4960.8 5000.4 4710.5 4885.8 4904.5 4953.4 4999.3 4885.1 4903.7 4925.5 4956.8 4976.5 4331.4 4363.8 4412.4 4417.0 4465.4 6067.1 6069.7 6091.3 6139.0 6148.0 5665.4 5679.0 5682.1 5711.3 5715.4 6431.6 6459.9 6460.8 6499.8 6614.9 4622.4 4766.7 4772.8 4780.2 4796.7 6781.6 6866.8 6893.4 6896.6 6907.6 4892.2 4925.3 4926.8 4946.4 4964.4 4965.9 4966.3 4973.9 5042.3 5043.2 5475.0 5492.4 5593.3 5608.2 5615.9 6543.0 6576.4 6601.2 6615.6 6678.7 1644.0 1681.5 1713.4 1737.7 1744.6 1141.5 1141.7 1188.6 1192.8 1205.9 5654.3 5726.0 5738.9 5746.6 5760.0 6453.4 6526.5 6611.6 6617.8 6647.9 5781.2 5790.2 5800.6 5805.5 5810.3 3541.5 3545.3 3575.1 3578.5 3580.5 6494.3 6516.6 6556.6 6557.4 6591.1 6819.0 6890.4 6920.5 6927.4 7000.7 6464.8 6513.6 6541.9 6542.7 6584.5 6672.3 6680.0 6720.4 6735.4 6795.6 6651.6 6679.0 6694.6 6749.6 6761.0 6522.7 6577.6 6608.9 6613.4 6663.7 4878.6 4911.2 4948.4 4952.7 4959.4 6668.0 6711.5 6730.8 6735.3 6772.2 6408.2 6437.5 6457.7 6571.4 6592.7 6339.7 6499.0 6537.6 6556.2 6567.7 6227.9 6321.4 6400.9 6422.4 6521.2 6410.2 6524.7 6533.7 6556.9 6601.9 6295.6 6341.0 6351.5 6374.6 6427.6 06:16:00 INFO - PID 13541 | 06:16:00 INFO - PID 13541 | [#5] yui.html Cycles:180 Average:23346.97 Median:19784.11 stddev:32044.41 (162.0%) stddev-sans-first:32089.82 06:16:00 INFO - PID 13541 | Values: 861.9 886.7 896.6 902.5 914.4 16907.5 17669.0 17769.1 17809.9 19729.3 16743.2 16775.2 16781.4 16879.0 16925.8 941.6 950.7 957.7 957.8 964.1 874.2 944.5 950.2 951.6 952.5 995.3 1009.4 1009.8 1011.1 1014.7 988.1 1003.9 1006.7 1010.8 1011.3 729.3 729.6 737.3 738.6 739.8 17245.6 17297.7 17373.0 17404.8 18710.8 20179.0 20212.9 20780.2 20792.2 20806.4 16587.8 16661.2 16929.4 16944.9 17011.7 3696.8 3717.9 3759.9 3765.1 3767.4 119931.0 121509.4 124096.4 131033.3 132886.0 16490.2 17083.4 17443.6 17481.4 17481.8 30001.0 30351.2 30355.7 30363.7 30403.5 9292.2 9425.5 9478.0 9483.3 9527.5 4154.0 4253.2 4274.1 4276.7 4280.6 4828.7 5643.9 5690.3 5693.1 5718.4 1048.6 1079.7 1085.4 1103.3 1106.6 670.1 674.2 692.1 704.4 704.4 11477.9 11737.7 11854.9 11992.5 12104.6 686.3 691.9 699.5 703.6 705.6 5474.1 5506.0 5642.5 5679.6 5716.0 103190.5 109093.0 110565.0 111916.0 115232.0 98912.5 101672.5 102836.0 103443.5 105414.5 84596.0 87668.0 87996.6 89041.6 90485.5 10267.4 10427.0 10461.5 10613.8 10751.1 39675.2 40092.6 40102.8 40210.2 40411.4 50700.7 50806.7 50930.5 53346.7 68432.7 18272.2 18330.8 18385.6 18871.6 18876.0 17474.0 18105.2 18706.9 20712.3 21825.6 19124.2 19377.0 19462.1 19994.4 20242.3 17984.5 17985.7 18194.8 18306.3 18667.5 19005.2 19476.2 19566.3 19838.9 20203.2 11646.5 12281.3 13362.0 13602.8 13954.2 14393.0 14538.6 14624.8 14792.0 15227.4 06:16:00 INFO - PID 13541 | -------- Summary: end -------- 06:16:00 INFO - PID 13541 | 06:16:01 INFO - TEST-INFO | 13541: exit 0 06:16:01 INFO - TEST-OK | dromaeo_css | took 1114012ms 06:16:01 INFO - TEST-START | kraken 06:16:01 INFO - Initialising browser for kraken test... 06:16:01 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:49474/getInfo.html -profile /tmp/tmpq9Kfgc/profile 06:16:01 INFO - TEST-INFO | started process 13967 (/builds/slave/test/build/application/firefox/firefox http://localhost:49474/getInfo.html) 06:16:07 INFO - TEST-INFO | 13967: exit 0 06:16:07 INFO - Browser initialized. 06:16:07 INFO - Running cycle 1/1 for kraken test... 06:16:07 INFO - TEST-INFO | started process 14153 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpq9Kfgc/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 06:16:08 INFO - PID 14153 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 06:16:08 INFO - PID 14153 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 06:16:08 INFO - PID 14153 | 06:16:08 INFO - PID 14153 | (/builds/slave/test/build/application/firefox/firefox:14213): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 06:16:08 INFO - PID 14153 | 06:16:09 INFO - PID 14153 | 06:16:09 INFO - PID 14153 | (/builds/slave/test/build/application/firefox/firefox:14271): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 06:16:09 INFO - PID 14153 | 06:16:09 INFO - PID 14153 | __metrics Screen width/height:1600/1200 06:16:09 INFO - PID 14153 | colorDepth:24 06:16:09 INFO - PID 14153 | Browser inner width/height: 1024/768 06:16:09 INFO - PID 14153 | __metrics 06:16:19 INFO - PID 14153 | RSS: Main: 202866688 06:16:19 INFO - PID 14153 | 06:16:21 INFO - PID 14153 | Cycle 1(1): loaded http://localhost:49474/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49474/tests/kraken/driver.html?testName=audio-beat-detection) 06:16:22 INFO - PID 14153 | RSS: Main: 207044608 06:16:22 INFO - PID 14153 | 06:16:25 INFO - PID 14153 | Cycle 1(1): loaded http://localhost:49474/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49474/tests/kraken/driver.html?testName=audio-dft) 06:16:26 INFO - PID 14153 | RSS: Main: 208261120 06:16:26 INFO - PID 14153 | 06:16:29 INFO - PID 14153 | Cycle 1(1): loaded http://localhost:49474/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49474/tests/kraken/driver.html?testName=audio-fft) 06:16:29 INFO - PID 14153 | RSS: Main: 202821632 06:16:29 INFO - PID 14153 | 06:16:32 INFO - PID 14153 | Cycle 1(1): loaded http://localhost:49474/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49474/tests/kraken/driver.html?testName=audio-oscillator) 06:16:32 INFO - PID 14153 | RSS: Main: 202711040 06:16:32 INFO - PID 14153 | 06:16:34 INFO - PID 14153 | Cycle 1(1): loaded http://localhost:49474/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49474/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:16:35 INFO - PID 14153 | RSS: Main: 203685888 06:16:35 INFO - PID 14153 | 06:16:38 INFO - PID 14153 | Cycle 1(1): loaded http://localhost:49474/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49474/tests/kraken/driver.html?testName=imaging-darkroom) 06:16:39 INFO - PID 14153 | RSS: Main: 204414976 06:16:39 INFO - PID 14153 | 06:16:42 INFO - PID 14153 | Cycle 1(1): loaded http://localhost:49474/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49474/tests/kraken/driver.html?testName=imaging-desaturate) 06:16:43 INFO - PID 14153 | RSS: Main: 203304960 06:16:43 INFO - PID 14153 | 06:16:46 INFO - PID 14153 | Cycle 1(1): loaded http://localhost:49474/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49474/tests/kraken/driver.html?testName=json-parse-financial) 06:16:47 INFO - PID 14153 | RSS: Main: 206000128 06:16:47 INFO - PID 14153 | 06:16:48 INFO - PID 14153 | Cycle 1(1): loaded http://localhost:49474/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49474/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:16:49 INFO - PID 14153 | RSS: Main: 205336576 06:16:49 INFO - PID 14153 | 06:16:51 INFO - PID 14153 | Cycle 1(1): loaded http://localhost:49474/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49474/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:16:51 INFO - PID 14153 | RSS: Main: 204595200 06:16:51 INFO - PID 14153 | 06:16:54 INFO - PID 14153 | Cycle 1(1): loaded http://localhost:49474/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49474/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:16:54 INFO - PID 14153 | RSS: Main: 204963840 06:16:54 INFO - PID 14153 | 06:16:57 INFO - PID 14153 | Cycle 1(1): loaded http://localhost:49474/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49474/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:16:58 INFO - PID 14153 | RSS: Main: 206409728 06:16:58 INFO - PID 14153 | 06:17:01 INFO - PID 14153 | Cycle 1(1): loaded http://localhost:49474/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49474/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:17:01 INFO - PID 14153 | RSS: Main: 205635584 06:17:01 INFO - PID 14153 | 06:17:03 INFO - PID 14153 | Cycle 1(1): loaded http://localhost:49474/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49474/tests/kraken/driver.html?testName=ai-astar) 06:17:03 INFO - PID 14153 | RSS: Main: 207790080 06:17:03 INFO - PID 14153 | 06:17:03 INFO - PID 14153 | __start_tp_report 06:17:03 INFO - PID 14153 | _x_x_mozilla_page_load 06:17:03 INFO - PID 14153 | _x_x_mozilla_page_load_details 06:17:03 INFO - PID 14153 | |i|pagename|runs| 06:17:03 INFO - PID 14153 | |0;ai-astar;99;108;101;109;109;109;115;103;110;111 06:17:03 INFO - PID 14153 | |1;audio-beat-detection;150;119;168;192;124;151;145;120;166;130 06:17:03 INFO - PID 14153 | |2;audio-dft;167;165;172;167;163;169;166;169;163;162 06:17:03 INFO - PID 14153 | |3;audio-fft;74;75;76;168;78;75;73;74;168;76 06:17:03 INFO - PID 14153 | |4;audio-oscillator;78;80;138;89;79;80;129;79;85;140 06:17:03 INFO - PID 14153 | |5;imaging-gaussian-blur;114;122;124;120;125;115;120;126;121;115 06:17:03 INFO - PID 14153 | |6;imaging-darkroom;150;153;158;162;156;153;152;158;154;150 06:17:03 INFO - PID 14153 | |7;imaging-desaturate;94;95;96;95;95;94;94;94;94;105 06:17:03 INFO - PID 14153 | |8;json-parse-financial;54;54;64;53;55;55;55;52;56;54 06:17:03 INFO - PID 14153 | |9;json-stringify-tinderbox;55;59;55;55;61;62;61;56;56;60 06:17:03 INFO - PID 14153 | |10;stanford-crypto-aes;80;81;86;75;77;77;80;82;75;76 06:17:03 INFO - PID 14153 | |11;stanford-crypto-ccm;120;126;118;120;126;122;124;126;134;180 06:17:03 INFO - PID 14153 | |12;stanford-crypto-pbkdf2;173;168;177;174;180;175;177;166;168;174 06:17:03 INFO - PID 14153 | |13;stanford-crypto-sha256-iterative;70;66;67;65;71;66;79;66;65;65 06:17:03 INFO - PID 14153 | __end_tp_report 06:17:03 INFO - PID 14153 | __start_cc_report 06:17:03 INFO - PID 14153 | _x_x_mozilla_cycle_collect,962 06:17:03 INFO - PID 14153 | __end_cc_report 06:17:03 INFO - PID 14153 | __startTimestamp1503580623791__endTimestamp 06:17:03 INFO - PID 14153 | 06:17:03 INFO - PID 14153 | ------- Summary: start ------- 06:17:03 INFO - PID 14153 | Number of tests: 14 06:17:03 INFO - PID 14153 | 06:17:03 INFO - PID 14153 | [#0] ai-astar Cycles:10 Average:107.40 Median:109.50 stddev:4.90 (4.5%) stddev-sans-first:4.15 06:17:03 INFO - PID 14153 | Values: 99.0 108.0 101.0 109.0 109.0 109.0 115.0 103.0 110.0 111.0 06:17:03 INFO - PID 14153 | 06:17:03 INFO - PID 14153 | [#1] audio-beat-detection Cycles:10 Average:146.50 Median:150.50 stddev:24.00 (15.9%) stddev-sans-first:25.42 06:17:03 INFO - PID 14153 | Values: 150.0 119.0 168.0 192.0 124.0 151.0 145.0 120.0 166.0 130.0 06:17:03 INFO - PID 14153 | 06:17:03 INFO - PID 14153 | [#2] audio-dft Cycles:10 Average:166.30 Median:167.00 stddev:3.16 (1.9%) stddev-sans-first:3.35 06:17:03 INFO - PID 14153 | Values: 167.0 165.0 172.0 167.0 163.0 169.0 166.0 169.0 163.0 162.0 06:17:03 INFO - PID 14153 | 06:17:03 INFO - PID 14153 | [#3] audio-fft Cycles:10 Average:93.70 Median:75.00 stddev:39.18 (52.2%) stddev-sans-first:40.91 06:17:03 INFO - PID 14153 | Values: 74.0 75.0 76.0 168.0 78.0 75.0 73.0 74.0 168.0 76.0 06:17:03 INFO - PID 14153 | 06:17:03 INFO - PID 14153 | [#4] audio-oscillator Cycles:10 Average:97.70 Median:79.50 stddev:26.55 (33.4%) stddev-sans-first:27.19 06:17:03 INFO - PID 14153 | Values: 78.0 80.0 138.0 89.0 79.0 80.0 129.0 79.0 85.0 140.0 06:17:03 INFO - PID 14153 | 06:17:03 INFO - PID 14153 | [#5] imaging-gaussian-blur Cycles:10 Average:120.20 Median:121.50 stddev:4.32 (3.6%) stddev-sans-first:3.95 06:17:03 INFO - PID 14153 | Values: 114.0 122.0 124.0 120.0 125.0 115.0 120.0 126.0 121.0 115.0 06:17:03 INFO - PID 14153 | 06:17:03 INFO - PID 14153 | [#6] imaging-darkroom Cycles:10 Average:154.60 Median:155.00 stddev:3.86 (2.5%) stddev-sans-first:3.72 06:17:03 INFO - PID 14153 | Values: 150.0 153.0 158.0 162.0 156.0 153.0 152.0 158.0 154.0 150.0 06:17:03 INFO - PID 14153 | 06:17:03 INFO - PID 14153 | [#7] imaging-desaturate Cycles:10 Average:95.60 Median:94.50 stddev:3.37 (3.6%) stddev-sans-first:3.53 06:17:03 INFO - PID 14153 | Values: 94.0 95.0 96.0 95.0 95.0 94.0 94.0 94.0 94.0 105.0 06:17:03 INFO - PID 14153 | 06:17:03 INFO - PID 14153 | [#8] json-parse-financial Cycles:10 Average:55.20 Median:55.00 stddev:3.29 (6.0%) stddev-sans-first:3.46 06:17:03 INFO - PID 14153 | Values: 54.0 54.0 64.0 53.0 55.0 55.0 55.0 52.0 56.0 54.0 06:17:03 INFO - PID 14153 | 06:17:03 INFO - PID 14153 | [#9] json-stringify-tinderbox Cycles:10 Average:58.00 Median:59.50 stddev:2.87 (4.8%) stddev-sans-first:2.83 06:17:03 INFO - PID 14153 | Values: 55.0 59.0 55.0 55.0 61.0 62.0 61.0 56.0 56.0 60.0 06:17:03 INFO - PID 14153 | 06:17:03 INFO - PID 14153 | [#10] stanford-crypto-aes Cycles:10 Average:78.90 Median:80.00 stddev:3.54 (4.4%) stddev-sans-first:3.73 06:17:03 INFO - PID 14153 | Values: 80.0 81.0 86.0 75.0 77.0 77.0 80.0 82.0 75.0 76.0 06:17:03 INFO - PID 14153 | 06:17:03 INFO - PID 14153 | [#11] stanford-crypto-ccm Cycles:10 Average:129.60 Median:126.00 stddev:18.28 (14.5%) stddev-sans-first:19.05 06:17:03 INFO - PID 14153 | Values: 120.0 126.0 118.0 120.0 126.0 122.0 124.0 126.0 134.0 180.0 06:17:03 INFO - PID 14153 | 06:17:03 INFO - PID 14153 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:173.20 Median:174.50 stddev:4.54 (2.6%) stddev-sans-first:4.82 06:17:03 INFO - PID 14153 | Values: 173.0 168.0 177.0 174.0 180.0 175.0 177.0 166.0 168.0 174.0 06:17:03 INFO - PID 14153 | 06:17:03 INFO - PID 14153 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.00 Median:66.50 stddev:4.40 (6.6%) stddev-sans-first:4.60 06:17:03 INFO - PID 14153 | Values: 70.0 66.0 67.0 65.0 71.0 66.0 79.0 66.0 65.0 65.0 06:17:03 INFO - PID 14153 | -------- Summary: end -------- 06:17:03 INFO - PID 14153 | 06:17:04 INFO - PID 14153 | 06:17:04 INFO - PID 14153 | ###!!! [Parent][MessageChannel] Error: (msgtype=0x150081,name=PBrowser::Msg_Destroy) Closed channel: cannot send/recv 06:17:04 INFO - PID 14153 | 06:17:04 INFO - PID 14153 | 06:17:04 INFO - PID 14153 | ###!!! [Child][MessageChannel] Error: (msgtype=0x150001,name=PBrowser::Msg_AsyncMessage) Closed channel: cannot send/recv 06:17:04 INFO - PID 14153 | 06:17:04 INFO - PID 14153 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 06:17:04 INFO - TEST-INFO | 14153: exit 0 06:17:04 INFO - TEST-OK | kraken | took 63635ms 06:17:04 INFO - SUITE-END | took 1177s 06:17:04 INFO - Completed test suite (00:19:37) 06:17:04 INFO - javascript benchmark 06:17:04 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8393.735520908205, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3182.3317103408267, 3220.3881262560126, 3271.934561308774, 3297.3075654112645, 3317.253617935963, 6128.877422451559, 6135.969320153404, 6155.353687862777, 6164.568480206387, 6181.783637572691, 5997.070454079614, 6045.304789949158, 6063.302720187169, 6088.999999999989, 6099.5120390368775, 5601.3558440779225, 5602.859928501779, 5625.6905870177125, 5638.238837756912, 5665.320161580596, 5689.573282003846, 5715.2855893013375, 5718.142278658193, 5721.113227449752, 5724.398938111501, 5700.059490184135, 5703.144528320764, 5704.800331988381, 5705.144228365737, 5738.942610573903, 5649.152627105926, 5717.656940583578, 5727.11229759388, 5727.255456790632, 5738.254026976508, 5539.113741801333, 5614.831555053355, 5677.37548869624, 5717.399673034354, 5780.277465316835, 6017.308009578911, 6049.907008662609, 6101.145839582462, 6137.693115344215, 6186.288576813679, 6446.194225721785, 6476.481881449473, 6524.11924390206, 6552.901706474407, 6553.672316384162, 6620.3710647488415, 6661.900071498883, 6697.732090716366, 6742.0, 6745.763898263586, 6154.5384096192965, 6184.350643182446, 6216.036514340284, 6257.7184026718905, 6262.749490020439, 6553.148090748172, 6806.93193068063, 6813.216480104752, 6875.759348422881, 6885.896711549331, 6330.208723909512, 6362.618242905441, 6403.783281176561, 6409.230892292955, 6420.807375778734, 6440.194975628046, 6457.483401327882, 6513.413792758674, 6540.70566824494, 6573.868522629521, 6456.806295811133, 6468.256150542654, 6528.967355163289, 6529.2817790043055, 6574.211094668671, 6553.737850486023, 6614.040964060185, 6631.900521492182, 6699.430548403343, 6704.497162712719, 5119.718415487184, 5209.453007434241, 5241.449647787003, 5311.601629877775, 5317.122674758699, 4565.406497155415, 4594.770261486912, 4610.538946105363, 4629.305604159335, 4653.511381304982, 5696.8860622787315, 5699.059655156852, 5754.942450575524, 5777.0467872801355, 5783.826485205451, 6531.248906375733, 6536.150300460909, 6540.313267106981, 6620.602763834186, 6628.270890202074, 5533.1976863354585, 5602.747876345574, 5689.544836413077, 5790.710464476843, 5804.0, 5703.344115426779, 5747.597668163188, 5751.971240143773, 5759.308882934075, 5780.248567686174, 6455.870882582322, 6466.805995820133, 6545.214574251216, 6559.639219842859, 6560.671966401661, 6486.708098135501, 6539.247986481616, 6613.14029176204, 6625.17185351831, 6724.025016372696, 6407.519436042408, 6468.353164683494, 6516.543841931209, 6544.607323560602, 6552.115464412243, 6368.458681011981, 6522.412982831378, 6544.116544266653, 6553.639549824806, 6568.211814582281, 6425.8714825704155, 6575.0, 6605.438537724347, 6700.49746269022, 6702.765403210864, 6282.403171698681, 6340.4293613575, 6469.870602587827, 6494.09082728409, 6511.055896894829, 6437.002264648987, 6438.935610643834, 6454.386833250834, 6465.903011454738, 6479.416852483299, 6272.749090036347, 6318.304986451579, 6341.27075386318, 6445.806625801233, 6458.54790164684, 6344.619322840645, 6392.616443013248, 6461.61230326182, 6509.609423434609, 6542.476601871744, 6243.875122497616, 6308.211473565804, 6309.895768240448, 6330.556861019869, 6333.429991300805], "value": 6028.358017582614, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [804.1355542791497, 824.7855557555041, 845.6110189312916, 865.4158443050943, 872.8603423452247, 38834.41748373773, 39444.013899652484, 39458.408123878165, 39518.20963580727, 39556.20887582246, 35861.82069089651, 36066.0, 36270.093247668825, 36278.45582316261, 36348.27303453929, 6942.687579058955, 7019.82450438738, 7041.436685065182, 7062.1525416950035, 7066.187388450317, 6913.066735990652, 6923.861522769542, 6960.303969603025, 6998.125234345707, 6998.4751143664425, 6975.0, 6977.511574189809, 7329.926700732978, 7479.813004674872, 7495.400367970577, 7383.040204773372, 7428.925710742931, 7438.479306448524, 7446.66490007951, 7453.329200371965, 7594.430417718693, 7785.1436342002335, 7797.610119494003, 7798.0, 7809.492382995144, 37040.81479592585, 37450.0, 37520.43719344212, 37542.62457375446, 37549.436758448646, 3475.565554305712, 3517.2789578136676, 3538.8230588470733, 3540.132667496454, 3542.3800834853796, 30732.0, 30802.691973080207, 30879.228019299473, 31038.84480577605, 31096.844515777502, 5088.931324421876, 5213.635045546814, 5229.686218826844, 5231.973840130813, 5266.4733526647415, 161738.0, 173448.0, 173531.0, 173687.13156434262, 174842.0, 46538.30192547115, 46621.06757864824, 46749.0, 46750.29874551884, 46767.532324676315, 48291.51708482941, 48293.75853120712, 48441.5155848437, 48784.51215487799, 48945.0, 16049.197540122947, 16216.918915405347, 16349.509514714578, 16380.918095409446, 16402.589935251715, 13172.407241674147, 13223.47106115764, 13323.866761332456, 13374.0, 13419.530316439073, 13757.243351615558, 14016.299185040707, 14019.08875923082, 14111.364988575539, 14311.212883291519, 5673.716314184274, 5719.771209151588, 5744.569157313218, 5752.626079304916, 5765.682887441231, 965.923264980298, 996.6113215845826, 1001.5242759689235, 1004.2468148888333, 1025.897410258983, 22065.227717029826, 22180.0, 22400.775992240197, 22685.546289074457, 22836.65745013826, 1087.244365166202, 1088.8257878739364, 1095.5782023920847, 1099.290957332516, 1099.8075336816025, 11728.94135529317, 11858.584949526725, 11954.58158964432, 11964.28214307127, 12008.459619316976, 203408.0, 203886.0, 206029.0, 206214.0, 206574.0, 43825.78087109608, 51511.0, 51776.74111629418, 51918.22122668163, 52055.21917171245, 1318.2288361308742, 1401.4184113592949, 1435.1747745046432, 1437.7483940310613, 1462.6416527950507, 26544.0, 26574.601380978915, 26748.465030698888, 26801.86599066992, 27196.0, 35964.46053309255, 36069.27861442809, 36106.27787444184, 36195.63804361923, 36247.45628815517, 167961.0, 168554.0, 168662.0, 169143.0, 169673.15163424105, 33426.164345890575, 33956.830215848764, 34743.0, 34793.13017174591, 34869.825650871586, 43300.0, 44549.10901782011, 44655.55344446514, 44855.551444485136, 44940.0, 31597.77384770681, 32354.676453235166, 32467.0, 32497.35005299928, 32635.18412039718, 31902.0, 32069.67930320667, 32387.35225295528, 32629.510557342135, 32631.18422039373, 35929.10177245485, 35945.1013724659, 36008.81995590005, 36193.27613447769, 36198.0, 31383.215419613778, 31418.842905786238, 31700.36599268048, 31725.0, 32234.0, 23112.191073312355, 23511.529769404857, 23820.76179238255, 23853.64219536743, 23938.64092038586], "value": 16706.11441846743, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [557.5818136397702, 562.9324481062273, 565.3131445293967, 569.9772009119635, 572.6392372805134, 21385.465363365904, 22294.108235670552, 22356.0, 22386.0, 22427.0, 23081.192158274465, 24660.13689520867, 25112.74887251127, 25270.36824079399, 25354.0, 6377.776777812789, 6553.737850485975, 6554.80335589933, 6590.769323073693, 6604.207495100594, 6508.674566271691, 6528.41244288014, 6539.607623542579, 6554.442872355856, 6562.8687426251445, 6023.096535519663, 6422.678866056702, 6452.774152904649, 6491.221053473566, 6496.870062598745, 6536.901946470759, 6537.346265373449, 6559.245686746014, 6569.57297775643, 6585.769498067569, 6058.575899687024, 6065.4844338231305, 6075.149479072933, 6111.938880611181, 6138.570300078996, 27085.1874443767, 28057.0, 28484.572731409044, 28492.857535712188, 28506.714932850613, 1930.855185861066, 1941.0, 1945.046927005771, 1964.0965156028244, 1967.9409617711349, 18574.257029718796, 19187.32844350455, 19228.903855480774, 19247.326343577908, 19411.126499307564, 2306.169778879602, 2339.3215967369442, 2401.279616115176, 2408.4701365699516, 2438.4147804526788, 128822.3558882209, 133555.99999999904, 134609.0, 134791.32604337015, 135001.0, 13885.37515811791, 14175.503857364944, 14245.643858903402, 14831.332590033475, 15213.31540080699, 22578.0, 22580.774192257868, 22623.0, 22641.0, 22666.773332266795, 9452.0, 9668.468234247042, 9833.26250531213, 9866.062724041205, 9890.752731181634, 8859.0, 8924.910750892537, 8953.59708813105, 9007.23438507721, 9106.271498280254, 8970.596323165473, 9039.63841446348, 9072.501012444247, 9151.17639412456, 9414.0, 2592.1745297625303, 2627.9474410511684, 2636.973630263711, 2665.7067722550505, 2731.590261460757, 921.9032001639865, 943.4999450291219, 968.6874285343399, 989.1443901025705, 989.3223142147652, 19063.428097157106, 19095.045247737835, 19332.613347733248, 19711.211551537777, 19806.910619915754, 1009.0363702663939, 1028.6039874648313, 1031.097789434245, 1037.7820657661825, 1043.9217058720626, 10682.839757403644, 10752.301100428447, 10819.918008199274, 10822.0, 10832.67501974942, 460334.0, 469753.0, 474086.0, 475379.0, 476680.0, 115961.0, 116460.0, 117674.0, 117772.41113794377, 118763.0, 1132.3432409202642, 1143.9370834604176, 1159.8550181227347, 1161.1001473857827, 1168.1414160591958, 16953.660926781642, 17029.82970170332, 17174.39889603858, 17380.47858564245, 17709.734353984957, 51390.48609514006, 52050.0, 52313.738431307596, 53351.466485336205, 54325.0, 67575.0, 67822.0, 67998.3200167992, 68893.0, 69860.30139698742, 13718.862811371759, 13920.651983700489, 14205.928970355082, 14218.217998010208, 14224.075435096685, 22057.33827985099, 22209.888950555145, 22310.10759569599, 22362.217322393637, 22565.0, 17406.0, 17447.38934137299, 17840.0, 18036.278548858423, 18096.72854907151, 16064.67870642604, 16320.347186112422, 16353.219952608322, 16601.335946562485, 17012.319507219574, 18185.45443636693, 18471.815281847008, 18622.06889655539, 18632.0, 18654.34709785151, 13852.515161969282, 13860.306984650928, 13983.930080349533, 13986.531414201363, 13992.0, 17551.034693092002, 17667.0, 17824.287028519233, 17934.192961317032, 18100.637987240443], "value": 11683.215122959828, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1300.336828217609, 1324.4702119152337, 1350.3518311210617, 1354.2145555577765, 1363.093542794042, 13944.511942082028, 14362.425502979868, 14470.131792092481, 14505.129692218474, 14506.709865802677, 14617.269136543156, 15576.221188940535, 15636.0, 15665.84334156658, 15673.843261567381, 2677.06302794022, 2750.614913912054, 2751.463464624399, 2753.903613373532, 2762.737539933713, 2583.0313632387856, 2601.8959241630405, 2635.6705411823523, 2659.494696007762, 2674.3581540430255, 2668.5863691127906, 2704.634874291965, 2723.2919440945298, 2728.5947736915505, 2731.235254128847, 2640.3267166872515, 2673.1980405878257, 2683.2889284339667, 2684.141074856047, 2694.7844172466157, 2589.2879458148973, 2613.2290974162665, 2619.816612837102, 2626.290901456596, 2633.6049592561076, 14246.145231286053, 14403.423863045466, 14461.349239284258, 14490.20303883286, 14531.491397801132, 7732.613369331567, 8016.959915200387, 8056.113827478973, 8056.315213206826, 8072.1120676726105, 5384.246205531229, 5459.481349271852, 5546.473085056913, 5564.610477266593, 5587.944120558783, 1848.9075546222634, 1857.5913299074182, 1870.7661542307212, 1874.4564076417823, 1907.895065771382, 24663.753362466327, 24842.0, 24876.751232487626, 24881.875590622112, 24909.128180513777, 6707.463402927754, 6708.42978346846, 6765.458763299022, 6802.693878775467, 6890.104286442831, 16712.74930876038, 16757.41349052777, 16779.07715075658, 16805.915970420316, 16844.073575953196, 7514.098308202942, 7746.806329841686, 7953.483023603448, 8221.342292616577, 8233.46482478637, 7280.235575264627, 7332.706691732377, 7356.37470814976, 7456.291652293024, 7459.850802984019, 5063.3417655704525, 5103.897922041539, 5192.61055420845, 5220.869478263074, 5294.867628309246, 1332.4803326702595, 1339.4173534512518, 1479.2751551739784, 1522.2388805597202, 1546.6056155680456, 877.9692710755093, 882.2765332427476, 890.9242714369221, 896.4397251717677, 898.883145191121, 8166.510009399455, 8300.958495207486, 8378.078411374743, 8407.747767566983, 8490.915090849136, 939.7509659940122, 946.4936259101348, 966.2366730283138, 971.9951400242953, 979.1677074486772, 4485.0, 4559.226518785463, 4625.606823419979, 4680.7191568506, 4685.976570117127, 20451.693224601346, 20985.0, 21389.144434222457, 21420.464488387915, 21583.2445864394, 19371.225150993803, 20725.79274207269, 20727.170913163605, 20892.059857306456, 20933.16267349289, 11050.944745276222, 11219.887801122048, 11228.045616122557, 11246.49390777417, 11252.043576296106, 7569.886451703119, 8041.276285134366, 8462.0, 8573.91426085756, 8761.502971653048, 18156.636867262845, 18222.36221732233, 18246.635067298314, 18471.722924155878, 18499.81500184981, 18319.54201144982, 18407.0, 18713.345032923782, 18726.438206853818, 19450.221991120197, 11973.401329933642, 12119.878801211875, 12151.696207594881, 12153.210041347284, 12156.75686486283, 13540.32298385057, 13675.17948923028, 13808.861911380758, 13902.860971390157, 13903.165810051429, 9799.314048016571, 10834.458277086273, 11428.085753139896, 11429.885701142883, 11589.536418543164, 10803.891961080288, 11082.722931926766, 11091.50088246047, 11091.944540277247, 11236.887631123584, 8987.46075235488, 9153.816923661623, 9162.908370916206, 9165.541722913695, 9189.632414703337, 7324.48728589015, 7329.0, 7335.889961650686, 7344.522606030591, 7345.3021962913435, 7255.52839065459, 7283.672234749343, 7349.265073492608, 7397.371223446067, 7482.925170748223], "value": 6570.564689601427, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [497.4144299433639, 502.94216165140995, 519.794681100965, 523.3196844102665, 526.3657292961981, 6136.631802091866, 6246.3753624637575, 6299.968500157504, 6315.147455093561, 6331.92357299259, 6091.451769340759, 6126.877462450749, 6140.1096840958035, 6172.567920245585, 6248.15649887265, 4907.509249075099, 4914.778834952435, 4947.109520286347, 4984.152694042022, 5028.421731500887, 4884.975575122137, 4945.876353091184, 4951.3810773653295, 4960.826371077013, 5000.449950505442, 4710.481846996828, 4885.755712214375, 4904.509549045102, 4953.405591329028, 4999.250112483121, 4885.145099607555, 4903.681260718042, 4925.507449255082, 4956.776945037482, 4976.502349765013, 4331.436913201263, 4363.825446982117, 4412.360207769888, 4416.977915110404, 4465.352523884051, 6067.120267561223, 6069.663179675857, 6091.329953705089, 6138.9693051534905, 6148.0, 5665.433456654343, 5678.971605141989, 5682.119271512922, 5711.343195532525, 5715.399883012307, 6431.5819471734185, 6459.903101453482, 6460.806175814686, 6499.837504062436, 6614.933850661528, 4622.411449588241, 4766.73782941938, 4772.785224664864, 4780.211265141247, 4796.704889679767, 6781.559198652072, 6866.793996180172, 6893.4140598049225, 6896.551724137858, 6907.620080895549, 4892.241702536113, 4925.335079764186, 4926.802927882916, 4946.35697359341, 4964.429090654548, 4965.851024469272, 4966.279889416014, 4973.850784476472, 5042.319286896222, 5043.167877300204, 5474.974259524001, 5492.368377636543, 5593.322003398964, 5608.242887210256, 5615.859603509945, 6543.0, 6576.44100251484, 6601.174853143357, 6615.60306381609, 6678.666066696645, 1644.0382376309753, 1681.5039563328846, 1713.4088739384893, 1737.7480265361453, 1744.6043165467574, 1141.520561364229, 1141.6860363399965, 1188.5661733467396, 1192.8270400791837, 1205.8664855036238, 5654.349749778746, 5726.024521540062, 5738.942610573924, 5746.626469279567, 5760.0, 6453.354664533509, 6526.510511711545, 6611.595028560295, 6617.834554136089, 6647.900281495781, 5781.19063331134, 5790.160426738184, 5800.564957628195, 5805.506531944748, 5810.331811841608, 3541.4771648191527, 3545.3441113393587, 3575.1419659281587, 3578.499010138583, 3580.480830279647, 6494.337321698081, 6516.641584712792, 6556.639834509998, 6557.409833114947, 6591.110200123018, 6819.0, 6890.414314783299, 6920.515563910579, 6927.411170050602, 7000.719971201095, 6464.838379040561, 6513.609183449103, 6541.8691626168165, 6542.738290468423, 6584.5061620378665, 6672.332766723289, 6680.0, 6720.428763554957, 6735.383507958153, 6795.592264464148, 6651.600903945779, 6679.0, 6694.59832410057, 6749.628770417477, 6760.966195168992, 6522.706478208397, 6577.572457790228, 6608.933910660832, 6613.404793568602, 6663.700133493908, 4878.63410244226, 4911.238757992517, 4948.406191257072, 4952.678075925053, 4959.404871415453, 6668.0, 6711.530192886451, 6730.764423245163, 6735.2927942567285, 6772.187337519529, 6408.198975128109, 6437.549371543947, 6457.741690332334, 6571.3757193066585, 6592.670366481752, 6339.714712837841, 6499.0, 6537.5750576212395, 6556.18047744032, 6567.737290508326, 6227.906581401192, 6321.431071203614, 6400.935990640221, 6422.421982021454, 6521.217453905561, 6410.230772307353, 6524.739010439529, 6533.738650453929, 6556.901646475211, 6601.867962640817, 6295.55931084838, 6340.968295158495, 6351.523635727432, 6374.553781235269, 6427.614343139427], "value": 5024.728556975978, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [861.8793368928348, 886.7384121684103, 896.5786080542147, 902.5171533229721, 914.4193437167398, 16907.49277521673, 17669.028203448837, 17769.111544422776, 17809.91095044526, 19729.309474168407, 16743.16284185792, 16775.24511396987, 16781.41265055723, 16879.0, 16925.830741692578, 941.5809964565753, 950.7385468996047, 957.7174733453613, 957.7701351675618, 964.0504103458088, 874.2175752701329, 944.4994153098869, 950.2493030505892, 951.6431338248157, 952.5046975572698, 995.3032876986102, 1009.4296722351847, 1009.8333774927128, 1011.1354791653141, 1014.7463134216446, 988.0761488008735, 1003.859459675646, 1006.7483129217695, 1010.8382658774598, 1011.2567263061644, 729.3108012927786, 729.5914287998738, 737.2958824322759, 738.6454501839094, 739.7521830186894, 17245.568860778454, 17297.740533892003, 17373.044482553454, 17404.825951740448, 18710.812891871043, 20179.0, 20212.89893550523, 20780.168793248253, 20792.16831326745, 20806.375808725763, 16587.751183732253, 16661.166941652868, 16929.407470738464, 16944.915275423667, 17011.659766804594, 3696.7597106188014, 3717.8698745544048, 3759.9436008459893, 3765.1151979284846, 3767.416050512175, 119931.0, 121509.39245303717, 124096.37951810274, 131033.34483327523, 132886.0, 16490.17549122539, 17083.40208092711, 17443.56391090233, 17481.388151414638, 17481.825181748274, 30001.0, 30351.241218969262, 30355.69644303573, 30363.696363036528, 30403.543946840815, 9292.210162136158, 9425.528723563795, 9477.952610236905, 9483.336166468282, 9527.47598882068, 4154.044569748975, 4253.21315556623, 4274.059706864485, 4276.722013069203, 4280.636145927569, 4828.686135401188, 5643.943560564342, 5690.260266165372, 5693.146028095819, 5718.370979192286, 1048.5700862646431, 1079.6599071292517, 1085.4464223246043, 1103.3490240757992, 1106.5684383090602, 670.0786418674322, 674.20780582815, 692.0760784352946, 704.3871831506622, 704.4012589299055, 11477.942610286895, 11737.706557335963, 11854.885021597533, 11992.460339284753, 12104.636860894187, 686.2759788423261, 691.8754624167598, 699.5278187223604, 703.6305939381865, 705.6048612776862, 5474.124140137559, 5506.0, 5642.548596112301, 5679.630823996427, 5715.999700052557, 103190.48404758079, 109093.0, 110565.0, 111916.0, 115232.0, 98912.50543747235, 101672.49163754134, 102836.0, 103443.4827825856, 105414.47292763487, 84596.0, 87668.0, 87996.5600171995, 89041.55479222562, 90485.54757226304, 10267.435291059064, 10427.0, 10461.47692615351, 10613.787724245627, 10751.086157676373, 39675.2064958705, 40092.59907401005, 40102.79948600355, 40210.1957960845, 40411.39382909318, 50700.74649626728, 50806.74596626993, 50930.49069509257, 53346.73326633342, 68432.6578367105, 18272.177752000927, 18330.81669183291, 18385.63228735391, 18871.622567548297, 18876.0, 17474.0, 18105.185266662767, 18706.906465467586, 20712.275070372463, 21825.56348872982, 19124.235030598622, 19377.0, 19462.12420441112, 19994.400167994983, 20242.291519797327, 17984.460466186036, 17985.730214046536, 18194.818051819842, 18306.26774928988, 18667.533311667317, 19005.23979040878, 19476.220951162362, 19566.315178968667, 19838.90080549588, 20203.19187232553, 11646.475908584303, 12281.324527151093, 13362.0, 13602.795958060835, 13954.162750235018, 14393.0, 14538.636534086732, 14624.756895663988, 14792.0, 15227.390904364145], "value": 9002.310518313745, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1544.9000000000003, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 108.0, 101.0, 109.0, 109.0, 109.0, 115.0, 103.0, 110.0, 111.0], "value": 107.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 119.0, 168.0, 192.0, 124.0, 151.0, 145.0, 120.0, 166.0, 130.0], "value": 146.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 165.0, 172.0, 167.0, 163.0, 169.0, 166.0, 169.0, 163.0, 162.0], "value": 166.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 75.0, 76.0, 168.0, 78.0, 75.0, 73.0, 74.0, 168.0, 76.0], "value": 93.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 80.0, 138.0, 89.0, 79.0, 80.0, 129.0, 79.0, 85.0, 140.0], "value": 97.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 122.0, 124.0, 120.0, 125.0, 115.0, 120.0, 126.0, 121.0, 115.0], "value": 120.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 153.0, 158.0, 162.0, 156.0, 153.0, 152.0, 158.0, 154.0, 150.0], "value": 154.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 96.0, 95.0, 95.0, 94.0, 94.0, 94.0, 94.0, 105.0], "value": 95.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 54.0, 64.0, 53.0, 55.0, 55.0, 55.0, 52.0, 56.0, 54.0], "value": 55.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 59.0, 55.0, 55.0, 61.0, 62.0, 61.0, 56.0, 56.0, 60.0], "value": 58.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 81.0, 86.0, 75.0, 77.0, 77.0, 80.0, 82.0, 75.0, 76.0], "value": 78.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [120.0, 126.0, 118.0, 120.0, 126.0, 122.0, 124.0, 126.0, 134.0, 180.0], "value": 129.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [173.0, 168.0, 177.0, 174.0, 180.0, 175.0, 177.0, 166.0, 168.0, 174.0], "value": 173.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 66.0, 67.0, 65.0, 71.0, 66.0, 79.0, 66.0, 65.0, 65.0], "value": 68.0, "unit": "score"}]}]} 06:17:05 INFO - Return code: 0 06:17:05 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 06:17:05 INFO - # TBPL SUCCESS # 06:17:05 INFO - Running post-action listener: _package_coverage_data 06:17:05 INFO - Running post-action listener: _resource_record_post_action 06:17:05 INFO - [mozharness: 2017-08-24 13:17:05.071151Z] Finished run-tests step (success) 06:17:05 INFO - Running post-run listener: _resource_record_post_run 06:17:05 INFO - Total resource usage - Wall time: 1196s; CPU: 13.0%; Read bytes: 180224; Write bytes: 435879936; Read time: 88; Write time: 423864 06:17:05 INFO - TinderboxPrint: CPU usage
13.3% 06:17:05 INFO - TinderboxPrint: I/O read bytes / time
180,224 / 88 06:17:05 INFO - TinderboxPrint: I/O write bytes / time
435,879,936 / 423,864 06:17:05 INFO - TinderboxPrint: CPU idle
8,263.7 (86.7%) 06:17:05 INFO - TinderboxPrint: CPU user
1,203.0 (12.6%) 06:17:05 INFO - TinderboxPrint: Swap in / out
0 / 0 06:17:05 INFO - install - Wall time: 18s; CPU: 14.0%; Read bytes: 0; Write bytes: 115290112; Read time: 0; Write time: 112692 06:17:05 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 06:17:05 INFO - run-tests - Wall time: 1178s; CPU: 13.0%; Read bytes: 180224; Write bytes: 320589824; Read time: 88; Write time: 311172 06:17:05 INFO - Running post-run listener: _upload_blobber_files 06:17:05 INFO - Blob upload gear active. 06:17:05 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:17:05 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 06:17:05 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'] 06:17:05 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 06:17:06 INFO - (blobuploader) - INFO - Open directory for files ... 06:17:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 06:17:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:17:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:17:07 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 06:17:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:17:07 INFO - (blobuploader) - INFO - Done attempting. 06:17:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 06:17:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:17:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:17:08 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 06:17:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:17:08 INFO - (blobuploader) - INFO - Done attempting. 06:17:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 06:17:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:17:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:17:15 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 06:17:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:17:15 INFO - (blobuploader) - INFO - Done attempting. 06:17:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 06:17:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:17:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:17:16 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 06:17:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:17:16 INFO - (blobuploader) - INFO - Done attempting. 06:17:16 INFO - (blobuploader) - INFO - Iteration through files over. 06:17:16 INFO - Return code: 0 06:17:16 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:17:16 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:17:16 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e9493f1d31dba077d87b604d631a1aec613c86a26e564d59440f000e47dafaa001c854d7e0498319dbb442afc5c26be265579bad9d8a809ef6a1757bffaf8709", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b668e04e4c2ccda12e4a8828293e501aca0d511b7842240de620b986e2dda1d5ef5eb882bda7473704581800fffbe7ec6f07dd011eddc8985a66ba46275a5fac", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e1ed3738dadf92e14d76e5abec20565cbe2383c08a6cc6a2ba255dff2c1c97c29810103d19c137b3073c39f32865c0be59fc6cfdfb3cdbd7622afbde721c0428", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7bf82ba9639233677e1b3cd61693de386f1636055412b5a96bb78f2664987258d773f5b549ad045e78a9c4c3cd08869ae9c6d2717ba10c243c49d0a6e958774e"} 06:17:16 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:17:16 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:17:16 INFO - Contents: 06:17:16 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e9493f1d31dba077d87b604d631a1aec613c86a26e564d59440f000e47dafaa001c854d7e0498319dbb442afc5c26be265579bad9d8a809ef6a1757bffaf8709", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b668e04e4c2ccda12e4a8828293e501aca0d511b7842240de620b986e2dda1d5ef5eb882bda7473704581800fffbe7ec6f07dd011eddc8985a66ba46275a5fac", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e1ed3738dadf92e14d76e5abec20565cbe2383c08a6cc6a2ba255dff2c1c97c29810103d19c137b3073c39f32865c0be59fc6cfdfb3cdbd7622afbde721c0428", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7bf82ba9639233677e1b3cd61693de386f1636055412b5a96bb78f2664987258d773f5b549ad045e78a9c4c3cd08869ae9c6d2717ba10c243c49d0a6e958774e"} 06:17:16 INFO - Running post-run listener: copy_logs_to_upload_dir 06:17:16 INFO - Copying logs to upload dir... 06:17:16 INFO - mkdir: /builds/slave/test/build/upload/logs 06:17:16 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1257.661393 ========= master_lag: -0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 57 secs) (at 2017-08-24 06:17:16.108807) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-24 06:17:16.112626) ========= 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=82c74bd3bc9a11c5833d2010000001e6-1503579376.400570-620425387 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/e9493f1d31dba077d87b604d631a1aec613c86a26e564d59440f000e47dafaa001c854d7e0498319dbb442afc5c26be265579bad9d8a809ef6a1757bffaf8709", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b668e04e4c2ccda12e4a8828293e501aca0d511b7842240de620b986e2dda1d5ef5eb882bda7473704581800fffbe7ec6f07dd011eddc8985a66ba46275a5fac", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e1ed3738dadf92e14d76e5abec20565cbe2383c08a6cc6a2ba255dff2c1c97c29810103d19c137b3073c39f32865c0be59fc6cfdfb3cdbd7622afbde721c0428", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7bf82ba9639233677e1b3cd61693de386f1636055412b5a96bb78f2664987258d773f5b549ad045e78a9c4c3cd08869ae9c6d2717ba10c243c49d0a6e958774e"} build_url:https://queue.taskcluster.net/v1/task/ZAmByIIwSJKylSbIRUWeYQ/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011415 build_url: 'https://queue.taskcluster.net/v1/task/ZAmByIIwSJKylSbIRUWeYQ/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e9493f1d31dba077d87b604d631a1aec613c86a26e564d59440f000e47dafaa001c854d7e0498319dbb442afc5c26be265579bad9d8a809ef6a1757bffaf8709", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b668e04e4c2ccda12e4a8828293e501aca0d511b7842240de620b986e2dda1d5ef5eb882bda7473704581800fffbe7ec6f07dd011eddc8985a66ba46275a5fac", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e1ed3738dadf92e14d76e5abec20565cbe2383c08a6cc6a2ba255dff2c1c97c29810103d19c137b3073c39f32865c0be59fc6cfdfb3cdbd7622afbde721c0428", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7bf82ba9639233677e1b3cd61693de386f1636055412b5a96bb78f2664987258d773f5b549ad045e78a9c4c3cd08869ae9c6d2717ba10c243c49d0a6e958774e"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-24 06:17:16.139798) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-24 06:17:16.140127) ========= 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=82c74bd3bc9a11c5833d2010000001e6-1503579376.400570-620425387 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004615 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-24 06:17:16.222135) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-24 06:17:16.222454) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-24 06:17:16.222761) ========= ========= Total master_lag: 0.15 =========