builder: mozilla-central_ubuntu64_hw_qr_test-dromaeojs-e10s slave: talos-linux64-ix-027 starttime: 1503622977.33 results: success (0) revision: 3199bacd6b3827d711155a9e81c081434cf7cd8f ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-08-24 18:02:57.329171) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-08-24 18:02:57.329623) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-24 18:02:57.329896) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-24 18:02:57.346267) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-24 18:02:57.346630) ========= 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=1f5564757af27664ca02d71f000001b0-1503622976.524054-895464963 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-08-24 18:02:58-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 43.5M=0s 2017-08-24 18:02:58 (43.5 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.186217 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-24 18:02:57.547826) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-24 18:02:57.548164) ========= 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=1f5564757af27664ca02d71f000001b0-1503622976.524054-895464963 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014990 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-24 18:02:57.601955) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-24 18:02:57.604425) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 3199bacd6b3827d711155a9e81c081434cf7cd8f --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 3199bacd6b3827d711155a9e81c081434cf7cd8f --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=1f5564757af27664ca02d71f000001b0-1503622976.524054-895464963 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-08-24 18:02:58,553 truncating revision to first 12 chars 2017-08-24 18:02:58,553 Setting DEBUG logging. 2017-08-24 18:02:58,553 attempt 1/10 2017-08-24 18:02:58,553 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/3199bacd6b38?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-08-24 18:02:59,206 unpacking tar archive at: mozilla-central-3199bacd6b38/testing/mozharness/ program finished with exit code 0 elapsedTime=0.959596 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-24 18:02:58.578674) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-24 18:02:58.580056) ========= script_repo_revision: 3199bacd6b3827d711155a9e81c081434cf7cd8f ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-24 18:02:58.580479) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-24 18:02:58.580771) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-24 18:02:58.593598) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 56 secs) (at 2017-08-24 18:02:58.593985) ========= /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=1f5564757af27664ca02d71f000001b0-1503622976.524054-895464963 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 18:02:59 INFO - MultiFileLogger online at 20170824 18:02:59 in /builds/slave/test 18:02:59 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 18:02:59 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:02:59 INFO - {'append_to_log': False, 18:02:59 INFO - 'base_work_dir': '/builds/slave/test', 18:02:59 INFO - 'blob_upload_branch': 'Firefox', 18:02:59 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:02:59 INFO - 'branch': 'Firefox', 18:02:59 INFO - 'buildbot_json_path': 'buildprops.json', 18:02:59 INFO - 'code_coverage': False, 18:02:59 INFO - 'config_files': ('talos/linux_config.py',), 18:02:59 INFO - 'default_actions': ('clobber', 18:02:59 INFO - 'read-buildbot-config', 18:02:59 INFO - 'download-and-extract', 18:02:59 INFO - 'populate-webroot', 18:02:59 INFO - 'create-virtualenv', 18:02:59 INFO - 'install', 18:02:59 INFO - 'setup-mitmproxy', 18:02:59 INFO - 'run-tests'), 18:02:59 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:02:59 INFO - 'disable_ccov_upload': False, 18:02:59 INFO - 'download_minidump_stackwalk': True, 18:02:59 INFO - 'download_symbols': 'ondemand', 18:02:59 INFO - 'enable_stylo': False, 18:02:59 INFO - 'enable_webrender': False, 18:02:59 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 18:02:59 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:02:59 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:02:59 INFO - 'gecko_profile': False, 18:02:59 INFO - 'gecko_profile_interval': 0, 18:02:59 INFO - 'installer_path': 'installer.exe', 18:02:59 INFO - 'log_level': 'info', 18:02:59 INFO - 'log_name': 'talos', 18:02:59 INFO - 'log_to_console': True, 18:02:59 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 18:02:59 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 18:02:59 INFO - 'opt_config_files': (), 18:02:59 INFO - 'pip_index': False, 18:02:59 INFO - 'suite': 'dromaeojs-e10s', 18:02:59 INFO - 'system_bits': '32', 18:02:59 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:02:59 INFO - 'title': 'talos-linux64-ix-027', 18:02:59 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:02:59 INFO - 'use_talos_json': True, 18:02:59 INFO - 'verify': 'False', 18:02:59 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:02:59 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:02:59 INFO - 'work_dir': 'build'} 18:02:59 INFO - [mozharness: 2017-08-25 01:02:59.687326Z] Running clobber step. 18:02:59 INFO - Running pre-action listener: _resource_record_pre_action 18:02:59 INFO - Running main action method: clobber 18:02:59 INFO - rmtree: /builds/slave/test/build 18:02:59 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:03:00 INFO - Running post-action listener: _resource_record_post_action 18:03:00 INFO - [mozharness: 2017-08-25 01:03:00.701806Z] Finished clobber step (success) 18:03:00 INFO - [mozharness: 2017-08-25 01:03:00.701905Z] Running read-buildbot-config step. 18:03:00 INFO - Running pre-action listener: _resource_record_pre_action 18:03:00 INFO - Running main action method: read_buildbot_config 18:03:00 INFO - Using buildbot properties: 18:03:00 INFO - { 18:03:00 INFO - "project": "", 18:03:00 INFO - "product": "firefox", 18:03:00 INFO - "who": "ryanvm@gmail.com", 18:03:00 INFO - "installer_path": "public/build/target.tar.bz2", 18:03:00 INFO - "builddir": "mozilla-central_ubuntu64_hw_qr_test-dromaeojs-e10s", 18:03:00 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 18:03:00 INFO - "buildername": "Ubuntu HW 12.04 x64 qr mozilla-central talos dromaeojs-e10s", 18:03:00 INFO - "stage_platform": "linux64-qr", 18:03:00 INFO - "basedir": "/builds/slave/test", 18:03:00 INFO - "buildnumber": 18, 18:03:00 INFO - "platform": "ubuntu64_hw_qr", 18:03:00 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 18:03:00 INFO - "slavebuilddir": "test", 18:03:00 INFO - "taskId": "O-LmSGp0QoKr3m3Vstlc8Q", 18:03:00 INFO - "branch": "mozilla-central", 18:03:00 INFO - "script_repo_revision": "production", 18:03:00 INFO - "revision": "3199bacd6b3827d711155a9e81c081434cf7cd8f", 18:03:00 INFO - "slavename": "talos-linux64-ix-027", 18:03:00 INFO - "repo_path": "mozilla-central" 18:03:00 INFO - } 18:03:00 INFO - Finding installer, test and symbols from parent task. 18:03:00 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/O-LmSGp0QoKr3m3Vstlc8Q'}, attempt #1 18:03:01 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/O-LmSGp0QoKr3m3Vstlc8Q'}, attempt #1 18:03:01 INFO - Task dependencies: Amqe4HB8QW2hqpHBtZHK2w 18:03:01 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Amqe4HB8QW2hqpHBtZHK2w'}, attempt #1 18:03:02 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/Amqe4HB8QW2hqpHBtZHK2w/artifacts/public/build/target.tar.bz2 18:03:02 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/Amqe4HB8QW2hqpHBtZHK2w/artifacts/public/build/target.test_packages.json 18:03:02 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/Amqe4HB8QW2hqpHBtZHK2w/artifacts/public/build/target.crashreporter-symbols.zip 18:03:02 INFO - Running post-action listener: _resource_record_post_action 18:03:02 INFO - [mozharness: 2017-08-25 01:03:02.090985Z] Finished read-buildbot-config step (success) 18:03:02 INFO - [mozharness: 2017-08-25 01:03:02.091202Z] Running download-and-extract step. 18:03:02 INFO - Running pre-action listener: _resource_record_pre_action 18:03:02 INFO - Running main action method: download_and_extract 18:03:02 INFO - mkdir: /builds/slave/test/build/tests 18:03:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:03:02 INFO - trying https://queue.taskcluster.net/v1/task/Amqe4HB8QW2hqpHBtZHK2w/artifacts/public/build/target.test_packages.json 18:03:02 INFO - Downloading https://queue.taskcluster.net/v1/task/Amqe4HB8QW2hqpHBtZHK2w/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 18:03:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Amqe4HB8QW2hqpHBtZHK2w/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 18:03:02 INFO - Downloaded 989 bytes. 18:03:02 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 18:03:02 INFO - Using the following test package requirements: 18:03:02 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 18:03:02 INFO - u'common': [u'target.common.tests.zip'], 18:03:02 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 18:03:02 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 18:03:02 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 18:03:02 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 18:03:02 INFO - u'mozbase': [u'target.common.tests.zip'], 18:03:02 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 18:03:02 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 18:03:02 INFO - u'web-platform': [u'target.common.tests.zip', 18:03:02 INFO - u'target.web-platform.tests.tar.gz'], 18:03:02 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 18:03:02 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 18:03:02 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Amqe4HB8QW2hqpHBtZHK2w/artifacts/public/build/target.common.tests.zip 18:03:02 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Amqe4HB8QW2hqpHBtZHK2w/artifacts/public/build/target.common.tests.zip'}, attempt #1 18:03:02 INFO - Fetch https://queue.taskcluster.net/v1/task/Amqe4HB8QW2hqpHBtZHK2w/artifacts/public/build/target.common.tests.zip into memory 18:03:03 INFO - Content-Length response header: 54980109 18:03:03 INFO - Bytes received: 54980109 18:03:14 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 18:03:14 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Amqe4HB8QW2hqpHBtZHK2w/artifacts/public/build/target.common.tests.zip 18:03:14 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Amqe4HB8QW2hqpHBtZHK2w/artifacts/public/build/target.common.tests.zip'}, attempt #1 18:03:14 INFO - Fetch https://queue.taskcluster.net/v1/task/Amqe4HB8QW2hqpHBtZHK2w/artifacts/public/build/target.common.tests.zip into memory 18:03:15 INFO - Content-Length response header: 54980109 18:03:15 INFO - Bytes received: 54980109 18:03:27 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Amqe4HB8QW2hqpHBtZHK2w/artifacts/public/build/target.talos.tests.zip 18:03:27 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Amqe4HB8QW2hqpHBtZHK2w/artifacts/public/build/target.talos.tests.zip'}, attempt #1 18:03:27 INFO - Fetch https://queue.taskcluster.net/v1/task/Amqe4HB8QW2hqpHBtZHK2w/artifacts/public/build/target.talos.tests.zip into memory 18:03:28 INFO - Content-Length response header: 14496555 18:03:28 INFO - Bytes received: 14496555 18:03:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:03:28 INFO - trying https://queue.taskcluster.net/v1/task/Amqe4HB8QW2hqpHBtZHK2w/artifacts/public/build/target.tar.bz2 18:03:28 INFO - Downloading https://queue.taskcluster.net/v1/task/Amqe4HB8QW2hqpHBtZHK2w/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 18:03:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Amqe4HB8QW2hqpHBtZHK2w/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 18:03:29 INFO - Downloaded 61070188 bytes. 18:03:29 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Amqe4HB8QW2hqpHBtZHK2w/artifacts/public/build/target.tar.bz2 18:03:29 INFO - mkdir: /builds/slave/test/properties 18:03:29 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:03:29 INFO - Writing to file /builds/slave/test/properties/build_url 18:03:29 INFO - Contents: 18:03:29 INFO - build_url:https://queue.taskcluster.net/v1/task/Amqe4HB8QW2hqpHBtZHK2w/artifacts/public/build/target.tar.bz2 18:03:29 INFO - Running post-action listener: _resource_record_post_action 18:03:29 INFO - Running post-action listener: find_tests_for_verification 18:03:29 INFO - Running post-action listener: set_extra_try_arguments 18:03:29 INFO - [mozharness: 2017-08-25 01:03:29.908869Z] Finished download-and-extract step (success) 18:03:29 INFO - [mozharness: 2017-08-25 01:03:29.908966Z] Running populate-webroot step. 18:03:29 INFO - Running pre-action listener: _resource_record_pre_action 18:03:29 INFO - Running main action method: populate_webroot 18:03:29 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:03:29 INFO - 'chromez-stylo-e10s': {'talos_options': ['--stylo'], 18:03:29 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:03:29 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:03:29 INFO - 'dromaeojs-stylo-e10s': {'talos_options': ['--stylo'], 18:03:29 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:03:29 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 18:03:29 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:03:29 INFO - 'g1-stylo-e10s': {'pagesets_name': 'tp5n.zip', 18:03:29 INFO - 'talos_options': ['--stylo'], 18:03:29 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:03:29 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 18:03:29 INFO - 'tests': ['damp', 'tps']}, 18:03:29 INFO - 'g2-stylo-e10s': {'pagesets_name': 'tp5n.zip', 18:03:29 INFO - 'talos_options': ['--stylo'], 18:03:29 INFO - 'tests': ['damp', 'tps']}, 18:03:29 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:03:29 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 18:03:29 INFO - 'g4-stylo-e10s': {'talos_options': ['--stylo'], 18:03:29 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 18:03:29 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 18:03:29 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 18:03:29 INFO - 'g5-stylo-e10s': {'pagesets_name': 'tp5n.zip', 18:03:29 INFO - 'talos_options': ['--stylo'], 18:03:29 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 18:03:29 INFO - 'other-e10s': {'tests': ['a11yr', 18:03:29 INFO - 'ts_paint', 18:03:29 INFO - 'tpaint', 18:03:29 INFO - 'sessionrestore', 18:03:29 INFO - 'sessionrestore_many_windows', 18:03:29 INFO - 'sessionrestore_no_auto_restore', 18:03:29 INFO - 'tabpaint']}, 18:03:29 INFO - 'other-stylo-e10s': {'talos_options': ['--stylo'], 18:03:29 INFO - 'tests': ['a11yr', 18:03:29 INFO - 'ts_paint', 18:03:29 INFO - 'tpaint', 18:03:29 INFO - 'sessionrestore', 18:03:29 INFO - 'sessionrestore_many_windows', 18:03:29 INFO - 'sessionrestore_no_auto_restore', 18:03:29 INFO - 'tabpaint']}, 18:03:29 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 18:03:29 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 18:03:29 INFO - 'perf-reftest-singletons-stylo-e10s': {'talos_options': ['--stylo'], 18:03:29 INFO - 'tests': ['bloom_basic_singleton']}, 18:03:29 INFO - 'perf-reftest-stylo-e10s': {'talos_options': ['--stylo'], 18:03:29 INFO - 'tests': ['bloom_basic']}, 18:03:29 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:03:29 INFO - 'tsvgr_opacity', 18:03:29 INFO - 'tart', 18:03:29 INFO - 'tscrollx', 18:03:29 INFO - 'tsvg_static']}, 18:03:29 INFO - 'svgr-stylo-e10s': {'talos_options': ['--stylo'], 18:03:29 INFO - 'tests': ['tsvgx', 18:03:29 INFO - 'tsvgr_opacity', 18:03:29 INFO - 'tart', 18:03:29 INFO - 'tscrollx', 18:03:29 INFO - 'tsvg_static']}, 18:03:29 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 18:03:29 INFO - 'tp5o-stylo-e10s': {'pagesets_name': 'tp5n.zip', 18:03:29 INFO - 'talos_options': ['--stylo'], 18:03:29 INFO - 'tests': ['tp5o']}, 18:03:29 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 18:03:29 INFO - 'talos_options': ['--mitmproxy', 18:03:29 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 18:03:29 INFO - '--firstNonBlankPaint'], 18:03:29 INFO - 'tests': ['tp6_google', 18:03:29 INFO - 'tp6_youtube', 18:03:29 INFO - 'tp6_amazon', 18:03:29 INFO - 'tp6_facebook']}, 18:03:29 INFO - 'tp6-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 18:03:29 INFO - 'talos_options': ['--stylo', 18:03:29 INFO - '--mitmproxy', 18:03:29 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 18:03:29 INFO - '--firstNonBlankPaint'], 18:03:29 INFO - 'tests': ['tp6_google', 18:03:29 INFO - 'tp6_youtube', 18:03:29 INFO - 'tp6_amazon', 18:03:29 INFO - 'tp6_facebook']}, 18:03:29 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 18:03:29 INFO - 'talos_options': ['--stylo', 18:03:29 INFO - '--stylo-threads=1', 18:03:29 INFO - '--mitmproxy', 18:03:29 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 18:03:29 INFO - '--firstNonBlankPaint'], 18:03:29 INFO - 'tests': ['tp6_google', 18:03:29 INFO - 'tp6_youtube', 18:03:29 INFO - 'tp6_amazon', 18:03:29 INFO - 'tp6_facebook']}, 18:03:29 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 18:03:29 INFO - 'talos_options': ['--xperf_path', 18:03:29 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:03:29 INFO - 'tests': ['tp5n']}, 18:03:29 INFO - 'xperf-stylo-e10s': {'pagesets_name': 'tp5n.zip', 18:03:29 INFO - 'talos_options': ['--stylo', 18:03:29 INFO - '--xperf_path', 18:03:29 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:03:29 INFO - 'tests': ['tp5n']}}} 18:03:29 INFO - Running post-action listener: _resource_record_post_action 18:03:29 INFO - [mozharness: 2017-08-25 01:03:29.923256Z] Finished populate-webroot step (success) 18:03:29 INFO - [mozharness: 2017-08-25 01:03:29.923345Z] Running create-virtualenv step. 18:03:29 INFO - Running pre-action listener: _resource_record_pre_action 18:03:29 INFO - Running main action method: create_virtualenv 18:03:29 INFO - Creating virtualenv /builds/slave/test/build/venv 18:03:29 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 18:03:29 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 18:03:29 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 18:03:30 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:03:30 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:03:30 INFO - Installing distribute.............................................................................................................................................................................................done. 18:03:30 INFO - Installing pip...............done. 18:03:30 INFO - Return code: 0 18:03:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 18:03:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 18:03:31 INFO - Reading from file tmpfile_stdout 18:03:31 INFO - Output received: 18:03:31 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 18:03:31 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:03:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:03:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:03:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:03:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:03:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x117e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1349a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14ecf90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14ea130>, '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 0x14eb030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14eb4c0>, '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': '1f5564757af27664ca02d71f000001b0-1503622976.524054-895464963', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:03:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 18:03:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 18:03:31 INFO - Using env: {'DISPLAY': ':0', 18:03:31 INFO - 'HOME': '/home/cltbld', 18:03:31 INFO - 'LANG': 'en_US.UTF-8', 18:03:31 INFO - 'LANGUAGE': 'en_US:en', 18:03:31 INFO - 'LOGNAME': 'cltbld', 18:03:31 INFO - 'MAIL': '/var/mail/cltbld', 18:03:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:03:31 INFO - 'MOZ_NO_REMOTE': '1', 18:03:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:03:31 INFO - 'NO_EM_RESTART': '1', 18:03:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:03:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:03:31 INFO - 'PWD': '/builds/slave/test', 18:03:31 INFO - 'SHELL': '/bin/bash', 18:03:31 INFO - 'SHLVL': '1', 18:03:31 INFO - 'TERM': 'linux', 18:03:31 INFO - 'TMOUT': '86400', 18:03:31 INFO - 'USER': 'cltbld', 18:03:31 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1503622976.524054-895464963', 18:03:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:03:31 INFO - '_': '/tools/buildbot/bin/python'} 18:03:32 INFO - Ignoring indexes: http://pypi.python.org/simple/ 18:03:32 INFO - Downloading/unpacking pip>=1.5 18:03:32 INFO - Running setup.py egg_info for package pip 18:03:32 INFO - warning: no files found matching 'pip/cacert.pem' 18:03:32 INFO - warning: no files found matching '*.html' under directory 'docs' 18:03:32 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 18:03:32 INFO - no previously-included directories found matching 'docs/_build/_sources' 18:03:32 INFO - Installing collected packages: pip 18:03:32 INFO - Found existing installation: pip 1.1 18:03:32 INFO - Uninstalling pip: 18:03:32 INFO - Successfully uninstalled pip 18:03:32 INFO - Running setup.py install for pip 18:03:32 INFO - warning: no files found matching 'pip/cacert.pem' 18:03:32 INFO - warning: no files found matching '*.html' under directory 'docs' 18:03:32 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 18:03:32 INFO - no previously-included directories found matching 'docs/_build/_sources' 18:03:32 INFO - Installing pip script to /builds/slave/test/build/venv/bin 18:03:32 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 18:03:32 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 18:03:32 INFO - Successfully installed pip 18:03:32 INFO - Cleaning up... 18:03:32 INFO - Return code: 0 18:03:32 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:03:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:03:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:03:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:03:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:03:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x117e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1349a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14ecf90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14ea130>, '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 0x14eb030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14eb4c0>, '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': '1f5564757af27664ca02d71f000001b0-1503622976.524054-895464963', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:03:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 18:03:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 18:03:32 INFO - Using env: {'DISPLAY': ':0', 18:03:32 INFO - 'HOME': '/home/cltbld', 18:03:32 INFO - 'LANG': 'en_US.UTF-8', 18:03:32 INFO - 'LANGUAGE': 'en_US:en', 18:03:32 INFO - 'LOGNAME': 'cltbld', 18:03:32 INFO - 'MAIL': '/var/mail/cltbld', 18:03:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:03:32 INFO - 'MOZ_NO_REMOTE': '1', 18:03:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:03:32 INFO - 'NO_EM_RESTART': '1', 18:03:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:03:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:03:32 INFO - 'PWD': '/builds/slave/test', 18:03:32 INFO - 'SHELL': '/bin/bash', 18:03:32 INFO - 'SHLVL': '1', 18:03:32 INFO - 'TERM': 'linux', 18:03:32 INFO - 'TMOUT': '86400', 18:03:32 INFO - 'USER': 'cltbld', 18:03:32 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1503622976.524054-895464963', 18:03:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:03:32 INFO - '_': '/tools/buildbot/bin/python'} 18:03:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:03:32 INFO - Downloading/unpacking psutil>=3.1.1 18:03:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:03:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:03:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:03:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:03:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:03:33 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:03:33 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:03:33 INFO - Installing collected packages: psutil 18:03:33 INFO - Running setup.py install for psutil 18:03:33 INFO - building 'psutil._psutil_linux' extension 18:03:33 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 18:03:33 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 18:03:33 INFO - building 'psutil._psutil_posix' extension 18:03:33 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 18:03:34 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 18:03:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:03:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:03:34 INFO - Successfully installed psutil 18:03:34 INFO - Cleaning up... 18:03:34 INFO - Return code: 0 18:03:34 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:03:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:03:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:03:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:03:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:03:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x117e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1349a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14ecf90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14ea130>, '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 0x14eb030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14eb4c0>, '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': '1f5564757af27664ca02d71f000001b0-1503622976.524054-895464963', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:03:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 18:03:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 18:03:34 INFO - Using env: {'DISPLAY': ':0', 18:03:34 INFO - 'HOME': '/home/cltbld', 18:03:34 INFO - 'LANG': 'en_US.UTF-8', 18:03:34 INFO - 'LANGUAGE': 'en_US:en', 18:03:34 INFO - 'LOGNAME': 'cltbld', 18:03:34 INFO - 'MAIL': '/var/mail/cltbld', 18:03:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:03:34 INFO - 'MOZ_NO_REMOTE': '1', 18:03:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:03:34 INFO - 'NO_EM_RESTART': '1', 18:03:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:03:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:03:34 INFO - 'PWD': '/builds/slave/test', 18:03:34 INFO - 'SHELL': '/bin/bash', 18:03:34 INFO - 'SHLVL': '1', 18:03:34 INFO - 'TERM': 'linux', 18:03:34 INFO - 'TMOUT': '86400', 18:03:34 INFO - 'USER': 'cltbld', 18:03:34 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1503622976.524054-895464963', 18:03:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:03:34 INFO - '_': '/tools/buildbot/bin/python'} 18:03:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:03:34 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:03:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:03:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:03:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:03:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:03:35 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:03:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:03:35 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:03:35 INFO - Installing collected packages: mozsystemmonitor 18:03:35 INFO - Running setup.py install for mozsystemmonitor 18:03:35 INFO - Successfully installed mozsystemmonitor 18:03:35 INFO - Cleaning up... 18:03:35 INFO - Return code: 0 18:03:35 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:03:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:03:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:03:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:03:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:03:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x117e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1349a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14ecf90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14ea130>, '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 0x14eb030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14eb4c0>, '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': '1f5564757af27664ca02d71f000001b0-1503622976.524054-895464963', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:03:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 18:03:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 18:03:35 INFO - Using env: {'DISPLAY': ':0', 18:03:35 INFO - 'HOME': '/home/cltbld', 18:03:35 INFO - 'LANG': 'en_US.UTF-8', 18:03:35 INFO - 'LANGUAGE': 'en_US:en', 18:03:35 INFO - 'LOGNAME': 'cltbld', 18:03:35 INFO - 'MAIL': '/var/mail/cltbld', 18:03:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:03:35 INFO - 'MOZ_NO_REMOTE': '1', 18:03:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:03:35 INFO - 'NO_EM_RESTART': '1', 18:03:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:03:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:03:35 INFO - 'PWD': '/builds/slave/test', 18:03:35 INFO - 'SHELL': '/bin/bash', 18:03:35 INFO - 'SHLVL': '1', 18:03:35 INFO - 'TERM': 'linux', 18:03:35 INFO - 'TMOUT': '86400', 18:03:35 INFO - 'USER': 'cltbld', 18:03:35 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1503622976.524054-895464963', 18:03:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:03:35 INFO - '_': '/tools/buildbot/bin/python'} 18:03:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:03:35 INFO - Downloading/unpacking jsonschema==2.5.1 18:03:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:03:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:03:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:03:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:03:37 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:03:37 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:03:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:03:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:03:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:03:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:03:37 INFO - Downloading functools32-3.2.3-2.tar.gz 18:03:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:03:37 INFO - warning: no files found matching '*.txt' 18:03:37 INFO - no previously-included directories found matching 'build' 18:03:37 INFO - no previously-included directories found matching 'dist' 18:03:37 INFO - no previously-included directories found matching '.git*' 18:03:37 INFO - Installing collected packages: jsonschema, functools32 18:03:37 INFO - Running setup.py install for functools32 18:03:37 INFO - warning: no files found matching '*.txt' 18:03:37 INFO - no previously-included directories found matching 'build' 18:03:37 INFO - no previously-included directories found matching 'dist' 18:03:37 INFO - no previously-included directories found matching '.git*' 18:03:37 INFO - Successfully installed jsonschema functools32 18:03:37 INFO - Cleaning up... 18:03:37 INFO - Return code: 0 18:03:37 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:03:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:03:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:03:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:03:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:03:37 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 0x117e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1349a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14ecf90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14ea130>, '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 0x14eb030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14eb4c0>, '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': '1f5564757af27664ca02d71f000001b0-1503622976.524054-895464963', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:03:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 18:03:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 18:03:37 INFO - Using env: {'DISPLAY': ':0', 18:03:37 INFO - 'HOME': '/home/cltbld', 18:03:37 INFO - 'LANG': 'en_US.UTF-8', 18:03:37 INFO - 'LANGUAGE': 'en_US:en', 18:03:37 INFO - 'LOGNAME': 'cltbld', 18:03:37 INFO - 'MAIL': '/var/mail/cltbld', 18:03:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:03:37 INFO - 'MOZ_NO_REMOTE': '1', 18:03:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:03:37 INFO - 'NO_EM_RESTART': '1', 18:03:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:03:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:03:37 INFO - 'PWD': '/builds/slave/test', 18:03:37 INFO - 'SHELL': '/bin/bash', 18:03:37 INFO - 'SHLVL': '1', 18:03:37 INFO - 'TERM': 'linux', 18:03:37 INFO - 'TMOUT': '86400', 18:03:37 INFO - 'USER': 'cltbld', 18:03:37 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1503622976.524054-895464963', 18:03:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:03:37 INFO - '_': '/tools/buildbot/bin/python'} 18:03:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:03:37 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:03:37 INFO - Cleaning up... 18:03:37 INFO - Return code: 0 18:03:37 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:03:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:03:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:03:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:03:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:03:37 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 0x117e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1349a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14ecf90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14ea130>, '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 0x14eb030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14eb4c0>, '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': '1f5564757af27664ca02d71f000001b0-1503622976.524054-895464963', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:03:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 18:03:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 18:03:37 INFO - Using env: {'DISPLAY': ':0', 18:03:37 INFO - 'HOME': '/home/cltbld', 18:03:37 INFO - 'LANG': 'en_US.UTF-8', 18:03:37 INFO - 'LANGUAGE': 'en_US:en', 18:03:37 INFO - 'LOGNAME': 'cltbld', 18:03:37 INFO - 'MAIL': '/var/mail/cltbld', 18:03:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:03:37 INFO - 'MOZ_NO_REMOTE': '1', 18:03:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:03:37 INFO - 'NO_EM_RESTART': '1', 18:03:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:03:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:03:37 INFO - 'PWD': '/builds/slave/test', 18:03:37 INFO - 'SHELL': '/bin/bash', 18:03:37 INFO - 'SHLVL': '1', 18:03:37 INFO - 'TERM': 'linux', 18:03:37 INFO - 'TMOUT': '86400', 18:03:37 INFO - 'USER': 'cltbld', 18:03:37 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1503622976.524054-895464963', 18:03:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:03:37 INFO - '_': '/tools/buildbot/bin/python'} 18:03:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:03:37 INFO - Downloading/unpacking blobuploader==1.2.4 18:03:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:03:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:03:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:03:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:03:38 INFO - Downloading blobuploader-1.2.4.tar.gz 18:03:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:03:38 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:03:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:03:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:03:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:03:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:03:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:03:39 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:03:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:03:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:03:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:03:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:03:39 INFO - Downloading docopt-0.6.1.tar.gz 18:03:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:03:39 INFO - Installing collected packages: blobuploader, requests, docopt 18:03:39 INFO - Running setup.py install for blobuploader 18:03:39 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:03:39 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:03:39 INFO - Running setup.py install for requests 18:03:39 INFO - Running setup.py install for docopt 18:03:40 INFO - Successfully installed blobuploader requests docopt 18:03:40 INFO - Cleaning up... 18:03:40 INFO - Return code: 0 18:03:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:03:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:03:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:03:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:03:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:03:40 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 0x117e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1349a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14ecf90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14ea130>, '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 0x14eb030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14eb4c0>, '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': '1f5564757af27664ca02d71f000001b0-1503622976.524054-895464963', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:03:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 18:03:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:03:40 INFO - Using env: {'DISPLAY': ':0', 18:03:40 INFO - 'HOME': '/home/cltbld', 18:03:40 INFO - 'LANG': 'en_US.UTF-8', 18:03:40 INFO - 'LANGUAGE': 'en_US:en', 18:03:40 INFO - 'LOGNAME': 'cltbld', 18:03:40 INFO - 'MAIL': '/var/mail/cltbld', 18:03:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:03:40 INFO - 'MOZ_NO_REMOTE': '1', 18:03:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:03:40 INFO - 'NO_EM_RESTART': '1', 18:03:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:03:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:03:40 INFO - 'PWD': '/builds/slave/test', 18:03:40 INFO - 'SHELL': '/bin/bash', 18:03:40 INFO - 'SHLVL': '1', 18:03:40 INFO - 'TERM': 'linux', 18:03:40 INFO - 'TMOUT': '86400', 18:03:40 INFO - 'USER': 'cltbld', 18:03:40 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1503622976.524054-895464963', 18:03:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:03:40 INFO - '_': '/tools/buildbot/bin/python'} 18:03:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:03:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:03:40 INFO - Running setup.py (path:/tmp/pip-0x_ktd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:03:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:03:40 INFO - Running setup.py (path:/tmp/pip-sl1Go_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:03:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:03:40 INFO - Running setup.py (path:/tmp/pip-8RLyvm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:03:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:03:40 INFO - Running setup.py (path:/tmp/pip-z4uVQe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:03:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:03:40 INFO - Running setup.py (path:/tmp/pip-K0RPbp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:03:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:03:40 INFO - Running setup.py (path:/tmp/pip-Gnmt7z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:03:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:03:40 INFO - Running setup.py (path:/tmp/pip-47MUuE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:03:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:03:40 INFO - Running setup.py (path:/tmp/pip-FMTzFU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:03:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:03:40 INFO - Running setup.py (path:/tmp/pip-3OViJT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:03:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:03:40 INFO - Running setup.py (path:/tmp/pip-Zwiaod-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:03:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:03:40 INFO - Running setup.py (path:/tmp/pip-d3fpJ9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:03:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:03:41 INFO - Running setup.py (path:/tmp/pip-4Q2m6i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:03:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:03:41 INFO - Running setup.py (path:/tmp/pip-fxN2Ii-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:03:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:03:41 INFO - Running setup.py (path:/tmp/pip-bzD3tH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:03:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:03:41 INFO - Running setup.py (path:/tmp/pip-ZUH2oj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:03:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:03:41 INFO - Running setup.py (path:/tmp/pip-Njym5s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:03:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:03:41 INFO - Running setup.py (path:/tmp/pip-Udrd91-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:03:41 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:03:41 INFO - Running setup.py install for manifestparser 18:03:41 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:03:41 INFO - Running setup.py install for mozcrash 18:03:41 INFO - Running setup.py install for mozdebug 18:03:41 INFO - Running setup.py install for mozdevice 18:03:41 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:03:41 INFO - Running setup.py install for mozfile 18:03:41 INFO - Running setup.py install for mozhttpd 18:03:42 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:03:42 INFO - Running setup.py install for mozinfo 18:03:42 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:03:42 INFO - Running setup.py install for mozInstall 18:03:42 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:03:42 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:03:42 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:03:42 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:03:42 INFO - Running setup.py install for mozleak 18:03:42 INFO - Running setup.py install for mozlog 18:03:42 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:03:42 INFO - Running setup.py install for moznetwork 18:03:42 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:03:42 INFO - Running setup.py install for mozprocess 18:03:42 INFO - Running setup.py install for mozprofile 18:03:42 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:03:42 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:03:42 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:03:42 INFO - Running setup.py install for mozrunner 18:03:42 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:03:42 INFO - Running setup.py install for mozscreenshot 18:03:43 INFO - Running setup.py install for moztest 18:03:43 INFO - Running setup.py install for mozversion 18:03:43 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:03:43 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:03:43 INFO - Cleaning up... 18:03:43 INFO - Return code: 0 18:03:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:03:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:03:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:03:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:03:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:03:43 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 0x117e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1349a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14ecf90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14ea130>, '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 0x14eb030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14eb4c0>, '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': '1f5564757af27664ca02d71f000001b0-1503622976.524054-895464963', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:03:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 18:03:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:03:43 INFO - Using env: {'DISPLAY': ':0', 18:03:43 INFO - 'HOME': '/home/cltbld', 18:03:43 INFO - 'LANG': 'en_US.UTF-8', 18:03:43 INFO - 'LANGUAGE': 'en_US:en', 18:03:43 INFO - 'LOGNAME': 'cltbld', 18:03:43 INFO - 'MAIL': '/var/mail/cltbld', 18:03:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:03:43 INFO - 'MOZ_NO_REMOTE': '1', 18:03:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:03:43 INFO - 'NO_EM_RESTART': '1', 18:03:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:03:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:03:43 INFO - 'PWD': '/builds/slave/test', 18:03:43 INFO - 'SHELL': '/bin/bash', 18:03:43 INFO - 'SHLVL': '1', 18:03:43 INFO - 'TERM': 'linux', 18:03:43 INFO - 'TMOUT': '86400', 18:03:43 INFO - 'USER': 'cltbld', 18:03:43 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1503622976.524054-895464963', 18:03:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:03:43 INFO - '_': '/tools/buildbot/bin/python'} 18:03:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:03:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:03:43 INFO - Running setup.py (path:/tmp/pip-IUeear-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:03:43 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 18:03:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:03:43 INFO - Running setup.py (path:/tmp/pip-tVkAqF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:03:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:03:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:03:43 INFO - Running setup.py (path:/tmp/pip-QA9xJi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:03:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 18:03:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:03:43 INFO - Running setup.py (path:/tmp/pip-LF7JWm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:03:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:03:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:03:43 INFO - Running setup.py (path:/tmp/pip-32cDcQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:03:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 18:03:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:03:43 INFO - Running setup.py (path:/tmp/pip-tPjYQ3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:03:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 18:03:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:03:43 INFO - Running setup.py (path:/tmp/pip-xt_0Qc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:03:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 18:03:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:03:43 INFO - Running setup.py (path:/tmp/pip-leGldc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:03:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.13 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 18:03:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:03:44 INFO - Running setup.py (path:/tmp/pip-wDQQVS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:03:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 18:03:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:03:44 INFO - Running setup.py (path:/tmp/pip-Rzbu14-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:03:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.5 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:03:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:03:44 INFO - Running setup.py (path:/tmp/pip-bUs7Uv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:03:44 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 18:03:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:03:44 INFO - Running setup.py (path:/tmp/pip-y04fjA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:03:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 18:03:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:03:44 INFO - Running setup.py (path:/tmp/pip-IYerKz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:03:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 18:03:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:03:44 INFO - Running setup.py (path:/tmp/pip-gGpuD5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:03:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 18:03:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:03:44 INFO - Running setup.py (path:/tmp/pip-mdbgUW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:03:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 18:03:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:03:44 INFO - Running setup.py (path:/tmp/pip-9R1rRD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:03:44 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 18:03:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:03:44 INFO - Running setup.py (path:/tmp/pip-4AIIv9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:03:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 18:03:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:03:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:03:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 18:03:44 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:03:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:03:44 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.13->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 18:03:44 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:03:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:03:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:03:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:03:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:03:45 INFO - Downloading blessings-1.6.tar.gz 18:03:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:03:45 INFO - Installing collected packages: blessings 18:03:45 INFO - Running setup.py install for blessings 18:03:45 INFO - Successfully installed blessings 18:03:45 INFO - Cleaning up... 18:03:45 INFO - Return code: 0 18:03:45 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:03:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:03:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:03:46 INFO - Reading from file tmpfile_stdout 18:03:46 INFO - Current package versions: 18:03:46 INFO - argparse == 1.2.1 18:03:46 INFO - blessings == 1.6 18:03:46 INFO - blobuploader == 1.2.4 18:03:46 INFO - docopt == 0.6.1 18:03:46 INFO - functools32 == 3.2.3-2 18:03:46 INFO - jsonschema == 2.5.1 18:03:46 INFO - manifestparser == 1.1 18:03:46 INFO - mozInstall == 1.13 18:03:46 INFO - mozcrash == 1.0 18:03:46 INFO - mozdebug == 0.1 18:03:46 INFO - mozdevice == 0.51 18:03:46 INFO - mozfile == 1.2 18:03:46 INFO - mozhttpd == 0.7 18:03:46 INFO - mozinfo == 0.10 18:03:46 INFO - mozleak == 0.1 18:03:46 INFO - mozlog == 3.5 18:03:46 INFO - moznetwork == 0.27 18:03:46 INFO - mozprocess == 0.25 18:03:46 INFO - mozprofile == 0.28 18:03:46 INFO - mozrunner == 6.13 18:03:46 INFO - mozscreenshot == 0.1 18:03:46 INFO - mozsystemmonitor == 0.3 18:03:46 INFO - moztest == 0.8 18:03:46 INFO - mozversion == 1.4 18:03:46 INFO - psutil == 3.1.1 18:03:46 INFO - requests == 1.2.3 18:03:46 INFO - wsgiref == 0.1.2 18:03:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:03:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:03:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:03:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:03:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:03:46 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 0x117e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1349a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14ecf90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14ea130>, '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 0x14eb030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14eb4c0>, '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': '1f5564757af27664ca02d71f000001b0-1503622976.524054-895464963', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:03:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 18:03:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:03:46 INFO - Using env: {'DISPLAY': ':0', 18:03:46 INFO - 'HOME': '/home/cltbld', 18:03:46 INFO - 'LANG': 'en_US.UTF-8', 18:03:46 INFO - 'LANGUAGE': 'en_US:en', 18:03:46 INFO - 'LOGNAME': 'cltbld', 18:03:46 INFO - 'MAIL': '/var/mail/cltbld', 18:03:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:03:46 INFO - 'MOZ_NO_REMOTE': '1', 18:03:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:03:46 INFO - 'NO_EM_RESTART': '1', 18:03:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:03:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:03:46 INFO - 'PWD': '/builds/slave/test', 18:03:46 INFO - 'SHELL': '/bin/bash', 18:03:46 INFO - 'SHLVL': '1', 18:03:46 INFO - 'TERM': 'linux', 18:03:46 INFO - 'TMOUT': '86400', 18:03:46 INFO - 'USER': 'cltbld', 18:03:46 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1503622976.524054-895464963', 18:03:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:03:46 INFO - '_': '/tools/buildbot/bin/python'} 18:03:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:03:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 18:03:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 18:03:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 18:03:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 18:03:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 18:03:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 18:03:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 18:03:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 18:03:46 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 18:03:46 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 18:03:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:03:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:03:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:03:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:03:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 18:03:47 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 18:03:47 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 18:03:47 INFO - Installing collected packages: simplejson 18:03:47 INFO - Running setup.py install for simplejson 18:03:47 INFO - building 'simplejson._speedups' extension 18:03:47 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c simplejson/_speedups.c -o build/temp.linux-x86_64-2.7/simplejson/_speedups.o 18:03:48 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/simplejson/_speedups.o -o build/lib.linux-x86_64-2.7/simplejson/_speedups.so 18:03:48 INFO - Successfully installed simplejson 18:03:48 INFO - Cleaning up... 18:03:48 INFO - Return code: 0 18:03:48 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:03:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:03:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:03:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:03:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:03:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x117e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1349a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14ecf90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14ea130>, '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 0x14eb030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14eb4c0>, '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': '1f5564757af27664ca02d71f000001b0-1503622976.524054-895464963', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:03:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 18:03:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 18:03:48 INFO - Using env: {'DISPLAY': ':0', 18:03:48 INFO - 'HOME': '/home/cltbld', 18:03:48 INFO - 'LANG': 'en_US.UTF-8', 18:03:48 INFO - 'LANGUAGE': 'en_US:en', 18:03:48 INFO - 'LOGNAME': 'cltbld', 18:03:48 INFO - 'MAIL': '/var/mail/cltbld', 18:03:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:03:48 INFO - 'MOZ_NO_REMOTE': '1', 18:03:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:03:48 INFO - 'NO_EM_RESTART': '1', 18:03:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:03:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:03:48 INFO - 'PWD': '/builds/slave/test', 18:03:48 INFO - 'SHELL': '/bin/bash', 18:03:48 INFO - 'SHLVL': '1', 18:03:48 INFO - 'TERM': 'linux', 18:03:48 INFO - 'TMOUT': '86400', 18:03:48 INFO - 'USER': 'cltbld', 18:03:48 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1503622976.524054-895464963', 18:03:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:03:48 INFO - '_': '/tools/buildbot/bin/python'} 18:03:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:03:48 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:03:48 INFO - Cleaning up... 18:03:48 INFO - Return code: 0 18:03:48 INFO - Running post-action listener: _resource_record_post_action 18:03:48 INFO - Running post-action listener: _start_resource_monitoring 18:03:48 INFO - Starting resource monitoring. 18:03:48 INFO - [mozharness: 2017-08-25 01:03:48.902526Z] Finished create-virtualenv step (success) 18:03:48 INFO - [mozharness: 2017-08-25 01:03:48.903024Z] Running install step. 18:03:48 INFO - Running pre-action listener: _resource_record_pre_action 18:03:48 INFO - Running main action method: install 18:03:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:03:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:03:49 INFO - Reading from file tmpfile_stdout 18:03:49 INFO - Detecting whether we're running mozinstall >=1.0... 18:03:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:03:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:03:49 INFO - Reading from file tmpfile_stdout 18:03:49 INFO - Output received: 18:03:49 INFO - Usage: mozinstall [options] installer 18:03:49 INFO - Options: 18:03:49 INFO - -h, --help show this help message and exit 18:03:49 INFO - -d DEST, --destination=DEST 18:03:49 INFO - Directory to install application into. [default: 18:03:49 INFO - "/builds/slave/test"] 18:03:49 INFO - --app=APP Application being installed. [default: firefox] 18:03:49 INFO - mkdir: /builds/slave/test/build/application 18:03:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 18:03:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 18:04:07 INFO - Reading from file tmpfile_stdout 18:04:07 INFO - Output received: 18:04:07 INFO - /builds/slave/test/build/application/firefox/firefox 18:04:07 INFO - Running post-action listener: _resource_record_post_action 18:04:07 INFO - [mozharness: 2017-08-25 01:04:07.324993Z] Finished install step (success) 18:04:07 INFO - [mozharness: 2017-08-25 01:04:07.325279Z] Running setup-mitmproxy step. 18:04:07 INFO - Running pre-action listener: _resource_record_pre_action 18:04:07 INFO - Running main action method: setup_mitmproxy 18:04:07 INFO - Skipping: mitmproxy is not required 18:04:07 INFO - Running post-action listener: _resource_record_post_action 18:04:07 INFO - [mozharness: 2017-08-25 01:04:07.326408Z] Finished setup-mitmproxy step (success) 18:04:07 INFO - [mozharness: 2017-08-25 01:04:07.326630Z] Running run-tests step. 18:04:07 INFO - Running pre-action listener: _resource_record_pre_action 18:04:07 INFO - Running pre-action listener: _set_gcov_prefix 18:04:07 INFO - Running main action method: run_tests 18:04:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:04:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:04:07 INFO - Python 2.7.3 18:04:07 INFO - Return code: 0 18:04:07 INFO - grabbing minidump binary from tooltool 18:04:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:04:07 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 0x14ea130>, '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 0x14eb030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14eb4c0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:04:07 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 18:04:07 INFO - Copy/paste: /tools/tooltool.py --url https://tooltool.mozilla-releng.net/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 18:04:07 INFO - Calling ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 18:04:07 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:04:07 INFO - Return code: 0 18:04:07 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 18:04:07 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:04:07 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:04:07 INFO - ENV: RUST_BACKTRACE is now full 18:04:07 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 18:04:07 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:04:07 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/Amqe4HB8QW2hqpHBtZHK2w/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-027', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 18:04:07 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/Amqe4HB8QW2hqpHBtZHK2w/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-027 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 18:04:07 INFO - Using env: {'DISPLAY': ':0', 18:04:07 INFO - 'HOME': '/home/cltbld', 18:04:07 INFO - 'LANG': 'en_US.UTF-8', 18:04:07 INFO - 'LANGUAGE': 'en_US:en', 18:04:07 INFO - 'LOGNAME': 'cltbld', 18:04:07 INFO - 'MAIL': '/var/mail/cltbld', 18:04:07 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:04:07 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:04:07 INFO - 'MOZ_ACCELERATED': '1', 18:04:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:04:07 INFO - 'MOZ_NO_REMOTE': '1', 18:04:07 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:04:07 INFO - 'MOZ_WEBRENDER': '1', 18:04:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:04:07 INFO - 'NO_EM_RESTART': '1', 18:04:07 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:04:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:04:07 INFO - 'PWD': '/builds/slave/test', 18:04:07 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:04:07 INFO - 'RUST_BACKTRACE': 'full', 18:04:07 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 18:04:07 INFO - 'SHELL': '/bin/bash', 18:04:07 INFO - 'SHLVL': '1', 18:04:07 INFO - 'TERM': 'linux', 18:04:07 INFO - 'TMOUT': '86400', 18:04:07 INFO - 'USER': 'cltbld', 18:04:07 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1503622976.524054-895464963', 18:04:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:04:07 INFO - '_': '/tools/buildbot/bin/python'} 18:04:07 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/Amqe4HB8QW2hqpHBtZHK2w/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-027', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 18:04:07 INFO - mozversion application_buildid: 20170825001342 18:04:07 INFO - mozversion application_changeset: 3199bacd6b3827d711155a9e81c081434cf7cd8f 18:04:07 INFO - mozversion application_display_name: Nightly 18:04:07 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:04:07 INFO - mozversion application_name: Firefox 18:04:07 INFO - mozversion application_remotingname: firefox 18:04:07 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 18:04:07 INFO - mozversion application_vendor: Mozilla 18:04:07 INFO - mozversion application_version: 57.0a1 18:04:07 INFO - mozversion platform_buildid: 20170825001342 18:04:07 INFO - mozversion platform_changeset: 3199bacd6b3827d711155a9e81c081434cf7cd8f 18:04:07 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 18:04:07 INFO - mozversion platform_version: 57.0a1 18:04:07 INFO - using testdate: 1503623047 18:04:07 INFO - actual date: 1503623047 18:04:07 INFO - starting webserver on 'localhost:43829' 18:04:07 INFO - SUITE-START | Running 2 tests 18:04:07 INFO - TEST-START | dromaeo_css 18:04:07 INFO - Initialising browser for dromaeo_css test... 18:04:07 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:43829/getInfo.html -profile /tmp/tmpTEQPVV/profile 18:04:07 INFO - TEST-INFO | started process 16455 (/builds/slave/test/build/application/firefox/firefox http://localhost:43829/getInfo.html) 18:04:13 INFO - TEST-INFO | 16455: exit 0 18:04:13 INFO - Browser initialized. 18:04:13 INFO - Running cycle 1/1 for dromaeo_css test... 18:04:13 INFO - TEST-INFO | started process 16640 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpTEQPVV/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 18:04:14 INFO - PID 16640 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 18:04:14 INFO - PID 16640 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 18:04:15 INFO - PID 16640 | 18:04:15 INFO - PID 16640 | (/builds/slave/test/build/application/firefox/firefox:16700): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 18:04:15 INFO - PID 16640 | 18:04:15 INFO - PID 16640 | 18:04:15 INFO - PID 16640 | (/builds/slave/test/build/application/firefox/firefox:16758): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 18:04:15 INFO - PID 16640 | 18:04:15 INFO - PID 16640 | __metrics Screen width/height:1600/1200 18:04:15 INFO - PID 16640 | colorDepth:24 18:04:15 INFO - PID 16640 | Browser inner width/height: 1024/768 18:04:15 INFO - PID 16640 | __metrics 18:04:25 INFO - PID 16640 | RSS: Main: 200368128 18:04:25 INFO - PID 16640 | 18:04:28 INFO - PID 16640 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 18:07:22 INFO - PID 16640 | Cycle 1(1): loaded http://localhost:43829/tests/dromaeo/cssquery-dojo.html (next: http://localhost:43829/tests/dromaeo/cssquery-ext.html) 18:07:24 INFO - PID 16640 | RSS: Main: 211771392 18:07:24 INFO - PID 16640 | 18:10:26 INFO - PID 16640 | Cycle 1(1): loaded http://localhost:43829/tests/dromaeo/cssquery-ext.html (next: http://localhost:43829/tests/dromaeo/cssquery-jquery.html) 18:10:27 INFO - PID 16640 | RSS: Main: 208994304 18:10:27 INFO - PID 16640 | 18:13:29 INFO - PID 16640 | Cycle 1(1): loaded http://localhost:43829/tests/dromaeo/cssquery-jquery.html (next: http://localhost:43829/tests/dromaeo/cssquery-mootools.html) 18:13:30 INFO - PID 16640 | RSS: Main: 211259392 18:13:30 INFO - PID 16640 | 18:16:32 INFO - PID 16640 | Cycle 1(1): loaded http://localhost:43829/tests/dromaeo/cssquery-mootools.html (next: http://localhost:43829/tests/dromaeo/cssquery-prototype.html) 18:16:32 INFO - PID 16640 | RSS: Main: 210247680 18:16:32 INFO - PID 16640 | 18:19:36 INFO - PID 16640 | Cycle 1(1): loaded http://localhost:43829/tests/dromaeo/cssquery-prototype.html (next: http://localhost:43829/tests/dromaeo/cssquery-yui.html) 18:19:37 INFO - PID 16640 | RSS: Main: 214511616 18:19:37 INFO - PID 16640 | 18:22:39 INFO - PID 16640 | Cycle 1(1): loaded http://localhost:43829/tests/dromaeo/cssquery-yui.html (next: http://localhost:43829/tests/dromaeo/cssquery-dojo.html) 18:22:39 INFO - PID 16640 | RSS: Main: 214142976 18:22:39 INFO - PID 16640 | 18:22:39 INFO - PID 16640 | __start_tp_report 18:22:39 INFO - PID 16640 | _x_x_mozilla_page_load 18:22:39 INFO - PID 16640 | _x_x_mozilla_page_load_details 18:22:39 INFO - PID 16640 | |i|pagename|runs| 18:22:39 INFO - PID 16640 | |0;dojo.html;3211.5503829463864;3244.951325730113;3269.182704323919;3287.3918325109858;3296.077098412445;6152.723127459264;6256.718447669854;6292.496600271978;6300.338464461234;6317.905231421532;6036.3360030396625;6136.294326152494;6149.446549810516;6185.6597887116195;6200.658963756991;5676.120201368794;5677.829665110043;5785.074388097905;5791;5848.356680765112;5712;5737.540996720273;5778.4799368056865;5789.623674461147;5796.5652576056755;144.5539767899923;5566.888662226753;5606.943930560703;5752.913706294409;5764;5641.210230567724;5681.488666020078;5702.173184888168;5744.827655170351;5753.942460575425;5634.04221282383;5673.8297851064535;5739.16782066598;5756.827295181151;5757.107648314518;5990.251218597676;6114.847128821771;6224.937750622481;6277.843053923642;6279.403456671609;6527.902081468782;6635.767748128792;6647.202335719745;6719.092922455455;6762.087118239024;6474.643894585796;6601.405873471411;6650;6697.93302066978;6775.491838112112;6105.297890742577;6106.45041946227;6177.104319873638;6182.96908515459;6184.907226391652;6756.594604323658;6760.087388202628;6788.2193547742645;6809.557378770463;6811.386975172259;6435.517336199804;6470.579412338183;6472.482201423874;6477.870442591122;6487.091807147003;5837.737301821428;6523.934760652428;6573.5398522103;6576.243731970789;6706.865862682719;6568.704408301638;6577.046328282372;6703.765368212089;6720.32796720324;6721.66391680414;6439.967800161063;6606.075149479077;6639.136912201383;6660.333966603398;6689.966550167218;4876.683015603974;4885.047415753894;4894.045661096124;4905.632077594195;4961.107000739901;4315.546867578859;4358.150160718629;4393.6704747144095;4410.20616289071;4424.535423780521;5771.220885180447;5771.422857714279;5842.123681447817;5853.7365818538265;5858.970705146447;6467.191601049869;6570;6628.171478565179;6642.7342906284175;6757.594544327356;5674.1488776683;5675.631083979611;5716.056850619684;5725.6850873202375;5726.3128424588485;5634.098544232903;5648.548116150698;5657.773689052392;5674.773009079673;5675.716214189274;6278.591891527036;6325.841853953688;6365.2679941806355;6392.232932048091;6397.040443933354;6670.132882725215;6716.059751634775;6716.429103526255;6795.72817087311;6796.524243302922;6321.841953951188;6347.174867267226;6387.329330420239;6464.935350646621;6500.154979852589;6459.709313081016;6541.8691626168165;6550.508711846535;6599.373059559334;6646.468282537482;6648.667566621746;6669.233038200669;6695;6743.662816859235;6757.121574195323;6474.384933431316;6480.027995800668;6505.381988711065;6560.343965603402;6562.868742625216;6279.560430769985;6455.580387274812;6473.666816291767;6546.443552297917;6547.738090476328;6307.684615769285;6311.431971122621;6322.988321868479;6342.7145778441;6343.0485427186295;6437.163168788027;6466.159399278064;6471.385218404244;6498.057781621733;6522.5760325578685;6246.156768836331;6305.716242768995;6329.367063293634;6361.077643741539;6369.299377068612 18:22:39 INFO - PID 16640 | |1;ext.html;751.4814777803315;818.5702506184256;860.2687715441873;886.1537231943497;888.8933328000641;38905.22189556214;38961.22077558447;39321.60678393219;39350.80324598373;39546.406803897895;35749.2850142997;36195.638043619554;36206.27587448256;36223.45664815023;36342.27315453696;6846.5549739267035;6942.757003504878;6997.510174287802;7034.507584469089;7043.119610048744;6839.965800170992;6940.68766905488;6990.231074581792;7014.123234595676;7019.262977387378;7564.62176891156;7762.534247945113;7767.067951845786;7767.262110099531;7772.961135194344;7619.199984001655;7663.655135518915;7725.768226953172;7735.18780528042;7736.729214477523;7526.661300241502;7610.657520411595;7641.19767424418;7688.500247483896;7689.769306920802;36521.817390913144;36547.451788222934;36782;37073.629263707284;37280.44079338812;3444.431668774649;3487.145649315934;3489.825508724579;3492.9650703492894;3493.7379696522607;31008;31085.823992320547;31254.21864453384;31285.530717039263;31313.217169570715;5001.924971125436;5098.541131298164;5102.591792656615;5106.514881086291;5192.169252919514;204844;211107;212398;212540;212917;46180.5381946183;46341.30488042682;46353.768231158625;46399.76800115978;46432.7678361606;48739.756301218265;48756.5124348752;48791.75604121957;48801.267980980316;48814.75592622085;15502.147381894105;15698.450554230547;15837.920810395874;15846.841531584767;15865;13116.08187426871;13130.212187268795;13130.803037954438;13139.60581182566;13149.671258218621;13239.470421183238;13437.193768373929;13484.39320230592;13523.256220907748;13642.658933526543;5379.408265090837;5441.972790136024;5504.22940788282;5512.228288039678;5575.498205161555;972.5526257921294;993.4486360070086;994.2096033653263;1000.8698869146964;1004.2919741582167;21417.46456338604;21554.353369398945;21568.784312156993;21976.560468790536;22016.44958876041;1095.118429664112;1098.9725256868642;1108.7671588966246;1110.3670907582762;1111.4442778610694;11483;11684.591039313584;11761.88238117625;11824.586139485076;11916.761664766656;219472;220484;221190;221306;221683;41135.97160070962;48228.758856206194;48527.75736121297;48653.75673121612;48946;1411.5553600615774;1423.8220222472191;1423.964400889986;1427.7358688642635;1430.6208854653526;26654.46691066129;26823.597646035716;26912.865435672695;27207;27780.44439111247;47709;47803.52196477991;48168.5183148164;48219.03561928812;48588.75705621449;198978;200536;200901;201125;203689;32187.51718724268;34072.82963585166;34368.31263374668;34456.65543344635;34495;42405.787971059945;42768;42855.57144428516;42997.785011074746;43012.78493607512;30972.690273097884;31189.220269493446;31510.212244694067;31695.52456713105;31804;31309.53035704421;31321.530177046905;31409.214769630027;31526.527102093947;31726.36547269088;35122.82438587791;35290.47064294089;35465.46801798027;35648.287034258654;35691.64308356987;30154.396912062075;30901;30939.381212376076;31012.379752405275;31126.53310200394;21185.987331203512;22841.31476055721;22870.313890583308;23183.53632927298;23215.651765223873 18:22:39 INFO - PID 16640 | |2;jquery.html;546.3225600255681;549.8185598752412;553.8559974406653;561.5984571031712;567.1011446856733;21212.99999999998;21979.23072692456;22002.999999999978;22148.114075437003;22161.55676886461;21704.457388565294;23296.767032329673;23452.179173728924;23486.412839678975;23537.411564710892;6426.228852537679;6569.671516424183;6574.704138313763;6590.176227971504;6611.909035009242;6433.067205255235;6465;6485.383888530582;6487.7729279475225;6538.280789113193;6264.968675156618;6509.707063182144;6523.05415714721;6537.182852143482;6557.01644753286;6522.706478208468;6539.280679125292;6539.378759017885;6560.442362399202;6564.474842012628;5986.132010858401;6054.273487181523;6058.606190597597;6076.878462430771;6088.726007329681;28021.439571208666;28641.57037644437;28836.134915952556;29492.41015179706;29674.851625741732;1988.145097608028;2057.3210840422626;2058.084152552115;2058.783827698085;2059.660156074246;19113.426597202106;19525.609487810165;19768.20927162912;19793.307234246877;19832.30586929465;2324.732655744594;2373.2049763329305;2397.30478161333;2414.6740190074293;2430.1251549442186;129105;132202;132408;133019.33490332583;135375.32312338473;13560.932195339157;13969;14322.427102915975;14423.92788036053;14864;22581.4354641132;22666.093356265894;22704.31887043391;22751.317460476213;22766.886165569067;9892.15916647093;9964.701058968243;10025.298229124037;10039.69880903574;10048.949755251177;9278.211352035018;9300.11648893354;9356.953215234016;9367.718968430958;9377.156055954996;8526.616302266413;8608.956955215184;8721.912780872237;9288.953555232316;9393.624255029723;2572.897084116614;2610.4126571521256;2621.2922510921944;2647;2687.2207059952784;915.839728047589;947.1522986926662;952.8094381123665;966.4974213408988;975.0834215837232;18718.438446846616;18927.81072189288;19109.235630574898;19155.61688766217;19390.03049847502;958.5590628311055;1005.8189525885265;1006.285537268533;1017.3641474078701;1035.1304903880775;10201.642942497125;10514.684559463229;10572.894271057345;10701.625443109453;10844.620438284779;483856;491977;496613;496718;506482;105844;106421;108454.45772771086;111554;112314;1130.8021096308278;1137.8805225451208;1161.6573110932295;1172.302480024384;1177.0465922602718;17182.828171718123;17358.218880150172;17463.650726985135;17533.298668053645;18007.639847203245;53976;54937;56910.7235710567;56968.43031569797;57767;67344.32655673381;68419;68988.31011690019;69615;70103;11363.318200907972;13824.308784560933;14169.929150354183;14270.143791372551;14505.854941450873;22271.109155633592;22548.436289092904;22640.094396223965;22818.771812281666;23018.769812301663;17624.735628965318;17736.467905962843;17936;18356.35752748701;18477.90761046186;16124.435644752377;16140.757888631915;16834.57913552122;16847.831521684628;17197.828021720125;17879.642407152045;18702.719459207852;18853.528661783017;18854.905725471286;19105.235790568222;13956.651083722989;14019.789703154665;14028;14128.646783830487;14166.43334266659;17882.731759023365;18114.909425452788;18163.45509634713;18272.177752000927;18334.541636459195 18:22:39 INFO - PID 16640 | |3;mootools.html;1314.5267703626694;1351.0610125962453;1358.6195865157756;1362.80920671106;1368.9041767076303;14442.927785361071;14703.558893233194;15004.774928376082;15042.473513427032;15067.698646027073;14084.29578521075;14856;14986.25068746561;14998.625034374147;15022.624434389145;2621.1350254415997;2671.759541641252;2674.291312802109;2674.692410372803;2694.393761403678;2552.4639825636546;2654.0578094776365;2704.256329509381;2707.891684332634;2708.8374697518116;2229.7435794883636;2306.4925716342377;2312.7571604981486;2635.3279913622;2724.4006318609872;2594.2087663262605;2603.791696664272;2615.712271650117;2623.435961268327;2626.487834872201;2643.286312695581;2657.1762753546464;2666.560017597094;2686.0867305116158;2709.7425744554234;14243.287835608282;14311.499097531536;14331.06848054873;14333;14405.279736013263;7392.482526223166;7655.770326890202;7681.462297639167;7689.19263477338;7728.613569321511;4995.125852975751;5008.799648014112;5185.222216667492;5289.814856480043;5314.654547454442;1858.8977606231788;1863.8974856382893;1880.7366968624403;1884.9151788169565;1930.9613807723908;24273.635895461583;24656.26031219066;24861.378465538324;24976.50046999068;25019.874900625564;6764.695588698422;6803.115594972622;6863.588184708933;6946.027556142144;7011.754588589375;19072.237110515423;19080.71378929317;19090;19131.90434047821;19156.137973791214;7608.2011388804485;7757;7757.612119394008;7776.144624091346;7776.455648104578;6794.422474089758;6937.479689023344;6987.406070483964;7034.824129396704;7052.788416347517;5360.249565060816;5430.91853622196;5440.863978400493;5533.08704063833;5533.253010843565;1438.439008786574;1668.5911951571943;1674.137819023204;1675.3633619224618;1723.586339278564;860.7589874835056;875.5097145598485;884.1556313720382;896.5069211933411;907.7004588485784;8510.914890851136;8770.824583508294;8881.600327985256;8961.014288428267;9028.322875784212;945.9148676619137;985.3299756165733;995.7311525888115;1000.9402470511488;1009.6415772400838;5118;5134.640575159703;5156.226566015052;5249.501297376743;5460.044492213847;19738;20123.295684650966;20670.58658826815;20823.167073316898;20999;20391;20438.59122817535;20442.28452004173;20462.693059604117;20475;10299.74250643725;10343.689689309333;10769.461526923622;11107.55569777216;11142.498587563417;8324.25081742646;8327.95836020816;8414.915850841413;8503.1921967413;8684.609192586222;17331.480055598353;18071.909640451715;18180.000099994628;18240.99674517914;18480;17802;18028.188731506852;18052.458426247234;18561.814381856548;18562.164702588685;11682.532698692297;11698.883011169779;12094.516219351128;12103.394830258276;12123.393830308272;13441.462341506622;13595.184288942695;13618.795718064435;13676.863231367559;13742.93128534351;10865.728356791144;11026.724331891766;11145.164112691418;11222.887771122185;11250.66248012561;10634.89365106339;10789.352638841694;10854.620088296871;11047.502862371373;11108.722281943015;9669.274804389559;9685.515724213901;9687.757806054906;9758.75603109928;9770.120689138012;7143.357097861218;7156.1770396403;7174.605396703231;7223.711051557879;7297.16082650502;7218.27817218274;7290.526115802456;7304.744333948286;7338.706451742084;7380.409567234501 18:22:39 INFO - PID 16640 | |4;prototype.html;499.29275132143823;503.1459073314835;509.5363219470283;519.7141572135325;526.5629527492182;6201.286852012008;6203.813885583429;6240.812775616733;6294.937050629493;6314.873702525947;5969.462748352647;6052.273727152748;6129.969350153254;6218.440340369366;6242.875142497147;5019.598432125439;5029.924551131727;5030.346055012843;5062.949370506303;5076.822311219108;4801.399825021872;4952.207646776516;5023.824166154185;5026.29631851541;5056.974715126437;4930.235813448921;4934.989656309666;4954.653174277802;4996.475370086143;4998.650634201919;4974.975125124387;4986;5033.11920413928;5036.647434679574;5041.773120209599;4480.529544397824;4487.551244875486;4494.16857881293;4542.046170304207;4542.273236282212;6009.673035414538;6129.877402451971;6173.919564076314;6211.596246243979;6213.6951240239605;5698.601097923147;5772.393898640625;5787.971060144714;5788.97105514474;5795.27622017651;6528.249251336109;6601.3728695773825;6639.734410623618;6656.534042617019;6665.300143484913;4784.928226076611;4851.345068415754;4865.6594038417325;4920.901581968341;4939.604831613462;6858.691358888812;6886.965565172142;6901.965490172517;6919.8962015569805;7019.754308599226;4948.1835497142565;4962.503749625009;4981.90036199274;5010.674306170087;5038.193888977746;5106;5112.9744351278005;5132.897342053138;5146.588272938156;5150.351887396072;5647.237622920935;5664.640486283262;5704;5704.771809127588;5769.88460230793;6630.436412904861;6681.265060843303;6692.59844409337;6694.196696396463;6705.798826035227;1650.7276299410703;1699.507142928537;1700.4218565687725;1745.7119575270192;1747.4932269641915;1095.3427943234155;1210.661014915825;1210.8365370674846;1220.4385982448173;1226.6074856046157;5683.175939488751;5704;5753.568482363839;5770.19217309577;5788.929048126026;6557.5081868860025;6609.702563384659;6691.464682825363;6767;6789.898151527731;5715.485606295453;5764.337101233328;5771.00900189434;5778.739956701958;5789.189513468118;3558.491263659114;3583.695385892176;3590.8922732318074;3621.4386770050683;3628.129248980226;6410.166678331787;6442.323556026549;6502.2197336318995;6520.836979075561;6524.151860258136;6949.409300209539;6988.545744526691;7026.789196324118;7054.470914681317;7111.64441777909;6538.705758240986;6586.242582103024;6597.47220222381;6599.307072757388;6600.339966003361;6696.83257918556;6782.762603309058;6827.283135270725;6832.760853370109;6836.316368363124;6583.506237032146;6659.866802664016;6772;6812.773700733916;6858.348456896587;6425.421712045938;6507.642079685474;6514.54398192141;6540.672966351759;6561.770338038145;4964.280179373898;4968.254761785761;4981.576565991932;4999.600031997359;5009.974950125372;6738.427233685192;6766.086578311865;6838.7857706877685;6907.89638155418;6926.549774264657;6463.192100987377;6575.901361479678;6621.1392518972225;6706.853806781783;6717.429018533284;6526.608403495616;6612.272650008399;6619.97390404488;6655.600663960178;6689.632070235989;6219.40915613016;6295.370462953668;6465.321141280286;6488.837779055561;6534.215894092739;6262.874742505215;6431.273014334554;6496.480281577557;6545.541812073109;6556.704948277244;6270.435660790368;6349.777757778455;6376;6376.713047912948;6396.872062558816 18:22:39 INFO - PID 16640 | |5;yui.html;869.2828416556339;891.2513488669516;901.7591285655142;907.0430695616125;915.4644532948225;18688.345907893225;19650.7052394214;19711.408657740256;19733.01334933253;20918.26786062488;20097.799022009774;21093;21207;21370.89314553425;21416.78583214171;931.8602209668553;951.6859436386011;956.2541217850053;956.9904300956971;957.5164541906328;920.2086205862953;965.9227261819037;968.4044312747644;970.6311601591385;971.7230589282055;996.9302148849583;1002.6039714312842;1006.2050979726008;1006.7684432580511;1007.30999265503;990.1089019882128;993.3394292795282;995.5221493683036;998.8052329795693;999.720078378048;719.9208087110413;725.6371814092954;725.9854802903913;732.2787054751066;734.295076726338;18221.63556728858;18334;19287.324943626907;19569;19724.211031558865;20754.377368678965;20897.37307880766;21156;21169.259075932423;21310.893445532674;15672.686546269124;16225.756613650805;16273.593160170973;16364.34542618294;16543.751843722235;3628.7097032237357;3660.4509323601405;3664.4320130379833;3666.926661466782;3699.9640100771826;121323;121774.39112804379;124642;127452.36273818664;127562.08656870248;14937.925310373595;15528.611784705472;15580.298886550132;15624.609384765472;15703.842961570466;28954.27614309659;29401.705982939897;29794.25514362115;29801;29916.252093697833;8367.414281000272;8525.40322177454;8572.61423235956;8576.614052367533;8585.18440748128;4288.442502474658;4353.455818022747;4380.3210502372185;4382.233109205877;4391.363252328394;4979.950200497949;5026.723530205873;5031.069252188357;5032.521910418504;5052.520010598987;866.1425189062783;879.692107762278;882.9426087304305;887.671561522241;892.1435421994958;674.2785219814751;684.1892357556212;698.3470455124475;700.9859802803916;701.9754308599174;10567.72793849326;11458.197926145256;11660.650180494598;12018.759624807455;12094.395280235953;639.5107742576932;694.7915625312487;699.7271064284932;718.0772707071388;719.2986837833071;5331.306930099062;5389.305347326337;5410.567154627621;5577.107662773937;5607.542039069871;108944.4552777231;109296;111035;112313;114569;97896.51051744695;98391.50804245933;99779.50110249402;102151;103339.483302583;82082;83598;83679;86012.56993715116;86216.13783862206;10315.793684126425;10342.896571034495;10397.948010259901;10435.752409782619;10502.422366769595;38709.2258154841;39996;40724.796376017934;40735.1852962945;40883.59116408798;50273.49726502688;51249.487505125966;51800.74099629478;53060;53458.73270633622;15610.063396196265;15631.29659165363;16005.279762410486;16110.838891610934;16175.110368929822;17323.307067717655;17602.29590816353;17668.38160664422;17814.198361074043;17864.374746883797;15899.841001589835;16056.67886642284;16225.432109876097;16381;16490;15888.682226355639;16025.599360016093;16077.19614019271;16325.265363058928;16413.26140323712;15497.84502154964;15565.221738913235;15621.687566248838;15863.127527986071;15865;11515.596954106566;11531.711707207387;11533.82699259495;11609.071274298205;11685.065194784227;11224.887751122711;11613.535458581562;11633.534658613899;11650.126240531825;11664.416779160838 18:22:39 INFO - PID 16640 | __end_tp_report 18:22:39 INFO - PID 16640 | __start_cc_report 18:22:39 INFO - PID 16640 | _x_x_mozilla_cycle_collect,379 18:22:39 INFO - PID 16640 | __end_cc_report 18:22:39 INFO - PID 16640 | __startTimestamp1503624159630__endTimestamp 18:22:39 INFO - PID 16640 | 18:22:39 INFO - PID 16640 | ------- Summary: start ------- 18:22:39 INFO - PID 16640 | Number of tests: 6 18:22:39 INFO - PID 16640 | 18:22:39 INFO - PID 16640 | [#0] dojo.html Cycles:165 Average:6051.54 Median:6321.84 stddev:867.11 (13.7%) stddev-sans-first:840.66 18:22:39 INFO - PID 16640 | Values: 3211.6 3245.0 3269.2 3287.4 3296.1 6152.7 6256.7 6292.5 6300.3 6317.9 6036.3 6136.3 6149.4 6185.7 6200.7 5676.1 5677.8 5785.1 5791.0 5848.4 5712.0 5737.5 5778.5 5789.6 5796.6 144.6 5566.9 5606.9 5752.9 5764.0 5641.2 5681.5 5702.2 5744.8 5753.9 5634.0 5673.8 5739.2 5756.8 5757.1 5990.3 6114.8 6224.9 6277.8 6279.4 6527.9 6635.8 6647.2 6719.1 6762.1 6474.6 6601.4 6650.0 6697.9 6775.5 6105.3 6106.5 6177.1 6183.0 6184.9 6756.6 6760.1 6788.2 6809.6 6811.4 6435.5 6470.6 6472.5 6477.9 6487.1 5837.7 6523.9 6573.5 6576.2 6706.9 6568.7 6577.0 6703.8 6720.3 6721.7 6440.0 6606.1 6639.1 6660.3 6690.0 4876.7 4885.0 4894.0 4905.6 4961.1 4315.5 4358.2 4393.7 4410.2 4424.5 5771.2 5771.4 5842.1 5853.7 5859.0 6467.2 6570.0 6628.2 6642.7 6757.6 5674.1 5675.6 5716.1 5725.7 5726.3 5634.1 5648.5 5657.8 5674.8 5675.7 6278.6 6325.8 6365.3 6392.2 6397.0 6670.1 6716.1 6716.4 6795.7 6796.5 6321.8 6347.2 6387.3 6464.9 6500.2 6459.7 6541.9 6550.5 6599.4 6646.5 6648.7 6669.2 6695.0 6743.7 6757.1 6474.4 6480.0 6505.4 6560.3 6562.9 6279.6 6455.6 6473.7 6546.4 6547.7 6307.7 6311.4 6323.0 6342.7 6343.0 6437.2 6466.2 6471.4 6498.1 6522.6 6246.2 6305.7 6329.4 6361.1 6369.3 18:22:39 INFO - PID 16640 | 18:22:39 INFO - PID 16640 | [#1] ext.html Cycles:180 Average:37588.08 Median:3493.35 stddev:54662.33 (1564.8%) stddev-sans-first:54745.70 18:22:39 INFO - PID 16640 | Values: 751.5 818.6 860.3 886.2 888.9 38905.2 38961.2 39321.6 39350.8 39546.4 35749.3 36195.6 36206.3 36223.5 36342.3 6846.6 6942.8 6997.5 7034.5 7043.1 6840.0 6940.7 6990.2 7014.1 7019.3 7564.6 7762.5 7767.1 7767.3 7773.0 7619.2 7663.7 7725.8 7735.2 7736.7 7526.7 7610.7 7641.2 7688.5 7689.8 36521.8 36547.5 36782.0 37073.6 37280.4 3444.4 3487.1 3489.8 3493.0 3493.7 31008.0 31085.8 31254.2 31285.5 31313.2 5001.9 5098.5 5102.6 5106.5 5192.2 204844.0 211107.0 212398.0 212540.0 212917.0 46180.5 46341.3 46353.8 46399.8 46432.8 48739.8 48756.5 48791.8 48801.3 48814.8 15502.1 15698.5 15837.9 15846.8 15865.0 13116.1 13130.2 13130.8 13139.6 13149.7 13239.5 13437.2 13484.4 13523.3 13642.7 5379.4 5442.0 5504.2 5512.2 5575.5 972.6 993.4 994.2 1000.9 1004.3 21417.5 21554.4 21568.8 21976.6 22016.4 1095.1 1099.0 1108.8 1110.4 1111.4 11483.0 11684.6 11761.9 11824.6 11916.8 219472.0 220484.0 221190.0 221306.0 221683.0 41136.0 48228.8 48527.8 48653.8 48946.0 1411.6 1423.8 1424.0 1427.7 1430.6 26654.5 26823.6 26912.9 27207.0 27780.4 47709.0 47803.5 48168.5 48219.0 48588.8 198978.0 200536.0 200901.0 201125.0 203689.0 32187.5 34072.8 34368.3 34456.7 34495.0 42405.8 42768.0 42855.6 42997.8 43012.8 30972.7 31189.2 31510.2 31695.5 31804.0 31309.5 31321.5 31409.2 31526.5 31726.4 35122.8 35290.5 35465.5 35648.3 35691.6 30154.4 30901.0 30939.4 31012.4 31126.5 21186.0 22841.3 22870.3 23183.5 23215.7 18:22:39 INFO - PID 16640 | 18:22:39 INFO - PID 16640 | [#2] jquery.html Cycles:180 Average:34221.74 Median:22610.76 stddev:83022.64 (367.2%) stddev-sans-first:83217.04 18:22:39 INFO - PID 16640 | Values: 546.3 549.8 553.9 561.6 567.1 21213.0 21979.2 22003.0 22148.1 22161.6 21704.5 23296.8 23452.2 23486.4 23537.4 6426.2 6569.7 6574.7 6590.2 6611.9 6433.1 6465.0 6485.4 6487.8 6538.3 6265.0 6509.7 6523.1 6537.2 6557.0 6522.7 6539.3 6539.4 6560.4 6564.5 5986.1 6054.3 6058.6 6076.9 6088.7 28021.4 28641.6 28836.1 29492.4 29674.9 1988.1 2057.3 2058.1 2058.8 2059.7 19113.4 19525.6 19768.2 19793.3 19832.3 2324.7 2373.2 2397.3 2414.7 2430.1 129105.0 132202.0 132408.0 133019.3 135375.3 13560.9 13969.0 14322.4 14423.9 14864.0 22581.4 22666.1 22704.3 22751.3 22766.9 9892.2 9964.7 10025.3 10039.7 10048.9 9278.2 9300.1 9357.0 9367.7 9377.2 8526.6 8609.0 8721.9 9289.0 9393.6 2572.9 2610.4 2621.3 2647.0 2687.2 915.8 947.2 952.8 966.5 975.1 18718.4 18927.8 19109.2 19155.6 19390.0 958.6 1005.8 1006.3 1017.4 1035.1 10201.6 10514.7 10572.9 10701.6 10844.6 483856.0 491977.0 496613.0 496718.0 506482.0 105844.0 106421.0 108454.5 111554.0 112314.0 1130.8 1137.9 1161.7 1172.3 1177.0 17182.8 17358.2 17463.7 17533.3 18007.6 53976.0 54937.0 56910.7 56968.4 57767.0 67344.3 68419.0 68988.3 69615.0 70103.0 11363.3 13824.3 14169.9 14270.1 14505.9 22271.1 22548.4 22640.1 22818.8 23018.8 17624.7 17736.5 17936.0 18356.4 18477.9 16124.4 16140.8 16834.6 16847.8 17197.8 17879.6 18702.7 18853.5 18854.9 19105.2 13956.7 14019.8 14028.0 14128.6 14166.4 17882.7 18114.9 18163.5 18272.2 18334.5 18:22:39 INFO - PID 16640 | 18:22:39 INFO - PID 16640 | [#3] mootools.html Cycles:180 Average:9156.48 Median:2573.34 stddev:6419.15 (249.4%) stddev-sans-first:6410.11 18:22:39 INFO - PID 16640 | Values: 1314.5 1351.1 1358.6 1362.8 1368.9 14442.9 14703.6 15004.8 15042.5 15067.7 14084.3 14856.0 14986.3 14998.6 15022.6 2621.1 2671.8 2674.3 2674.7 2694.4 2552.5 2654.1 2704.3 2707.9 2708.8 2229.7 2306.5 2312.8 2635.3 2724.4 2594.2 2603.8 2615.7 2623.4 2626.5 2643.3 2657.2 2666.6 2686.1 2709.7 14243.3 14311.5 14331.1 14333.0 14405.3 7392.5 7655.8 7681.5 7689.2 7728.6 4995.1 5008.8 5185.2 5289.8 5314.7 1858.9 1863.9 1880.7 1884.9 1931.0 24273.6 24656.3 24861.4 24976.5 25019.9 6764.7 6803.1 6863.6 6946.0 7011.8 19072.2 19080.7 19090.0 19131.9 19156.1 7608.2 7757.0 7757.6 7776.1 7776.5 6794.4 6937.5 6987.4 7034.8 7052.8 5360.2 5430.9 5440.9 5533.1 5533.3 1438.4 1668.6 1674.1 1675.4 1723.6 860.8 875.5 884.2 896.5 907.7 8510.9 8770.8 8881.6 8961.0 9028.3 945.9 985.3 995.7 1000.9 1009.6 5118.0 5134.6 5156.2 5249.5 5460.0 19738.0 20123.3 20670.6 20823.2 20999.0 20391.0 20438.6 20442.3 20462.7 20475.0 10299.7 10343.7 10769.5 11107.6 11142.5 8324.3 8328.0 8414.9 8503.2 8684.6 17331.5 18071.9 18180.0 18241.0 18480.0 17802.0 18028.2 18052.5 18561.8 18562.2 11682.5 11698.9 12094.5 12103.4 12123.4 13441.5 13595.2 13618.8 13676.9 13742.9 10865.7 11026.7 11145.2 11222.9 11250.7 10634.9 10789.4 10854.6 11047.5 11108.7 9669.3 9685.5 9687.8 9758.8 9770.1 7143.4 7156.2 7174.6 7223.7 7297.2 7218.3 7290.5 7304.7 7338.7 7380.4 18:22:39 INFO - PID 16640 | 18:22:39 INFO - PID 16640 | [#4] prototype.html Cycles:180 Average:5536.08 Median:6187.60 stddev:1564.85 (25.3%) stddev-sans-first:1522.89 18:22:39 INFO - PID 16640 | Values: 499.3 503.1 509.5 519.7 526.6 6201.3 6203.8 6240.8 6294.9 6314.9 5969.5 6052.3 6130.0 6218.4 6242.9 5019.6 5029.9 5030.3 5062.9 5076.8 4801.4 4952.2 5023.8 5026.3 5057.0 4930.2 4935.0 4954.7 4996.5 4998.7 4975.0 4986.0 5033.1 5036.6 5041.8 4480.5 4487.6 4494.2 4542.0 4542.3 6009.7 6129.9 6173.9 6211.6 6213.7 5698.6 5772.4 5788.0 5789.0 5795.3 6528.2 6601.4 6639.7 6656.5 6665.3 4784.9 4851.3 4865.7 4920.9 4939.6 6858.7 6887.0 6902.0 6919.9 7019.8 4948.2 4962.5 4981.9 5010.7 5038.2 5106.0 5113.0 5132.9 5146.6 5150.4 5647.2 5664.6 5704.0 5704.8 5769.9 6630.4 6681.3 6692.6 6694.2 6705.8 1650.7 1699.5 1700.4 1745.7 1747.5 1095.3 1210.7 1210.8 1220.4 1226.6 5683.2 5704.0 5753.6 5770.2 5788.9 6557.5 6609.7 6691.5 6767.0 6789.9 5715.5 5764.3 5771.0 5778.7 5789.2 3558.5 3583.7 3590.9 3621.4 3628.1 6410.2 6442.3 6502.2 6520.8 6524.2 6949.4 6988.5 7026.8 7054.5 7111.6 6538.7 6586.2 6597.5 6599.3 6600.3 6696.8 6782.8 6827.3 6832.8 6836.3 6583.5 6659.9 6772.0 6812.8 6858.3 6425.4 6507.6 6514.5 6540.7 6561.8 4964.3 4968.3 4981.6 4999.6 5010.0 6738.4 6766.1 6838.8 6907.9 6926.5 6463.2 6575.9 6621.1 6706.9 6717.4 6526.6 6612.3 6620.0 6655.6 6689.6 6219.4 6295.4 6465.3 6488.8 6534.2 6262.9 6431.3 6496.5 6545.5 6556.7 6270.4 6349.8 6376.0 6376.7 6396.9 18:22:39 INFO - PID 16640 | 18:22:39 INFO - PID 16640 | [#5] yui.html Cycles:180 Average:22673.52 Median:25185.53 stddev:31673.62 (125.8%) stddev-sans-first:31720.16 18:22:39 INFO - PID 16640 | Values: 869.3 891.3 901.8 907.0 915.5 18688.3 19650.7 19711.4 19733.0 20918.3 20097.8 21093.0 21207.0 21370.9 21416.8 931.9 951.7 956.3 957.0 957.5 920.2 965.9 968.4 970.6 971.7 996.9 1002.6 1006.2 1006.8 1007.3 990.1 993.3 995.5 998.8 999.7 719.9 725.6 726.0 732.3 734.3 18221.6 18334.0 19287.3 19569.0 19724.2 20754.4 20897.4 21156.0 21169.3 21310.9 15672.7 16225.8 16273.6 16364.3 16543.8 3628.7 3660.5 3664.4 3666.9 3700.0 121323.0 121774.4 124642.0 127452.4 127562.1 14937.9 15528.6 15580.3 15624.6 15703.8 28954.3 29401.7 29794.3 29801.0 29916.3 8367.4 8525.4 8572.6 8576.6 8585.2 4288.4 4353.5 4380.3 4382.2 4391.4 4980.0 5026.7 5031.1 5032.5 5052.5 866.1 879.7 882.9 887.7 892.1 674.3 684.2 698.3 701.0 702.0 10567.7 11458.2 11660.7 12018.8 12094.4 639.5 694.8 699.7 718.1 719.3 5331.3 5389.3 5410.6 5577.1 5607.5 108944.5 109296.0 111035.0 112313.0 114569.0 97896.5 98391.5 99779.5 102151.0 103339.5 82082.0 83598.0 83679.0 86012.6 86216.1 10315.8 10342.9 10397.9 10435.8 10502.4 38709.2 39996.0 40724.8 40735.2 40883.6 50273.5 51249.5 51800.7 53060.0 53458.7 15610.1 15631.3 16005.3 16110.8 16175.1 17323.3 17602.3 17668.4 17814.2 17864.4 15899.8 16056.7 16225.4 16381.0 16490.0 15888.7 16025.6 16077.2 16325.3 16413.3 15497.8 15565.2 15621.7 15863.1 15865.0 11515.6 11531.7 11533.8 11609.1 11685.1 11224.9 11613.5 11633.5 11650.1 11664.4 18:22:39 INFO - PID 16640 | -------- Summary: end -------- 18:22:39 INFO - PID 16640 | 18:22:40 INFO - TEST-INFO | 16640: exit 0 18:22:40 INFO - TEST-OK | dromaeo_css | took 1112718ms 18:22:40 INFO - TEST-START | kraken 18:22:40 INFO - Initialising browser for kraken test... 18:22:40 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:43829/getInfo.html -profile /tmp/tmpnBXEhT/profile 18:22:40 INFO - TEST-INFO | started process 17059 (/builds/slave/test/build/application/firefox/firefox http://localhost:43829/getInfo.html) 18:22:46 INFO - TEST-INFO | 17059: exit 0 18:22:46 INFO - Browser initialized. 18:22:46 INFO - Running cycle 1/1 for kraken test... 18:22:46 INFO - TEST-INFO | started process 17247 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpnBXEhT/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 18:22:47 INFO - PID 17247 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 18:22:47 INFO - PID 17247 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 18:22:47 INFO - PID 17247 | 18:22:47 INFO - PID 17247 | (/builds/slave/test/build/application/firefox/firefox:17306): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 18:22:47 INFO - PID 17247 | 18:22:48 INFO - PID 17247 | 18:22:48 INFO - PID 17247 | (/builds/slave/test/build/application/firefox/firefox:17365): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 18:22:48 INFO - PID 17247 | 18:22:48 INFO - PID 17247 | __metrics Screen width/height:1600/1200 18:22:48 INFO - PID 17247 | colorDepth:24 18:22:48 INFO - PID 17247 | Browser inner width/height: 1024/768 18:22:48 INFO - PID 17247 | __metrics 18:22:57 INFO - PID 17247 | RSS: Main: 200515584 18:22:57 INFO - PID 17247 | 18:23:00 INFO - PID 17247 | Cycle 1(1): loaded http://localhost:43829/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:43829/tests/kraken/driver.html?testName=audio-beat-detection) 18:23:01 INFO - PID 17247 | RSS: Main: 207495168 18:23:01 INFO - PID 17247 | 18:23:01 INFO - PID 17247 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 18:23:04 INFO - PID 17247 | Cycle 1(1): loaded http://localhost:43829/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:43829/tests/kraken/driver.html?testName=audio-dft) 18:23:04 INFO - PID 17247 | RSS: Main: 210169856 18:23:04 INFO - PID 17247 | 18:23:07 INFO - PID 17247 | Cycle 1(1): loaded http://localhost:43829/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:43829/tests/kraken/driver.html?testName=audio-fft) 18:23:08 INFO - PID 17247 | RSS: Main: 204124160 18:23:08 INFO - PID 17247 | 18:23:10 INFO - PID 17247 | Cycle 1(1): loaded http://localhost:43829/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:43829/tests/kraken/driver.html?testName=audio-oscillator) 18:23:11 INFO - PID 17247 | RSS: Main: 204189696 18:23:11 INFO - PID 17247 | 18:23:13 INFO - PID 17247 | Cycle 1(1): loaded http://localhost:43829/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:43829/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:23:14 INFO - PID 17247 | RSS: Main: 204095488 18:23:14 INFO - PID 17247 | 18:23:17 INFO - PID 17247 | Cycle 1(1): loaded http://localhost:43829/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:43829/tests/kraken/driver.html?testName=imaging-darkroom) 18:23:18 INFO - PID 17247 | RSS: Main: 206053376 18:23:18 INFO - PID 17247 | 18:23:21 INFO - PID 17247 | Cycle 1(1): loaded http://localhost:43829/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:43829/tests/kraken/driver.html?testName=imaging-desaturate) 18:23:22 INFO - PID 17247 | RSS: Main: 206204928 18:23:22 INFO - PID 17247 | 18:23:25 INFO - PID 17247 | Cycle 1(1): loaded http://localhost:43829/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:43829/tests/kraken/driver.html?testName=json-parse-financial) 18:23:25 INFO - PID 17247 | RSS: Main: 206340096 18:23:25 INFO - PID 17247 | 18:23:27 INFO - PID 17247 | Cycle 1(1): loaded http://localhost:43829/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:43829/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:23:28 INFO - PID 17247 | RSS: Main: 206438400 18:23:28 INFO - PID 17247 | 18:23:29 INFO - PID 17247 | Cycle 1(1): loaded http://localhost:43829/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:43829/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:23:30 INFO - PID 17247 | RSS: Main: 205545472 18:23:30 INFO - PID 17247 | 18:23:32 INFO - PID 17247 | Cycle 1(1): loaded http://localhost:43829/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:43829/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:23:33 INFO - PID 17247 | RSS: Main: 206811136 18:23:33 INFO - PID 17247 | 18:23:35 INFO - PID 17247 | Cycle 1(1): loaded http://localhost:43829/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:43829/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:23:36 INFO - PID 17247 | RSS: Main: 207073280 18:23:36 INFO - PID 17247 | 18:23:39 INFO - PID 17247 | Cycle 1(1): loaded http://localhost:43829/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:43829/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:23:40 INFO - PID 17247 | RSS: Main: 206602240 18:23:40 INFO - PID 17247 | 18:23:42 INFO - PID 17247 | Cycle 1(1): loaded http://localhost:43829/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:43829/tests/kraken/driver.html?testName=ai-astar) 18:23:42 INFO - PID 17247 | RSS: Main: 208080896 18:23:42 INFO - PID 17247 | 18:23:42 INFO - PID 17247 | __start_tp_report 18:23:42 INFO - PID 17247 | _x_x_mozilla_page_load 18:23:42 INFO - PID 17247 | _x_x_mozilla_page_load_details 18:23:42 INFO - PID 17247 | |i|pagename|runs| 18:23:42 INFO - PID 17247 | |0;ai-astar;109;116;108;109;108;108;108;102;110;119 18:23:42 INFO - PID 17247 | |1;audio-beat-detection;149;130;154;180;118;160;151;121;181;122 18:23:42 INFO - PID 17247 | |2;audio-dft;167;163;163;164;173;169;170;164;166;168 18:23:42 INFO - PID 17247 | |3;audio-fft;75;84;84;169;79;76;76;74;166;76 18:23:42 INFO - PID 17247 | |4;audio-oscillator;82;84;136;80;90;81;131;85;86;132 18:23:42 INFO - PID 17247 | |5;imaging-gaussian-blur;114;120;115;118;115;116;114;116;115;126 18:23:42 INFO - PID 17247 | |6;imaging-darkroom;151;147;147;150;149;149;149;149;153;149 18:23:42 INFO - PID 17247 | |7;imaging-desaturate;96;99;96;104;94;94;94;100;94;96 18:23:42 INFO - PID 17247 | |8;json-parse-financial;55;53;54;53;54;55;57;57;53;55 18:23:42 INFO - PID 17247 | |9;json-stringify-tinderbox;55;59;55;61;57;58;62;63;56;56 18:23:42 INFO - PID 17247 | |10;stanford-crypto-aes;82;80;79;82;82;87;81;76;75;83 18:23:42 INFO - PID 17247 | |11;stanford-crypto-ccm;125;127;129;129;119;128;119;128;123;156 18:23:42 INFO - PID 17247 | |12;stanford-crypto-pbkdf2;188;173;178;174;180;179;178;171;178;174 18:23:42 INFO - PID 17247 | |13;stanford-crypto-sha256-iterative;69;64;66;67;68;65;67;73;64;66 18:23:42 INFO - PID 17247 | __end_tp_report 18:23:42 INFO - PID 17247 | __start_cc_report 18:23:42 INFO - PID 17247 | _x_x_mozilla_cycle_collect,952 18:23:42 INFO - PID 17247 | __end_cc_report 18:23:42 INFO - PID 17247 | __startTimestamp1503624222523__endTimestamp 18:23:42 INFO - PID 17247 | 18:23:42 INFO - PID 17247 | ------- Summary: start ------- 18:23:42 INFO - PID 17247 | Number of tests: 14 18:23:42 INFO - PID 17247 | 18:23:42 INFO - PID 17247 | [#0] ai-astar Cycles:10 Average:109.70 Median:109.00 stddev:4.69 (4.3%) stddev-sans-first:4.97 18:23:42 INFO - PID 17247 | Values: 109.0 116.0 108.0 109.0 108.0 108.0 108.0 102.0 110.0 119.0 18:23:42 INFO - PID 17247 | 18:23:42 INFO - PID 17247 | [#1] audio-beat-detection Cycles:10 Average:146.60 Median:152.50 stddev:23.36 (15.3%) stddev-sans-first:24.76 18:23:42 INFO - PID 17247 | Values: 149.0 130.0 154.0 180.0 118.0 160.0 151.0 121.0 181.0 122.0 18:23:42 INFO - PID 17247 | 18:23:42 INFO - PID 17247 | [#2] audio-dft Cycles:10 Average:166.70 Median:167.50 stddev:3.33 (2.0%) stddev-sans-first:3.54 18:23:42 INFO - PID 17247 | Values: 167.0 163.0 163.0 164.0 173.0 169.0 170.0 164.0 166.0 168.0 18:23:42 INFO - PID 17247 | 18:23:42 INFO - PID 17247 | [#3] audio-fft Cycles:10 Average:95.90 Median:76.00 stddev:37.90 (49.9%) stddev-sans-first:39.44 18:23:42 INFO - PID 17247 | Values: 75.0 84.0 84.0 169.0 79.0 76.0 76.0 74.0 166.0 76.0 18:23:42 INFO - PID 17247 | 18:23:42 INFO - PID 17247 | [#4] audio-oscillator Cycles:10 Average:98.70 Median:83.00 stddev:23.87 (28.8%) stddev-sans-first:24.54 18:23:42 INFO - PID 17247 | Values: 82.0 84.0 136.0 80.0 90.0 81.0 131.0 85.0 86.0 132.0 18:23:42 INFO - PID 17247 | 18:23:42 INFO - PID 17247 | [#5] imaging-gaussian-blur Cycles:10 Average:116.90 Median:116.00 stddev:3.70 (3.2%) stddev-sans-first:3.77 18:23:42 INFO - PID 17247 | Values: 114.0 120.0 115.0 118.0 115.0 116.0 114.0 116.0 115.0 126.0 18:23:42 INFO - PID 17247 | 18:23:42 INFO - PID 17247 | [#6] imaging-darkroom Cycles:10 Average:149.30 Median:149.00 stddev:1.77 (1.2%) stddev-sans-first:1.76 18:23:42 INFO - PID 17247 | Values: 151.0 147.0 147.0 150.0 149.0 149.0 149.0 149.0 153.0 149.0 18:23:42 INFO - PID 17247 | 18:23:42 INFO - PID 17247 | [#7] imaging-desaturate Cycles:10 Average:96.70 Median:95.00 stddev:3.33 (3.5%) stddev-sans-first:3.53 18:23:42 INFO - PID 17247 | Values: 96.0 99.0 96.0 104.0 94.0 94.0 94.0 100.0 94.0 96.0 18:23:42 INFO - PID 17247 | 18:23:42 INFO - PID 17247 | [#8] json-parse-financial Cycles:10 Average:54.60 Median:55.00 stddev:1.51 (2.7%) stddev-sans-first:1.59 18:23:42 INFO - PID 17247 | Values: 55.0 53.0 54.0 53.0 54.0 55.0 57.0 57.0 53.0 55.0 18:23:42 INFO - PID 17247 | 18:23:42 INFO - PID 17247 | [#9] json-stringify-tinderbox Cycles:10 Average:58.20 Median:58.50 stddev:2.94 (5.0%) stddev-sans-first:2.88 18:23:42 INFO - PID 17247 | Values: 55.0 59.0 55.0 61.0 57.0 58.0 62.0 63.0 56.0 56.0 18:23:42 INFO - PID 17247 | 18:23:42 INFO - PID 17247 | [#10] stanford-crypto-aes Cycles:10 Average:80.70 Median:82.00 stddev:3.47 (4.2%) stddev-sans-first:3.64 18:23:42 INFO - PID 17247 | Values: 82.0 80.0 79.0 82.0 82.0 87.0 81.0 76.0 75.0 83.0 18:23:42 INFO - PID 17247 | 18:23:42 INFO - PID 17247 | [#11] stanford-crypto-ccm Cycles:10 Average:128.30 Median:128.00 stddev:10.45 (8.2%) stddev-sans-first:11.01 18:23:42 INFO - PID 17247 | Values: 125.0 127.0 129.0 129.0 119.0 128.0 119.0 128.0 123.0 156.0 18:23:42 INFO - PID 17247 | 18:23:42 INFO - PID 17247 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:177.30 Median:178.00 stddev:4.79 (2.7%) stddev-sans-first:3.14 18:23:42 INFO - PID 17247 | Values: 188.0 173.0 178.0 174.0 180.0 179.0 178.0 171.0 178.0 174.0 18:23:42 INFO - PID 17247 | 18:23:42 INFO - PID 17247 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.90 Median:67.00 stddev:2.69 (4.0%) stddev-sans-first:2.74 18:23:42 INFO - PID 17247 | Values: 69.0 64.0 66.0 67.0 68.0 65.0 67.0 73.0 64.0 66.0 18:23:42 INFO - PID 17247 | -------- Summary: end -------- 18:23:42 INFO - PID 17247 | 18:23:42 INFO - PID 17247 | 18:23:42 INFO - PID 17247 | ###!!! [Parent][MessageChannel] Error: (msgtype=0x150081,name=PBrowser::Msg_Destroy) Closed channel: cannot send/recv 18:23:42 INFO - PID 17247 | 18:23:42 INFO - PID 17247 | 18:23:42 INFO - PID 17247 | ###!!! [Child][MessageChannel] Error: (msgtype=0x150001,name=PBrowser::Msg_AsyncMessage) Closed channel: cannot send/recv 18:23:42 INFO - PID 17247 | 18:23:43 INFO - PID 17247 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 18:23:43 INFO - TEST-INFO | 17247: exit 0 18:23:43 INFO - TEST-OK | kraken | took 63119ms 18:23:43 INFO - SUITE-END | took 1175s 18:23:43 INFO - Completed test suite (00:19:35) 18:23:43 INFO - javascript benchmark 18:23:43 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8361.686760582048, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3211.5503829463864, 3244.951325730113, 3269.182704323919, 3287.3918325109858, 3296.077098412445, 6152.723127459264, 6256.718447669854, 6292.496600271978, 6300.338464461234, 6317.905231421532, 6036.3360030396625, 6136.294326152494, 6149.446549810516, 6185.6597887116195, 6200.658963756991, 5676.120201368794, 5677.829665110043, 5785.074388097905, 5791.0, 5848.356680765112, 5712.0, 5737.540996720273, 5778.4799368056865, 5789.623674461147, 5796.5652576056755, 144.5539767899923, 5566.888662226753, 5606.943930560703, 5752.913706294409, 5764.0, 5641.210230567724, 5681.488666020078, 5702.173184888168, 5744.827655170351, 5753.942460575425, 5634.04221282383, 5673.8297851064535, 5739.16782066598, 5756.827295181151, 5757.107648314518, 5990.251218597676, 6114.847128821771, 6224.937750622481, 6277.843053923642, 6279.403456671609, 6527.902081468782, 6635.767748128792, 6647.202335719745, 6719.092922455455, 6762.087118239024, 6474.643894585796, 6601.405873471411, 6650.0, 6697.93302066978, 6775.491838112112, 6105.297890742577, 6106.45041946227, 6177.104319873638, 6182.96908515459, 6184.907226391652, 6756.594604323658, 6760.087388202628, 6788.2193547742645, 6809.557378770463, 6811.386975172259, 6435.517336199804, 6470.579412338183, 6472.482201423874, 6477.870442591122, 6487.091807147003, 5837.737301821428, 6523.934760652428, 6573.5398522103, 6576.243731970789, 6706.865862682719, 6568.704408301638, 6577.046328282372, 6703.765368212089, 6720.32796720324, 6721.66391680414, 6439.967800161063, 6606.075149479077, 6639.136912201383, 6660.333966603398, 6689.966550167218, 4876.683015603974, 4885.047415753894, 4894.045661096124, 4905.632077594195, 4961.107000739901, 4315.546867578859, 4358.150160718629, 4393.6704747144095, 4410.20616289071, 4424.535423780521, 5771.220885180447, 5771.422857714279, 5842.123681447817, 5853.7365818538265, 5858.970705146447, 6467.191601049869, 6570.0, 6628.171478565179, 6642.7342906284175, 6757.594544327356, 5674.1488776683, 5675.631083979611, 5716.056850619684, 5725.6850873202375, 5726.3128424588485, 5634.098544232903, 5648.548116150698, 5657.773689052392, 5674.773009079673, 5675.716214189274, 6278.591891527036, 6325.841853953688, 6365.2679941806355, 6392.232932048091, 6397.040443933354, 6670.132882725215, 6716.059751634775, 6716.429103526255, 6795.72817087311, 6796.524243302922, 6321.841953951188, 6347.174867267226, 6387.329330420239, 6464.935350646621, 6500.154979852589, 6459.709313081016, 6541.8691626168165, 6550.508711846535, 6599.373059559334, 6646.468282537482, 6648.667566621746, 6669.233038200669, 6695.0, 6743.662816859235, 6757.121574195323, 6474.384933431316, 6480.027995800668, 6505.381988711065, 6560.343965603402, 6562.868742625216, 6279.560430769985, 6455.580387274812, 6473.666816291767, 6546.443552297917, 6547.738090476328, 6307.684615769285, 6311.431971122621, 6322.988321868479, 6342.7145778441, 6343.0485427186295, 6437.163168788027, 6466.159399278064, 6471.385218404244, 6498.057781621733, 6522.5760325578685, 6246.156768836331, 6305.716242768995, 6329.367063293634, 6361.077643741539, 6369.299377068612], "value": 5990.941795504609, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [751.4814777803315, 818.5702506184256, 860.2687715441873, 886.1537231943497, 888.8933328000641, 38905.22189556214, 38961.22077558447, 39321.60678393219, 39350.80324598373, 39546.406803897895, 35749.2850142997, 36195.638043619554, 36206.27587448256, 36223.45664815023, 36342.27315453696, 6846.5549739267035, 6942.757003504878, 6997.510174287802, 7034.507584469089, 7043.119610048744, 6839.965800170992, 6940.68766905488, 6990.231074581792, 7014.123234595676, 7019.262977387378, 7564.62176891156, 7762.534247945113, 7767.067951845786, 7767.262110099531, 7772.961135194344, 7619.199984001655, 7663.655135518915, 7725.768226953172, 7735.18780528042, 7736.729214477523, 7526.661300241502, 7610.657520411595, 7641.19767424418, 7688.500247483896, 7689.769306920802, 36521.817390913144, 36547.451788222934, 36782.0, 37073.629263707284, 37280.44079338812, 3444.431668774649, 3487.145649315934, 3489.825508724579, 3492.9650703492894, 3493.7379696522607, 31008.0, 31085.823992320547, 31254.21864453384, 31285.530717039263, 31313.217169570715, 5001.924971125436, 5098.541131298164, 5102.591792656615, 5106.514881086291, 5192.169252919514, 204844.0, 211107.0, 212398.0, 212540.0, 212917.0, 46180.5381946183, 46341.30488042682, 46353.768231158625, 46399.76800115978, 46432.7678361606, 48739.756301218265, 48756.5124348752, 48791.75604121957, 48801.267980980316, 48814.75592622085, 15502.147381894105, 15698.450554230547, 15837.920810395874, 15846.841531584767, 15865.0, 13116.08187426871, 13130.212187268795, 13130.803037954438, 13139.60581182566, 13149.671258218621, 13239.470421183238, 13437.193768373929, 13484.39320230592, 13523.256220907748, 13642.658933526543, 5379.408265090837, 5441.972790136024, 5504.22940788282, 5512.228288039678, 5575.498205161555, 972.5526257921294, 993.4486360070086, 994.2096033653263, 1000.8698869146964, 1004.2919741582167, 21417.46456338604, 21554.353369398945, 21568.784312156993, 21976.560468790536, 22016.44958876041, 1095.118429664112, 1098.9725256868642, 1108.7671588966246, 1110.3670907582762, 1111.4442778610694, 11483.0, 11684.591039313584, 11761.88238117625, 11824.586139485076, 11916.761664766656, 219472.0, 220484.0, 221190.0, 221306.0, 221683.0, 41135.97160070962, 48228.758856206194, 48527.75736121297, 48653.75673121612, 48946.0, 1411.5553600615774, 1423.8220222472191, 1423.964400889986, 1427.7358688642635, 1430.6208854653526, 26654.46691066129, 26823.597646035716, 26912.865435672695, 27207.0, 27780.44439111247, 47709.0, 47803.52196477991, 48168.5183148164, 48219.03561928812, 48588.75705621449, 198978.0, 200536.0, 200901.0, 201125.0, 203689.0, 32187.51718724268, 34072.82963585166, 34368.31263374668, 34456.65543344635, 34495.0, 42405.787971059945, 42768.0, 42855.57144428516, 42997.785011074746, 43012.78493607512, 30972.690273097884, 31189.220269493446, 31510.212244694067, 31695.52456713105, 31804.0, 31309.53035704421, 31321.530177046905, 31409.214769630027, 31526.527102093947, 31726.36547269088, 35122.82438587791, 35290.47064294089, 35465.46801798027, 35648.287034258654, 35691.64308356987, 30154.396912062075, 30901.0, 30939.381212376076, 31012.379752405275, 31126.53310200394, 21185.987331203512, 22841.31476055721, 22870.313890583308, 23183.53632927298, 23215.651765223873], "value": 16872.144813309693, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [546.3225600255681, 549.8185598752412, 553.8559974406653, 561.5984571031712, 567.1011446856733, 21212.99999999998, 21979.23072692456, 22002.999999999978, 22148.114075437003, 22161.55676886461, 21704.457388565294, 23296.767032329673, 23452.179173728924, 23486.412839678975, 23537.411564710892, 6426.228852537679, 6569.671516424183, 6574.704138313763, 6590.176227971504, 6611.909035009242, 6433.067205255235, 6465.0, 6485.383888530582, 6487.7729279475225, 6538.280789113193, 6264.968675156618, 6509.707063182144, 6523.05415714721, 6537.182852143482, 6557.01644753286, 6522.706478208468, 6539.280679125292, 6539.378759017885, 6560.442362399202, 6564.474842012628, 5986.132010858401, 6054.273487181523, 6058.606190597597, 6076.878462430771, 6088.726007329681, 28021.439571208666, 28641.57037644437, 28836.134915952556, 29492.41015179706, 29674.851625741732, 1988.145097608028, 2057.3210840422626, 2058.084152552115, 2058.783827698085, 2059.660156074246, 19113.426597202106, 19525.609487810165, 19768.20927162912, 19793.307234246877, 19832.30586929465, 2324.732655744594, 2373.2049763329305, 2397.30478161333, 2414.6740190074293, 2430.1251549442186, 129105.0, 132202.0, 132408.0, 133019.33490332583, 135375.32312338473, 13560.932195339157, 13969.0, 14322.427102915975, 14423.92788036053, 14864.0, 22581.4354641132, 22666.093356265894, 22704.31887043391, 22751.317460476213, 22766.886165569067, 9892.15916647093, 9964.701058968243, 10025.298229124037, 10039.69880903574, 10048.949755251177, 9278.211352035018, 9300.11648893354, 9356.953215234016, 9367.718968430958, 9377.156055954996, 8526.616302266413, 8608.956955215184, 8721.912780872237, 9288.953555232316, 9393.624255029723, 2572.897084116614, 2610.4126571521256, 2621.2922510921944, 2647.0, 2687.2207059952784, 915.839728047589, 947.1522986926662, 952.8094381123665, 966.4974213408988, 975.0834215837232, 18718.438446846616, 18927.81072189288, 19109.235630574898, 19155.61688766217, 19390.03049847502, 958.5590628311055, 1005.8189525885265, 1006.285537268533, 1017.3641474078701, 1035.1304903880775, 10201.642942497125, 10514.684559463229, 10572.894271057345, 10701.625443109453, 10844.620438284779, 483856.0, 491977.0, 496613.0, 496718.0, 506482.0, 105844.0, 106421.0, 108454.45772771086, 111554.0, 112314.0, 1130.8021096308278, 1137.8805225451208, 1161.6573110932295, 1172.302480024384, 1177.0465922602718, 17182.828171718123, 17358.218880150172, 17463.650726985135, 17533.298668053645, 18007.639847203245, 53976.0, 54937.0, 56910.7235710567, 56968.43031569797, 57767.0, 67344.32655673381, 68419.0, 68988.31011690019, 69615.0, 70103.0, 11363.318200907972, 13824.308784560933, 14169.929150354183, 14270.143791372551, 14505.854941450873, 22271.109155633592, 22548.436289092904, 22640.094396223965, 22818.771812281666, 23018.769812301663, 17624.735628965318, 17736.467905962843, 17936.0, 18356.35752748701, 18477.90761046186, 16124.435644752377, 16140.757888631915, 16834.57913552122, 16847.831521684628, 17197.828021720125, 17879.642407152045, 18702.719459207852, 18853.528661783017, 18854.905725471286, 19105.235790568222, 13956.651083722989, 14019.789703154665, 14028.0, 14128.646783830487, 14166.43334266659, 17882.731759023365, 18114.909425452788, 18163.45509634713, 18272.177752000927, 18334.541636459195], "value": 11700.811200445925, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1314.5267703626694, 1351.0610125962453, 1358.6195865157756, 1362.80920671106, 1368.9041767076303, 14442.927785361071, 14703.558893233194, 15004.774928376082, 15042.473513427032, 15067.698646027073, 14084.29578521075, 14856.0, 14986.25068746561, 14998.625034374147, 15022.624434389145, 2621.1350254415997, 2671.759541641252, 2674.291312802109, 2674.692410372803, 2694.393761403678, 2552.4639825636546, 2654.0578094776365, 2704.256329509381, 2707.891684332634, 2708.8374697518116, 2229.7435794883636, 2306.4925716342377, 2312.7571604981486, 2635.3279913622, 2724.4006318609872, 2594.2087663262605, 2603.791696664272, 2615.712271650117, 2623.435961268327, 2626.487834872201, 2643.286312695581, 2657.1762753546464, 2666.560017597094, 2686.0867305116158, 2709.7425744554234, 14243.287835608282, 14311.499097531536, 14331.06848054873, 14333.0, 14405.279736013263, 7392.482526223166, 7655.770326890202, 7681.462297639167, 7689.19263477338, 7728.613569321511, 4995.125852975751, 5008.799648014112, 5185.222216667492, 5289.814856480043, 5314.654547454442, 1858.8977606231788, 1863.8974856382893, 1880.7366968624403, 1884.9151788169565, 1930.9613807723908, 24273.635895461583, 24656.26031219066, 24861.378465538324, 24976.50046999068, 25019.874900625564, 6764.695588698422, 6803.115594972622, 6863.588184708933, 6946.027556142144, 7011.754588589375, 19072.237110515423, 19080.71378929317, 19090.0, 19131.90434047821, 19156.137973791214, 7608.2011388804485, 7757.0, 7757.612119394008, 7776.144624091346, 7776.455648104578, 6794.422474089758, 6937.479689023344, 6987.406070483964, 7034.824129396704, 7052.788416347517, 5360.249565060816, 5430.91853622196, 5440.863978400493, 5533.08704063833, 5533.253010843565, 1438.439008786574, 1668.5911951571943, 1674.137819023204, 1675.3633619224618, 1723.586339278564, 860.7589874835056, 875.5097145598485, 884.1556313720382, 896.5069211933411, 907.7004588485784, 8510.914890851136, 8770.824583508294, 8881.600327985256, 8961.014288428267, 9028.322875784212, 945.9148676619137, 985.3299756165733, 995.7311525888115, 1000.9402470511488, 1009.6415772400838, 5118.0, 5134.640575159703, 5156.226566015052, 5249.501297376743, 5460.044492213847, 19738.0, 20123.295684650966, 20670.58658826815, 20823.167073316898, 20999.0, 20391.0, 20438.59122817535, 20442.28452004173, 20462.693059604117, 20475.0, 10299.74250643725, 10343.689689309333, 10769.461526923622, 11107.55569777216, 11142.498587563417, 8324.25081742646, 8327.95836020816, 8414.915850841413, 8503.1921967413, 8684.609192586222, 17331.480055598353, 18071.909640451715, 18180.000099994628, 18240.99674517914, 18480.0, 17802.0, 18028.188731506852, 18052.458426247234, 18561.814381856548, 18562.164702588685, 11682.532698692297, 11698.883011169779, 12094.516219351128, 12103.394830258276, 12123.393830308272, 13441.462341506622, 13595.184288942695, 13618.795718064435, 13676.863231367559, 13742.93128534351, 10865.728356791144, 11026.724331891766, 11145.164112691418, 11222.887771122185, 11250.66248012561, 10634.89365106339, 10789.352638841694, 10854.620088296871, 11047.502862371373, 11108.722281943015, 9669.274804389559, 9685.515724213901, 9687.757806054906, 9758.75603109928, 9770.120689138012, 7143.357097861218, 7156.1770396403, 7174.605396703231, 7223.711051557879, 7297.16082650502, 7218.27817218274, 7290.526115802456, 7304.744333948286, 7338.706451742084, 7380.409567234501], "value": 6590.603789801031, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [499.29275132143823, 503.1459073314835, 509.5363219470283, 519.7141572135325, 526.5629527492182, 6201.286852012008, 6203.813885583429, 6240.812775616733, 6294.937050629493, 6314.873702525947, 5969.462748352647, 6052.273727152748, 6129.969350153254, 6218.440340369366, 6242.875142497147, 5019.598432125439, 5029.924551131727, 5030.346055012843, 5062.949370506303, 5076.822311219108, 4801.399825021872, 4952.207646776516, 5023.824166154185, 5026.29631851541, 5056.974715126437, 4930.235813448921, 4934.989656309666, 4954.653174277802, 4996.475370086143, 4998.650634201919, 4974.975125124387, 4986.0, 5033.11920413928, 5036.647434679574, 5041.773120209599, 4480.529544397824, 4487.551244875486, 4494.16857881293, 4542.046170304207, 4542.273236282212, 6009.673035414538, 6129.877402451971, 6173.919564076314, 6211.596246243979, 6213.6951240239605, 5698.601097923147, 5772.393898640625, 5787.971060144714, 5788.97105514474, 5795.27622017651, 6528.249251336109, 6601.3728695773825, 6639.734410623618, 6656.534042617019, 6665.300143484913, 4784.928226076611, 4851.345068415754, 4865.6594038417325, 4920.901581968341, 4939.604831613462, 6858.691358888812, 6886.965565172142, 6901.965490172517, 6919.8962015569805, 7019.754308599226, 4948.1835497142565, 4962.503749625009, 4981.90036199274, 5010.674306170087, 5038.193888977746, 5106.0, 5112.9744351278005, 5132.897342053138, 5146.588272938156, 5150.351887396072, 5647.237622920935, 5664.640486283262, 5704.0, 5704.771809127588, 5769.88460230793, 6630.436412904861, 6681.265060843303, 6692.59844409337, 6694.196696396463, 6705.798826035227, 1650.7276299410703, 1699.507142928537, 1700.4218565687725, 1745.7119575270192, 1747.4932269641915, 1095.3427943234155, 1210.661014915825, 1210.8365370674846, 1220.4385982448173, 1226.6074856046157, 5683.175939488751, 5704.0, 5753.568482363839, 5770.19217309577, 5788.929048126026, 6557.5081868860025, 6609.702563384659, 6691.464682825363, 6767.0, 6789.898151527731, 5715.485606295453, 5764.337101233328, 5771.00900189434, 5778.739956701958, 5789.189513468118, 3558.491263659114, 3583.695385892176, 3590.8922732318074, 3621.4386770050683, 3628.129248980226, 6410.166678331787, 6442.323556026549, 6502.2197336318995, 6520.836979075561, 6524.151860258136, 6949.409300209539, 6988.545744526691, 7026.789196324118, 7054.470914681317, 7111.64441777909, 6538.705758240986, 6586.242582103024, 6597.47220222381, 6599.307072757388, 6600.339966003361, 6696.83257918556, 6782.762603309058, 6827.283135270725, 6832.760853370109, 6836.316368363124, 6583.506237032146, 6659.866802664016, 6772.0, 6812.773700733916, 6858.348456896587, 6425.421712045938, 6507.642079685474, 6514.54398192141, 6540.672966351759, 6561.770338038145, 4964.280179373898, 4968.254761785761, 4981.576565991932, 4999.600031997359, 5009.974950125372, 6738.427233685192, 6766.086578311865, 6838.7857706877685, 6907.89638155418, 6926.549774264657, 6463.192100987377, 6575.901361479678, 6621.1392518972225, 6706.853806781783, 6717.429018533284, 6526.608403495616, 6612.272650008399, 6619.97390404488, 6655.600663960178, 6689.632070235989, 6219.40915613016, 6295.370462953668, 6465.321141280286, 6488.837779055561, 6534.215894092739, 6262.874742505215, 6431.273014334554, 6496.480281577557, 6545.541812073109, 6556.704948277244, 6270.435660790368, 6349.777757778455, 6376.0, 6376.713047912948, 6396.872062558816], "value": 5070.92681842254, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [869.2828416556339, 891.2513488669516, 901.7591285655142, 907.0430695616125, 915.4644532948225, 18688.345907893225, 19650.7052394214, 19711.408657740256, 19733.01334933253, 20918.26786062488, 20097.799022009774, 21093.0, 21207.0, 21370.89314553425, 21416.78583214171, 931.8602209668553, 951.6859436386011, 956.2541217850053, 956.9904300956971, 957.5164541906328, 920.2086205862953, 965.9227261819037, 968.4044312747644, 970.6311601591385, 971.7230589282055, 996.9302148849583, 1002.6039714312842, 1006.2050979726008, 1006.7684432580511, 1007.30999265503, 990.1089019882128, 993.3394292795282, 995.5221493683036, 998.8052329795693, 999.720078378048, 719.9208087110413, 725.6371814092954, 725.9854802903913, 732.2787054751066, 734.295076726338, 18221.63556728858, 18334.0, 19287.324943626907, 19569.0, 19724.211031558865, 20754.377368678965, 20897.37307880766, 21156.0, 21169.259075932423, 21310.893445532674, 15672.686546269124, 16225.756613650805, 16273.593160170973, 16364.34542618294, 16543.751843722235, 3628.7097032237357, 3660.4509323601405, 3664.4320130379833, 3666.926661466782, 3699.9640100771826, 121323.0, 121774.39112804379, 124642.0, 127452.36273818664, 127562.08656870248, 14937.925310373595, 15528.611784705472, 15580.298886550132, 15624.609384765472, 15703.842961570466, 28954.27614309659, 29401.705982939897, 29794.25514362115, 29801.0, 29916.252093697833, 8367.414281000272, 8525.40322177454, 8572.61423235956, 8576.614052367533, 8585.18440748128, 4288.442502474658, 4353.455818022747, 4380.3210502372185, 4382.233109205877, 4391.363252328394, 4979.950200497949, 5026.723530205873, 5031.069252188357, 5032.521910418504, 5052.520010598987, 866.1425189062783, 879.692107762278, 882.9426087304305, 887.671561522241, 892.1435421994958, 674.2785219814751, 684.1892357556212, 698.3470455124475, 700.9859802803916, 701.9754308599174, 10567.72793849326, 11458.197926145256, 11660.650180494598, 12018.759624807455, 12094.395280235953, 639.5107742576932, 694.7915625312487, 699.7271064284932, 718.0772707071388, 719.2986837833071, 5331.306930099062, 5389.305347326337, 5410.567154627621, 5577.107662773937, 5607.542039069871, 108944.4552777231, 109296.0, 111035.0, 112313.0, 114569.0, 97896.51051744695, 98391.50804245933, 99779.50110249402, 102151.0, 103339.483302583, 82082.0, 83598.0, 83679.0, 86012.56993715116, 86216.13783862206, 10315.793684126425, 10342.896571034495, 10397.948010259901, 10435.752409782619, 10502.422366769595, 38709.2258154841, 39996.0, 40724.796376017934, 40735.1852962945, 40883.59116408798, 50273.49726502688, 51249.487505125966, 51800.74099629478, 53060.0, 53458.73270633622, 15610.063396196265, 15631.29659165363, 16005.279762410486, 16110.838891610934, 16175.110368929822, 17323.307067717655, 17602.29590816353, 17668.38160664422, 17814.198361074043, 17864.374746883797, 15899.841001589835, 16056.67886642284, 16225.432109876097, 16381.0, 16490.0, 15888.682226355639, 16025.599360016093, 16077.19614019271, 16325.265363058928, 16413.26140323712, 15497.84502154964, 15565.221738913235, 15621.687566248838, 15863.127527986071, 15865.0, 11515.596954106566, 11531.711707207387, 11533.82699259495, 11609.071274298205, 11685.065194784227, 11224.887751122711, 11613.535458581562, 11633.534658613899, 11650.126240531825, 11664.416779160838], "value": 8646.572241232183, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1546.5, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 116.0, 108.0, 109.0, 108.0, 108.0, 108.0, 102.0, 110.0, 119.0], "value": 109.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 130.0, 154.0, 180.0, 118.0, 160.0, 151.0, 121.0, 181.0, 122.0], "value": 146.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 163.0, 163.0, 164.0, 173.0, 169.0, 170.0, 164.0, 166.0, 168.0], "value": 166.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 84.0, 84.0, 169.0, 79.0, 76.0, 76.0, 74.0, 166.0, 76.0], "value": 95.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 84.0, 136.0, 80.0, 90.0, 81.0, 131.0, 85.0, 86.0, 132.0], "value": 98.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 120.0, 115.0, 118.0, 115.0, 116.0, 114.0, 116.0, 115.0, 126.0], "value": 116.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 147.0, 147.0, 150.0, 149.0, 149.0, 149.0, 149.0, 153.0, 149.0], "value": 149.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 99.0, 96.0, 104.0, 94.0, 94.0, 94.0, 100.0, 94.0, 96.0], "value": 96.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 53.0, 54.0, 53.0, 54.0, 55.0, 57.0, 57.0, 53.0, 55.0], "value": 54.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 59.0, 55.0, 61.0, 57.0, 58.0, 62.0, 63.0, 56.0, 56.0], "value": 58.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 80.0, 79.0, 82.0, 82.0, 87.0, 81.0, 76.0, 75.0, 83.0], "value": 80.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [125.0, 127.0, 129.0, 129.0, 119.0, 128.0, 119.0, 128.0, 123.0, 156.0], "value": 128.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [188.0, 173.0, 178.0, 174.0, 180.0, 179.0, 178.0, 171.0, 178.0, 174.0], "value": 177.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 64.0, 66.0, 67.0, 68.0, 65.0, 67.0, 73.0, 64.0, 66.0], "value": 66.9, "unit": "score"}]}]} 18:23:43 INFO - Return code: 0 18:23:43 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:23:43 INFO - # TBPL SUCCESS # 18:23:43 INFO - Running post-action listener: _package_coverage_data 18:23:43 INFO - Running post-action listener: _resource_record_post_action 18:23:43 INFO - [mozharness: 2017-08-25 01:23:43.813121Z] Finished run-tests step (success) 18:23:43 INFO - Running post-run listener: _resource_record_post_run 18:23:43 INFO - Total resource usage - Wall time: 1194s; CPU: 13.0%; Read bytes: 4653056; Write bytes: 431063040; Read time: 2548; Write time: 327164 18:23:43 INFO - TinderboxPrint: CPU usage
13.3% 18:23:43 INFO - TinderboxPrint: I/O read bytes / time
4,653,056 / 2,548 18:23:43 INFO - TinderboxPrint: I/O write bytes / time
431,063,040 / 327,164 18:23:43 INFO - TinderboxPrint: CPU idle
8,252.0 (86.7%) 18:23:43 INFO - TinderboxPrint: CPU user
1,202.2 (12.6%) 18:23:43 INFO - TinderboxPrint: Swap in / out
0 / 0 18:23:43 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 109506560; Read time: 0; Write time: 109920 18:23:43 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 18:23:43 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 4648960; Write bytes: 321556480; Read time: 2528; Write time: 217244 18:23:44 INFO - Running post-run listener: _upload_blobber_files 18:23:44 INFO - Blob upload gear active. 18:23:44 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:23:44 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 18:23:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 18:23:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 18:23:44 INFO - (blobuploader) - INFO - Open directory for files ... 18:23:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 18:23:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:23:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:23:45 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 18:23:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:23:45 INFO - (blobuploader) - INFO - Done attempting. 18:23:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:23:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:23:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:23:52 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:23:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:23:52 INFO - (blobuploader) - INFO - Done attempting. 18:23:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 18:23:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:23:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:23:53 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 18:23:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:23:53 INFO - (blobuploader) - INFO - Done attempting. 18:23:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 18:23:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:23:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:23:54 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 18:23:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:23:54 INFO - (blobuploader) - INFO - Done attempting. 18:23:54 INFO - (blobuploader) - INFO - Iteration through files over. 18:23:54 INFO - Return code: 0 18:23:54 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:23:54 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:23:54 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d06fcb6d842c4a6152c49e9423b2b89c4818a64940138c05c024c80c01485db57e63b421439341fd3437ed98a95c514e102302684f690a2acb386243def37f38", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/29f0f8d1fa64ba3699a42e635016e25bd256ccfa9be53ba9265243d991b9cfa2d85d43d4844c698f682a9d31c44629f5646dce40cf0b4070abebcadcdf6f9cfc", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8034572e9414f5af792148539d5868256ea1bfcca3745a9bae5d2be3b4d530f1f520458678c2dad13d1a9042b97733823c4d0dd2b93e3a758a8a2579d4273d43", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1f52652b2b63c92fe6059590def9be986aba0b68da989e008cdc791d1ffaa8518e6a7c18267d9d3d17d3186df7386ee564e6eb9811a2616f0d0c3a2e34f52469"} 18:23:54 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:23:54 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:23:54 INFO - Contents: 18:23:54 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d06fcb6d842c4a6152c49e9423b2b89c4818a64940138c05c024c80c01485db57e63b421439341fd3437ed98a95c514e102302684f690a2acb386243def37f38", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/29f0f8d1fa64ba3699a42e635016e25bd256ccfa9be53ba9265243d991b9cfa2d85d43d4844c698f682a9d31c44629f5646dce40cf0b4070abebcadcdf6f9cfc", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8034572e9414f5af792148539d5868256ea1bfcca3745a9bae5d2be3b4d530f1f520458678c2dad13d1a9042b97733823c4d0dd2b93e3a758a8a2579d4273d43", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1f52652b2b63c92fe6059590def9be986aba0b68da989e008cdc791d1ffaa8518e6a7c18267d9d3d17d3186df7386ee564e6eb9811a2616f0d0c3a2e34f52469"} 18:23:54 INFO - Running post-run listener: copy_logs_to_upload_dir 18:23:54 INFO - Copying logs to upload dir... 18:23:54 INFO - mkdir: /builds/slave/test/build/upload/logs 18:23:54 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1255.375599 ========= master_lag: 0.85 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 56 secs) (at 2017-08-24 18:23:54.820054) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-24 18:23:54.824843) ========= 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=1f5564757af27664ca02d71f000001b0-1503622976.524054-895464963 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/d06fcb6d842c4a6152c49e9423b2b89c4818a64940138c05c024c80c01485db57e63b421439341fd3437ed98a95c514e102302684f690a2acb386243def37f38", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/29f0f8d1fa64ba3699a42e635016e25bd256ccfa9be53ba9265243d991b9cfa2d85d43d4844c698f682a9d31c44629f5646dce40cf0b4070abebcadcdf6f9cfc", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8034572e9414f5af792148539d5868256ea1bfcca3745a9bae5d2be3b4d530f1f520458678c2dad13d1a9042b97733823c4d0dd2b93e3a758a8a2579d4273d43", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1f52652b2b63c92fe6059590def9be986aba0b68da989e008cdc791d1ffaa8518e6a7c18267d9d3d17d3186df7386ee564e6eb9811a2616f0d0c3a2e34f52469"} build_url:https://queue.taskcluster.net/v1/task/Amqe4HB8QW2hqpHBtZHK2w/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011248 build_url: 'https://queue.taskcluster.net/v1/task/Amqe4HB8QW2hqpHBtZHK2w/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d06fcb6d842c4a6152c49e9423b2b89c4818a64940138c05c024c80c01485db57e63b421439341fd3437ed98a95c514e102302684f690a2acb386243def37f38", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/29f0f8d1fa64ba3699a42e635016e25bd256ccfa9be53ba9265243d991b9cfa2d85d43d4844c698f682a9d31c44629f5646dce40cf0b4070abebcadcdf6f9cfc", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8034572e9414f5af792148539d5868256ea1bfcca3745a9bae5d2be3b4d530f1f520458678c2dad13d1a9042b97733823c4d0dd2b93e3a758a8a2579d4273d43", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1f52652b2b63c92fe6059590def9be986aba0b68da989e008cdc791d1ffaa8518e6a7c18267d9d3d17d3186df7386ee564e6eb9811a2616f0d0c3a2e34f52469"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-24 18:23:54.862981) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-24 18:23:54.867538) ========= 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=1f5564757af27664ca02d71f000001b0-1503622976.524054-895464963 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004464 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-24 18:23:54.948956) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-24 18:23:54.949290) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-24 18:23:54.949578) ========= ========= Total master_lag: 1.02 =========