builder: mozilla-central_ubuntu64_hw_qr_test-dromaeojs-e10s slave: talos-linux64-ix-078 starttime: 1504652868.93 results: success (0) revision: f64e2b4dcf5eec0b4ad456c149680a67b7c26dc4 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-05 16:07:48.926007) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-05 16:07:48.926479) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-05 16:07:48.926749) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-05 16:07:48.939571) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-05 16:07:48.939956) ========= 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=dac658600622fd96716c9acf000001b3-1504652868.17228-1316107021 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-05 16:07:49-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 99.6M=0s 2017-09-05 16:07:49 (99.6 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.119827 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-05 16:07:49.077334) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-05 16:07:49.077674) ========= 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=dac658600622fd96716c9acf000001b3-1504652868.17228-1316107021 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016635 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-05 16:07:49.129994) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-09-05 16:07:49.130336) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev f64e2b4dcf5eec0b4ad456c149680a67b7c26dc4 --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 f64e2b4dcf5eec0b4ad456c149680a67b7c26dc4 --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=dac658600622fd96716c9acf000001b3-1504652868.17228-1316107021 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-05 16:07:49,383 truncating revision to first 12 chars 2017-09-05 16:07:49,383 Setting DEBUG logging. 2017-09-05 16:07:49,383 attempt 1/10 2017-09-05 16:07:49,383 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/f64e2b4dcf5e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-05 16:07:50,311 unpacking tar archive at: mozilla-central-f64e2b4dcf5e/testing/mozharness/ program finished with exit code 0 elapsedTime=1.268846 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-09-05 16:07:50.429050) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-05 16:07:50.429367) ========= script_repo_revision: f64e2b4dcf5eec0b4ad456c149680a67b7c26dc4 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-05 16:07:50.429719) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-05 16:07:50.433012) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-05 16:07:50.446530) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 53 secs) (at 2017-09-05 16:07:50.446894) ========= /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=dac658600622fd96716c9acf000001b3-1504652868.17228-1316107021 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 16:07:50 INFO - MultiFileLogger online at 20170905 16:07:50 in /builds/slave/test 16:07:50 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 16:07:50 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:07:50 INFO - {'append_to_log': False, 16:07:50 INFO - 'base_work_dir': '/builds/slave/test', 16:07:50 INFO - 'blob_upload_branch': 'Firefox', 16:07:50 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:07:50 INFO - 'branch': 'Firefox', 16:07:50 INFO - 'buildbot_json_path': 'buildprops.json', 16:07:50 INFO - 'code_coverage': False, 16:07:50 INFO - 'config_files': ('talos/linux_config.py',), 16:07:50 INFO - 'default_actions': ('clobber', 16:07:50 INFO - 'read-buildbot-config', 16:07:50 INFO - 'download-and-extract', 16:07:50 INFO - 'populate-webroot', 16:07:50 INFO - 'create-virtualenv', 16:07:50 INFO - 'install', 16:07:50 INFO - 'setup-mitmproxy', 16:07:50 INFO - 'run-tests'), 16:07:50 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:07:50 INFO - 'disable_ccov_upload': False, 16:07:50 INFO - 'disable_stylo': False, 16:07:50 INFO - 'download_minidump_stackwalk': True, 16:07:50 INFO - 'download_symbols': 'ondemand', 16:07:50 INFO - 'enable_stylo': False, 16:07:50 INFO - 'enable_webrender': False, 16:07:50 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 16:07:50 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:07:50 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:07:50 INFO - 'gecko_profile': False, 16:07:50 INFO - 'gecko_profile_interval': 0, 16:07:50 INFO - 'installer_path': 'installer.exe', 16:07:50 INFO - 'log_level': 'info', 16:07:50 INFO - 'log_name': 'talos', 16:07:50 INFO - 'log_to_console': True, 16:07:50 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 16:07:50 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 16:07:50 INFO - 'opt_config_files': (), 16:07:50 INFO - 'pip_index': False, 16:07:50 INFO - 'suite': 'dromaeojs-e10s', 16:07:50 INFO - 'system_bits': '32', 16:07:50 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:07:50 INFO - 'title': 'talos-linux64-ix-078', 16:07:50 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:07:50 INFO - 'use_talos_json': True, 16:07:50 INFO - 'verify': 'False', 16:07:50 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:07:50 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:07:50 INFO - 'work_dir': 'build'} 16:07:50 INFO - [mozharness: 2017-09-05 23:07:50.835957Z] Running clobber step. 16:07:50 INFO - Running pre-action listener: _resource_record_pre_action 16:07:50 INFO - Running main action method: clobber 16:07:50 INFO - rmtree: /builds/slave/test/build 16:07:50 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:07:51 INFO - Running post-action listener: _resource_record_post_action 16:07:51 INFO - [mozharness: 2017-09-05 23:07:51.884788Z] Finished clobber step (success) 16:07:51 INFO - [mozharness: 2017-09-05 23:07:51.884887Z] Running read-buildbot-config step. 16:07:51 INFO - Running pre-action listener: _resource_record_pre_action 16:07:51 INFO - Running main action method: read_buildbot_config 16:07:51 INFO - Using buildbot properties: 16:07:51 INFO - { 16:07:51 INFO - "project": "", 16:07:51 INFO - "product": "firefox", 16:07:51 INFO - "who": "archaeopteryx@coole-files.de", 16:07:51 INFO - "installer_path": "public/build/target.tar.bz2", 16:07:51 INFO - "builddir": "mozilla-central_ubuntu64_hw_qr_test-dromaeojs-e10s", 16:07:51 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 16:07:51 INFO - "buildername": "Ubuntu HW 12.04 x64 qr mozilla-central talos dromaeojs-e10s", 16:07:51 INFO - "stage_platform": "linux64-qr", 16:07:51 INFO - "basedir": "/builds/slave/test", 16:07:51 INFO - "buildnumber": 36, 16:07:51 INFO - "platform": "ubuntu64_hw_qr", 16:07:51 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 16:07:51 INFO - "slavebuilddir": "test", 16:07:51 INFO - "taskId": "MhsangDJR_Warn63f5F2mw", 16:07:51 INFO - "branch": "mozilla-central", 16:07:51 INFO - "script_repo_revision": "production", 16:07:51 INFO - "revision": "f64e2b4dcf5eec0b4ad456c149680a67b7c26dc4", 16:07:51 INFO - "slavename": "talos-linux64-ix-078", 16:07:51 INFO - "repo_path": "mozilla-central" 16:07:51 INFO - } 16:07:51 INFO - Finding installer, test and symbols from parent task. 16:07:51 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MhsangDJR_Warn63f5F2mw'}, attempt #1 16:07:52 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MhsangDJR_Warn63f5F2mw'}, attempt #1 16:07:52 INFO - Task dependencies: VQZMjgW9QkOpdRucLZyMvw 16:07:52 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VQZMjgW9QkOpdRucLZyMvw'}, attempt #1 16:07:53 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/VQZMjgW9QkOpdRucLZyMvw/artifacts/public/build/target.tar.bz2 16:07:53 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/VQZMjgW9QkOpdRucLZyMvw/artifacts/public/build/target.test_packages.json 16:07:53 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/VQZMjgW9QkOpdRucLZyMvw/artifacts/public/build/target.crashreporter-symbols.zip 16:07:53 INFO - Running post-action listener: _resource_record_post_action 16:07:53 INFO - [mozharness: 2017-09-05 23:07:53.355001Z] Finished read-buildbot-config step (success) 16:07:53 INFO - [mozharness: 2017-09-05 23:07:53.355219Z] Running download-and-extract step. 16:07:53 INFO - Running pre-action listener: _resource_record_pre_action 16:07:53 INFO - Running main action method: download_and_extract 16:07:53 INFO - mkdir: /builds/slave/test/build/tests 16:07:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:07:53 INFO - trying https://queue.taskcluster.net/v1/task/VQZMjgW9QkOpdRucLZyMvw/artifacts/public/build/target.test_packages.json 16:07:53 INFO - Downloading https://queue.taskcluster.net/v1/task/VQZMjgW9QkOpdRucLZyMvw/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 16:07:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VQZMjgW9QkOpdRucLZyMvw/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 16:07:53 INFO - Downloaded 989 bytes. 16:07:53 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 16:07:53 INFO - Using the following test package requirements: 16:07:53 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 16:07:53 INFO - u'common': [u'target.common.tests.zip'], 16:07:53 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 16:07:53 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 16:07:53 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 16:07:53 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 16:07:53 INFO - u'mozbase': [u'target.common.tests.zip'], 16:07:53 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 16:07:53 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 16:07:53 INFO - u'web-platform': [u'target.common.tests.zip', 16:07:53 INFO - u'target.web-platform.tests.tar.gz'], 16:07:53 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 16:07:53 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 16:07:53 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VQZMjgW9QkOpdRucLZyMvw/artifacts/public/build/target.common.tests.zip 16:07:53 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VQZMjgW9QkOpdRucLZyMvw/artifacts/public/build/target.common.tests.zip'}, attempt #1 16:07:53 INFO - Fetch https://queue.taskcluster.net/v1/task/VQZMjgW9QkOpdRucLZyMvw/artifacts/public/build/target.common.tests.zip into memory 16:07:55 INFO - Content-Length response header: 55019892 16:07:55 INFO - Bytes received: 55019892 16:08:05 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 16:08:05 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VQZMjgW9QkOpdRucLZyMvw/artifacts/public/build/target.common.tests.zip 16:08:05 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VQZMjgW9QkOpdRucLZyMvw/artifacts/public/build/target.common.tests.zip'}, attempt #1 16:08:05 INFO - Fetch https://queue.taskcluster.net/v1/task/VQZMjgW9QkOpdRucLZyMvw/artifacts/public/build/target.common.tests.zip into memory 16:08:07 INFO - Content-Length response header: 55019892 16:08:07 INFO - Bytes received: 55019892 16:08:19 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VQZMjgW9QkOpdRucLZyMvw/artifacts/public/build/target.talos.tests.zip 16:08:19 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VQZMjgW9QkOpdRucLZyMvw/artifacts/public/build/target.talos.tests.zip'}, attempt #1 16:08:19 INFO - Fetch https://queue.taskcluster.net/v1/task/VQZMjgW9QkOpdRucLZyMvw/artifacts/public/build/target.talos.tests.zip into memory 16:08:19 INFO - Content-Length response header: 14496654 16:08:19 INFO - Bytes received: 14496654 16:08:20 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')]} 16:08:20 INFO - trying https://queue.taskcluster.net/v1/task/VQZMjgW9QkOpdRucLZyMvw/artifacts/public/build/target.tar.bz2 16:08:20 INFO - Downloading https://queue.taskcluster.net/v1/task/VQZMjgW9QkOpdRucLZyMvw/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 16:08:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VQZMjgW9QkOpdRucLZyMvw/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 16:08:21 INFO - Downloaded 62759133 bytes. 16:08:21 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/VQZMjgW9QkOpdRucLZyMvw/artifacts/public/build/target.tar.bz2 16:08:21 INFO - mkdir: /builds/slave/test/properties 16:08:21 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:08:21 INFO - Writing to file /builds/slave/test/properties/build_url 16:08:21 INFO - Contents: 16:08:21 INFO - build_url:https://queue.taskcluster.net/v1/task/VQZMjgW9QkOpdRucLZyMvw/artifacts/public/build/target.tar.bz2 16:08:21 INFO - Running post-action listener: _resource_record_post_action 16:08:21 INFO - Running post-action listener: find_tests_for_verification 16:08:21 INFO - Running post-action listener: set_extra_try_arguments 16:08:21 INFO - [mozharness: 2017-09-05 23:08:21.124460Z] Finished download-and-extract step (success) 16:08:21 INFO - [mozharness: 2017-09-05 23:08:21.124578Z] Running populate-webroot step. 16:08:21 INFO - Running pre-action listener: _resource_record_pre_action 16:08:21 INFO - Running main action method: populate_webroot 16:08:21 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:08:21 INFO - 'chromez-stylo-e10s': {'talos_options': ['--enable-stylo'], 16:08:21 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:08:21 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:08:21 INFO - 'dromaeojs-stylo-e10s': {'talos_options': ['--enable-stylo'], 16:08:21 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:08:21 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 16:08:21 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:08:21 INFO - 'g1-stylo-e10s': {'pagesets_name': 'tp5n.zip', 16:08:21 INFO - 'talos_options': ['--enable-stylo'], 16:08:21 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:08:21 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 16:08:21 INFO - 'tests': ['damp', 'tps']}, 16:08:21 INFO - 'g2-stylo-e10s': {'pagesets_name': 'tp5n.zip', 16:08:21 INFO - 'talos_options': ['--enable-stylo'], 16:08:21 INFO - 'tests': ['damp', 'tps']}, 16:08:21 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:08:21 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 16:08:21 INFO - 'g4-stylo-e10s': {'talos_options': ['--enable-stylo'], 16:08:21 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 16:08:21 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 16:08:21 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 16:08:21 INFO - 'g5-stylo-e10s': {'pagesets_name': 'tp5n.zip', 16:08:21 INFO - 'talos_options': ['--enable-stylo'], 16:08:21 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 16:08:21 INFO - 'other-e10s': {'tests': ['a11yr', 16:08:21 INFO - 'ts_paint', 16:08:21 INFO - 'tpaint', 16:08:21 INFO - 'sessionrestore', 16:08:21 INFO - 'sessionrestore_many_windows', 16:08:21 INFO - 'sessionrestore_no_auto_restore', 16:08:21 INFO - 'tabpaint']}, 16:08:21 INFO - 'other-stylo-e10s': {'talos_options': ['--enable-stylo'], 16:08:21 INFO - 'tests': ['a11yr', 16:08:21 INFO - 'ts_paint', 16:08:21 INFO - 'tpaint', 16:08:21 INFO - 'sessionrestore', 16:08:21 INFO - 'sessionrestore_many_windows', 16:08:21 INFO - 'sessionrestore_no_auto_restore', 16:08:21 INFO - 'tabpaint']}, 16:08:21 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 16:08:21 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 16:08:21 INFO - 'perf-reftest-singletons-stylo-e10s': {'talos_options': ['--enable-stylo'], 16:08:21 INFO - 'tests': ['bloom_basic_singleton']}, 16:08:21 INFO - 'perf-reftest-stylo-e10s': {'talos_options': ['--enable-stylo'], 16:08:21 INFO - 'tests': ['bloom_basic']}, 16:08:21 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:08:21 INFO - 'tsvgr_opacity', 16:08:21 INFO - 'tart', 16:08:21 INFO - 'tscrollx', 16:08:21 INFO - 'tsvg_static']}, 16:08:21 INFO - 'svgr-stylo-e10s': {'talos_options': ['--enable-stylo'], 16:08:21 INFO - 'tests': ['tsvgx', 16:08:21 INFO - 'tsvgr_opacity', 16:08:21 INFO - 'tart', 16:08:21 INFO - 'tscrollx', 16:08:21 INFO - 'tsvg_static']}, 16:08:21 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 16:08:21 INFO - 'tp5o-stylo-e10s': {'pagesets_name': 'tp5n.zip', 16:08:21 INFO - 'talos_options': ['--enable-stylo'], 16:08:21 INFO - 'tests': ['tp5o']}, 16:08:21 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 16:08:21 INFO - 'talos_options': ['--mitmproxy', 16:08:21 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 16:08:21 INFO - '--firstNonBlankPaint'], 16:08:21 INFO - 'tests': ['tp6_google', 16:08:21 INFO - 'tp6_youtube', 16:08:21 INFO - 'tp6_amazon', 16:08:21 INFO - 'tp6_facebook']}, 16:08:21 INFO - 'tp6-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 16:08:21 INFO - 'talos_options': ['--enable-stylo', 16:08:21 INFO - '--mitmproxy', 16:08:21 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 16:08:21 INFO - '--firstNonBlankPaint'], 16:08:21 INFO - 'tests': ['tp6_google', 16:08:21 INFO - 'tp6_youtube', 16:08:21 INFO - 'tp6_amazon', 16:08:21 INFO - 'tp6_facebook']}, 16:08:21 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 16:08:21 INFO - 'talos_options': ['--enable-stylo', 16:08:21 INFO - '--stylo-threads=1', 16:08:21 INFO - '--mitmproxy', 16:08:21 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 16:08:21 INFO - '--firstNonBlankPaint'], 16:08:21 INFO - 'tests': ['tp6_google', 16:08:21 INFO - 'tp6_youtube', 16:08:21 INFO - 'tp6_amazon', 16:08:21 INFO - 'tp6_facebook']}, 16:08:21 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 16:08:21 INFO - 'talos_options': ['--xperf_path', 16:08:21 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:08:21 INFO - 'tests': ['tp5n']}, 16:08:21 INFO - 'xperf-stylo-e10s': {'pagesets_name': 'tp5n.zip', 16:08:21 INFO - 'talos_options': ['--enable-stylo', 16:08:21 INFO - '--xperf_path', 16:08:21 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:08:21 INFO - 'tests': ['tp5n']}}} 16:08:21 INFO - Running post-action listener: _resource_record_post_action 16:08:21 INFO - [mozharness: 2017-09-05 23:08:21.139070Z] Finished populate-webroot step (success) 16:08:21 INFO - [mozharness: 2017-09-05 23:08:21.139160Z] Running create-virtualenv step. 16:08:21 INFO - Running pre-action listener: _resource_record_pre_action 16:08:21 INFO - Running main action method: create_virtualenv 16:08:21 INFO - Creating virtualenv /builds/slave/test/build/venv 16:08:21 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 16:08:21 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 16:08:21 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 16:08:21 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:08:21 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:08:21 INFO - Installing distribute.............................................................................................................................................................................................done. 16:08:22 INFO - Installing pip...............done. 16:08:22 INFO - Return code: 0 16:08:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 16:08:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 16:08:22 INFO - Reading from file tmpfile_stdout 16:08:22 INFO - Output received: 16:08:22 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 16:08:22 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:08:22 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')]} 16:08:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:08:22 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')]} 16:08:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:08:22 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 0x1cf41f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ebda80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2060a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2064090>, '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 0x2064d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x205ef90>, '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': 'dac658600622fd96716c9acf000001b3-1504652868.17228-1316107021', '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 16:08:22 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 16:08:22 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 16:08:22 INFO - Using env: {'DISPLAY': ':0', 16:08:22 INFO - 'HOME': '/home/cltbld', 16:08:22 INFO - 'LANG': 'en_US.UTF-8', 16:08:22 INFO - 'LANGUAGE': 'en_US:en', 16:08:22 INFO - 'LOGNAME': 'cltbld', 16:08:22 INFO - 'MAIL': '/var/mail/cltbld', 16:08:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:08:22 INFO - 'MOZ_NO_REMOTE': '1', 16:08:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:08:22 INFO - 'NO_EM_RESTART': '1', 16:08:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:08:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:08:22 INFO - 'PWD': '/builds/slave/test', 16:08:22 INFO - 'SHELL': '/bin/bash', 16:08:22 INFO - 'SHLVL': '1', 16:08:22 INFO - 'TERM': 'linux', 16:08:22 INFO - 'TMOUT': '86400', 16:08:22 INFO - 'USER': 'cltbld', 16:08:22 INFO - 'XDG_SESSION_COOKIE': 'dac658600622fd96716c9acf000001b3-1504652868.17228-1316107021', 16:08:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:08:22 INFO - '_': '/tools/buildbot/bin/python'} 16:08:23 INFO - Ignoring indexes: http://pypi.python.org/simple/ 16:08:23 INFO - Downloading/unpacking pip>=1.5 16:08:23 INFO - Running setup.py egg_info for package pip 16:08:23 INFO - warning: no files found matching 'pip/cacert.pem' 16:08:23 INFO - warning: no files found matching '*.html' under directory 'docs' 16:08:23 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 16:08:23 INFO - no previously-included directories found matching 'docs/_build/_sources' 16:08:23 INFO - Installing collected packages: pip 16:08:23 INFO - Found existing installation: pip 1.1 16:08:23 INFO - Uninstalling pip: 16:08:23 INFO - Successfully uninstalled pip 16:08:23 INFO - Running setup.py install for pip 16:08:23 INFO - warning: no files found matching 'pip/cacert.pem' 16:08:23 INFO - warning: no files found matching '*.html' under directory 'docs' 16:08:23 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 16:08:23 INFO - no previously-included directories found matching 'docs/_build/_sources' 16:08:23 INFO - Installing pip script to /builds/slave/test/build/venv/bin 16:08:23 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 16:08:23 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 16:08:23 INFO - Successfully installed pip 16:08:23 INFO - Cleaning up... 16:08:23 INFO - Return code: 0 16:08:23 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:08:23 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')]} 16:08:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:08:23 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')]} 16:08:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:08:23 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 0x1cf41f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ebda80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2060a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2064090>, '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 0x2064d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x205ef90>, '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': 'dac658600622fd96716c9acf000001b3-1504652868.17228-1316107021', '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 16:08:23 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 16:08:23 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 16:08:23 INFO - Using env: {'DISPLAY': ':0', 16:08:23 INFO - 'HOME': '/home/cltbld', 16:08:23 INFO - 'LANG': 'en_US.UTF-8', 16:08:23 INFO - 'LANGUAGE': 'en_US:en', 16:08:23 INFO - 'LOGNAME': 'cltbld', 16:08:23 INFO - 'MAIL': '/var/mail/cltbld', 16:08:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:08:23 INFO - 'MOZ_NO_REMOTE': '1', 16:08:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:08:23 INFO - 'NO_EM_RESTART': '1', 16:08:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:08:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:08:23 INFO - 'PWD': '/builds/slave/test', 16:08:23 INFO - 'SHELL': '/bin/bash', 16:08:23 INFO - 'SHLVL': '1', 16:08:23 INFO - 'TERM': 'linux', 16:08:23 INFO - 'TMOUT': '86400', 16:08:23 INFO - 'USER': 'cltbld', 16:08:23 INFO - 'XDG_SESSION_COOKIE': 'dac658600622fd96716c9acf000001b3-1504652868.17228-1316107021', 16:08:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:08:23 INFO - '_': '/tools/buildbot/bin/python'} 16:08:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:08:23 INFO - Downloading/unpacking psutil>=3.1.1 16:08:23 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 16:08:23 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 16:08:23 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 16:08:23 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 16:08:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:08:24 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:08:24 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:08:24 INFO - Installing collected packages: psutil 16:08:24 INFO - Running setup.py install for psutil 16:08:24 INFO - building 'psutil._psutil_linux' extension 16:08:24 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 16:08:24 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 16:08:25 INFO - building 'psutil._psutil_posix' extension 16:08:25 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 16:08:25 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 16:08:25 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:08:25 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:08:25 INFO - Successfully installed psutil 16:08:25 INFO - Cleaning up... 16:08:25 INFO - Return code: 0 16:08:25 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:08:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:08:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:08:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:08:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:08:25 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 0x1cf41f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ebda80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2060a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2064090>, '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 0x2064d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x205ef90>, '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': 'dac658600622fd96716c9acf000001b3-1504652868.17228-1316107021', '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 16:08:25 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 16:08:25 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 16:08:25 INFO - Using env: {'DISPLAY': ':0', 16:08:25 INFO - 'HOME': '/home/cltbld', 16:08:25 INFO - 'LANG': 'en_US.UTF-8', 16:08:25 INFO - 'LANGUAGE': 'en_US:en', 16:08:25 INFO - 'LOGNAME': 'cltbld', 16:08:25 INFO - 'MAIL': '/var/mail/cltbld', 16:08:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:08:25 INFO - 'MOZ_NO_REMOTE': '1', 16:08:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:08:25 INFO - 'NO_EM_RESTART': '1', 16:08:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:08:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:08:25 INFO - 'PWD': '/builds/slave/test', 16:08:25 INFO - 'SHELL': '/bin/bash', 16:08:25 INFO - 'SHLVL': '1', 16:08:25 INFO - 'TERM': 'linux', 16:08:25 INFO - 'TMOUT': '86400', 16:08:25 INFO - 'USER': 'cltbld', 16:08:25 INFO - 'XDG_SESSION_COOKIE': 'dac658600622fd96716c9acf000001b3-1504652868.17228-1316107021', 16:08:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:08:25 INFO - '_': '/tools/buildbot/bin/python'} 16:08:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:08:25 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:08:25 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 16:08:25 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 16:08:25 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 16:08:25 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 16:08:26 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:08:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:08:26 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:08:26 INFO - Installing collected packages: mozsystemmonitor 16:08:26 INFO - Running setup.py install for mozsystemmonitor 16:08:26 INFO - Successfully installed mozsystemmonitor 16:08:26 INFO - Cleaning up... 16:08:26 INFO - Return code: 0 16:08:26 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:08:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:08:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:08:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:08:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:08:26 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 0x1cf41f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ebda80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2060a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2064090>, '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 0x2064d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x205ef90>, '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': 'dac658600622fd96716c9acf000001b3-1504652868.17228-1316107021', '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 16:08:26 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 16:08:26 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 16:08:26 INFO - Using env: {'DISPLAY': ':0', 16:08:26 INFO - 'HOME': '/home/cltbld', 16:08:26 INFO - 'LANG': 'en_US.UTF-8', 16:08:26 INFO - 'LANGUAGE': 'en_US:en', 16:08:26 INFO - 'LOGNAME': 'cltbld', 16:08:26 INFO - 'MAIL': '/var/mail/cltbld', 16:08:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:08:26 INFO - 'MOZ_NO_REMOTE': '1', 16:08:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:08:26 INFO - 'NO_EM_RESTART': '1', 16:08:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:08:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:08:26 INFO - 'PWD': '/builds/slave/test', 16:08:26 INFO - 'SHELL': '/bin/bash', 16:08:26 INFO - 'SHLVL': '1', 16:08:26 INFO - 'TERM': 'linux', 16:08:26 INFO - 'TMOUT': '86400', 16:08:26 INFO - 'USER': 'cltbld', 16:08:26 INFO - 'XDG_SESSION_COOKIE': 'dac658600622fd96716c9acf000001b3-1504652868.17228-1316107021', 16:08:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:08:26 INFO - '_': '/tools/buildbot/bin/python'} 16:08:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:08:26 INFO - Downloading/unpacking jsonschema==2.5.1 16:08:26 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 16:08:26 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 16:08:26 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 16:08:26 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 16:08:28 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:08:28 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:08:28 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 16:08:28 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 16:08:28 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 16:08:28 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 16:08:28 INFO - Downloading functools32-3.2.3-2.tar.gz 16:08:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:08:28 INFO - warning: no files found matching '*.txt' 16:08:28 INFO - no previously-included directories found matching 'build' 16:08:28 INFO - no previously-included directories found matching 'dist' 16:08:28 INFO - no previously-included directories found matching '.git*' 16:08:28 INFO - Installing collected packages: jsonschema, functools32 16:08:28 INFO - Running setup.py install for functools32 16:08:28 INFO - warning: no files found matching '*.txt' 16:08:28 INFO - no previously-included directories found matching 'build' 16:08:28 INFO - no previously-included directories found matching 'dist' 16:08:28 INFO - no previously-included directories found matching '.git*' 16:08:28 INFO - Successfully installed jsonschema functools32 16:08:28 INFO - Cleaning up... 16:08:28 INFO - Return code: 0 16:08:28 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:08: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')]} 16:08:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:08: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')]} 16:08:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:08:28 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1cf41f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ebda80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2060a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2064090>, '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 0x2064d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x205ef90>, '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': 'dac658600622fd96716c9acf000001b3-1504652868.17228-1316107021', '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 16:08:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 16:08:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 16:08:28 INFO - Using env: {'DISPLAY': ':0', 16:08:28 INFO - 'HOME': '/home/cltbld', 16:08:28 INFO - 'LANG': 'en_US.UTF-8', 16:08:28 INFO - 'LANGUAGE': 'en_US:en', 16:08:28 INFO - 'LOGNAME': 'cltbld', 16:08:28 INFO - 'MAIL': '/var/mail/cltbld', 16:08:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:08:28 INFO - 'MOZ_NO_REMOTE': '1', 16:08:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:08:28 INFO - 'NO_EM_RESTART': '1', 16:08:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:08:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:08:28 INFO - 'PWD': '/builds/slave/test', 16:08:28 INFO - 'SHELL': '/bin/bash', 16:08:28 INFO - 'SHLVL': '1', 16:08:28 INFO - 'TERM': 'linux', 16:08:28 INFO - 'TMOUT': '86400', 16:08:28 INFO - 'USER': 'cltbld', 16:08:28 INFO - 'XDG_SESSION_COOKIE': 'dac658600622fd96716c9acf000001b3-1504652868.17228-1316107021', 16:08:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:08:28 INFO - '_': '/tools/buildbot/bin/python'} 16:08:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:08:28 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:08:28 INFO - Cleaning up... 16:08:28 INFO - Return code: 0 16:08:28 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:08: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')]} 16:08:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:08: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')]} 16:08:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:08:28 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1cf41f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ebda80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2060a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2064090>, '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 0x2064d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x205ef90>, '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': 'dac658600622fd96716c9acf000001b3-1504652868.17228-1316107021', '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 16:08:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 16:08:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 16:08:28 INFO - Using env: {'DISPLAY': ':0', 16:08:28 INFO - 'HOME': '/home/cltbld', 16:08:28 INFO - 'LANG': 'en_US.UTF-8', 16:08:28 INFO - 'LANGUAGE': 'en_US:en', 16:08:28 INFO - 'LOGNAME': 'cltbld', 16:08:28 INFO - 'MAIL': '/var/mail/cltbld', 16:08:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:08:28 INFO - 'MOZ_NO_REMOTE': '1', 16:08:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:08:28 INFO - 'NO_EM_RESTART': '1', 16:08:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:08:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:08:28 INFO - 'PWD': '/builds/slave/test', 16:08:28 INFO - 'SHELL': '/bin/bash', 16:08:28 INFO - 'SHLVL': '1', 16:08:28 INFO - 'TERM': 'linux', 16:08:28 INFO - 'TMOUT': '86400', 16:08:28 INFO - 'USER': 'cltbld', 16:08:28 INFO - 'XDG_SESSION_COOKIE': 'dac658600622fd96716c9acf000001b3-1504652868.17228-1316107021', 16:08:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:08:28 INFO - '_': '/tools/buildbot/bin/python'} 16:08:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:08:28 INFO - Downloading/unpacking blobuploader==1.2.4 16:08:28 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 16:08:28 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 16:08:28 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 16:08:28 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 16:08:29 INFO - Downloading blobuploader-1.2.4.tar.gz 16:08:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:08:30 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:08:30 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 16:08:30 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 16:08:30 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 16:08:30 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 16:08:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:08:30 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:08:30 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 16:08:30 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 16:08:30 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 16:08:30 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 16:08:30 INFO - Downloading docopt-0.6.1.tar.gz 16:08:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:08:30 INFO - Installing collected packages: blobuploader, requests, docopt 16:08:30 INFO - Running setup.py install for blobuploader 16:08:30 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:08:30 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:08:30 INFO - Running setup.py install for requests 16:08:30 INFO - Running setup.py install for docopt 16:08:31 INFO - Successfully installed blobuploader requests docopt 16:08:31 INFO - Cleaning up... 16:08:31 INFO - Return code: 0 16:08:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:08: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')]} 16:08:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:08: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')]} 16:08:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:08:31 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 0x1cf41f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ebda80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2060a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2064090>, '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 0x2064d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x205ef90>, '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': 'dac658600622fd96716c9acf000001b3-1504652868.17228-1316107021', '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 16:08:31 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 16:08:31 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 16:08:31 INFO - Using env: {'DISPLAY': ':0', 16:08:31 INFO - 'HOME': '/home/cltbld', 16:08:31 INFO - 'LANG': 'en_US.UTF-8', 16:08:31 INFO - 'LANGUAGE': 'en_US:en', 16:08:31 INFO - 'LOGNAME': 'cltbld', 16:08:31 INFO - 'MAIL': '/var/mail/cltbld', 16:08:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:08:31 INFO - 'MOZ_NO_REMOTE': '1', 16:08:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:08:31 INFO - 'NO_EM_RESTART': '1', 16:08:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:08:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:08:31 INFO - 'PWD': '/builds/slave/test', 16:08:31 INFO - 'SHELL': '/bin/bash', 16:08:31 INFO - 'SHLVL': '1', 16:08:31 INFO - 'TERM': 'linux', 16:08:31 INFO - 'TMOUT': '86400', 16:08:31 INFO - 'USER': 'cltbld', 16:08:31 INFO - 'XDG_SESSION_COOKIE': 'dac658600622fd96716c9acf000001b3-1504652868.17228-1316107021', 16:08:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:08:31 INFO - '_': '/tools/buildbot/bin/python'} 16:08:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:08:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:08:31 INFO - Running setup.py (path:/tmp/pip-v0lQdb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:08:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:08:31 INFO - Running setup.py (path:/tmp/pip-QtHvys-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:08:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:08:31 INFO - Running setup.py (path:/tmp/pip-Wxuz7b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:08:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:08:31 INFO - Running setup.py (path:/tmp/pip-77ISVj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:08:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:08:31 INFO - Running setup.py (path:/tmp/pip-FmW9mB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:08:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:08:31 INFO - Running setup.py (path:/tmp/pip-JTebV2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:08:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:08:31 INFO - Running setup.py (path:/tmp/pip-oIyBEb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:08:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:08:31 INFO - Running setup.py (path:/tmp/pip-qo0Co9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:08:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:08:31 INFO - Running setup.py (path:/tmp/pip-6PyGUL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:08:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:08:31 INFO - Running setup.py (path:/tmp/pip-88FEQE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:08:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:08:32 INFO - Running setup.py (path:/tmp/pip-9SBwy7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:08:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:08:32 INFO - Running setup.py (path:/tmp/pip-yrbnxg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:08:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:08:32 INFO - Running setup.py (path:/tmp/pip-ijFLwV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:08:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:08:32 INFO - Running setup.py (path:/tmp/pip-kNbKrh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:08:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:08:32 INFO - Running setup.py (path:/tmp/pip-AkMMJ9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:08:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:08:32 INFO - Running setup.py (path:/tmp/pip-gjGtnz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:08:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:08:32 INFO - Running setup.py (path:/tmp/pip-EPWvYJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:08:32 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:08:32 INFO - Running setup.py install for manifestparser 16:08:32 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:08:32 INFO - Running setup.py install for mozcrash 16:08:32 INFO - Running setup.py install for mozdebug 16:08:32 INFO - Running setup.py install for mozdevice 16:08:32 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:08:32 INFO - Running setup.py install for mozfile 16:08:33 INFO - Running setup.py install for mozhttpd 16:08:33 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:08:33 INFO - Running setup.py install for mozinfo 16:08:33 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:08:33 INFO - Running setup.py install for mozInstall 16:08:33 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:08:33 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:08:33 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:08:33 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:08:33 INFO - Running setup.py install for mozleak 16:08:33 INFO - Running setup.py install for mozlog 16:08:33 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:08:33 INFO - Running setup.py install for moznetwork 16:08:33 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:08:33 INFO - Running setup.py install for mozprocess 16:08:33 INFO - Running setup.py install for mozprofile 16:08:33 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:08:33 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:08:33 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:08:33 INFO - Running setup.py install for mozrunner 16:08:33 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:08:34 INFO - Running setup.py install for mozscreenshot 16:08:34 INFO - Running setup.py install for moztest 16:08:34 INFO - Running setup.py install for mozversion 16:08:34 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:08:34 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:08:34 INFO - Cleaning up... 16:08:34 INFO - Return code: 0 16:08:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:08: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')]} 16:08:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:08: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')]} 16:08:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:08:34 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 0x1cf41f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ebda80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2060a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2064090>, '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 0x2064d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x205ef90>, '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': 'dac658600622fd96716c9acf000001b3-1504652868.17228-1316107021', '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 16:08:34 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 16:08:34 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 16:08:34 INFO - Using env: {'DISPLAY': ':0', 16:08:34 INFO - 'HOME': '/home/cltbld', 16:08:34 INFO - 'LANG': 'en_US.UTF-8', 16:08:34 INFO - 'LANGUAGE': 'en_US:en', 16:08:34 INFO - 'LOGNAME': 'cltbld', 16:08:34 INFO - 'MAIL': '/var/mail/cltbld', 16:08:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:08:34 INFO - 'MOZ_NO_REMOTE': '1', 16:08:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:08:34 INFO - 'NO_EM_RESTART': '1', 16:08:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:08:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:08:34 INFO - 'PWD': '/builds/slave/test', 16:08:34 INFO - 'SHELL': '/bin/bash', 16:08:34 INFO - 'SHLVL': '1', 16:08:34 INFO - 'TERM': 'linux', 16:08:34 INFO - 'TMOUT': '86400', 16:08:34 INFO - 'USER': 'cltbld', 16:08:34 INFO - 'XDG_SESSION_COOKIE': 'dac658600622fd96716c9acf000001b3-1504652868.17228-1316107021', 16:08:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:08:34 INFO - '_': '/tools/buildbot/bin/python'} 16:08:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:08:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:08:34 INFO - Running setup.py (path:/tmp/pip-QwkHrt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:08:34 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)) 16:08:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:08:34 INFO - Running setup.py (path:/tmp/pip-SjHybg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:08:34 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)) 16:08:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:08:34 INFO - Running setup.py (path:/tmp/pip-VCP6pT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:08:34 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)) 16:08:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:08:34 INFO - Running setup.py (path:/tmp/pip-Rm7Xro-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:08:34 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)) 16:08:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:08:34 INFO - Running setup.py (path:/tmp/pip-rTtLqS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:08:34 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)) 16:08:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:08:34 INFO - Running setup.py (path:/tmp/pip-W39BZL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:08:34 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)) 16:08:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:08:34 INFO - Running setup.py (path:/tmp/pip-hTfA8_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:08:35 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)) 16:08:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:08:35 INFO - Running setup.py (path:/tmp/pip-xnrCw4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:08:35 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)) 16:08:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:08:35 INFO - Running setup.py (path:/tmp/pip-71vgpV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:08:35 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)) 16:08:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:08:35 INFO - Running setup.py (path:/tmp/pip-6RSKQH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:08:35 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)) 16:08:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:08:35 INFO - Running setup.py (path:/tmp/pip-AATIb7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:08:35 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)) 16:08:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:08:35 INFO - Running setup.py (path:/tmp/pip-r8mYtE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:08:35 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)) 16:08:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:08:35 INFO - Running setup.py (path:/tmp/pip-Z5aWUI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:08:35 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)) 16:08:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:08:35 INFO - Running setup.py (path:/tmp/pip-iTG79_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:08:35 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)) 16:08:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:08:35 INFO - Running setup.py (path:/tmp/pip-O1Fp_M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:08:35 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)) 16:08:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:08:35 INFO - Running setup.py (path:/tmp/pip-l4BWTD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:08:35 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)) 16:08:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:08:35 INFO - Running setup.py (path:/tmp/pip-pkdvfv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:08:35 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)) 16:08:35 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)) 16:08:35 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)) 16:08:35 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)) 16:08:35 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)) 16:08:35 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)) 16:08:35 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)) 16:08:35 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:08: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 16:08: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 16:08: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 16:08: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 16:08:36 INFO - Downloading blessings-1.6.tar.gz 16:08:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:08:36 INFO - Installing collected packages: blessings 16:08:36 INFO - Running setup.py install for blessings 16:08:37 INFO - Successfully installed blessings 16:08:37 INFO - Cleaning up... 16:08:37 INFO - Return code: 0 16:08:37 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:08:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:08:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:08:37 INFO - Reading from file tmpfile_stdout 16:08:37 INFO - Current package versions: 16:08:37 INFO - argparse == 1.2.1 16:08:37 INFO - blessings == 1.6 16:08:37 INFO - blobuploader == 1.2.4 16:08:37 INFO - docopt == 0.6.1 16:08:37 INFO - functools32 == 3.2.3-2 16:08:37 INFO - jsonschema == 2.5.1 16:08:37 INFO - manifestparser == 1.1 16:08:37 INFO - mozInstall == 1.13 16:08:37 INFO - mozcrash == 1.0 16:08:37 INFO - mozdebug == 0.1 16:08:37 INFO - mozdevice == 0.51 16:08:37 INFO - mozfile == 1.2 16:08:37 INFO - mozhttpd == 0.7 16:08:37 INFO - mozinfo == 0.10 16:08:37 INFO - mozleak == 0.1 16:08:37 INFO - mozlog == 3.5 16:08:37 INFO - moznetwork == 0.27 16:08:37 INFO - mozprocess == 0.25 16:08:37 INFO - mozprofile == 0.28 16:08:37 INFO - mozrunner == 6.13 16:08:37 INFO - mozscreenshot == 0.1 16:08:37 INFO - mozsystemmonitor == 0.3 16:08:37 INFO - moztest == 0.8 16:08:37 INFO - mozversion == 1.4 16:08:37 INFO - psutil == 3.1.1 16:08:37 INFO - requests == 1.2.3 16:08:37 INFO - wsgiref == 0.1.2 16:08:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:08: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')]} 16:08:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:08: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')]} 16:08:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:08:37 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 0x1cf41f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ebda80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2060a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2064090>, '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 0x2064d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x205ef90>, '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': 'dac658600622fd96716c9acf000001b3-1504652868.17228-1316107021', '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 16:08:37 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 16:08:37 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 16:08:37 INFO - Using env: {'DISPLAY': ':0', 16:08:37 INFO - 'HOME': '/home/cltbld', 16:08:37 INFO - 'LANG': 'en_US.UTF-8', 16:08:37 INFO - 'LANGUAGE': 'en_US:en', 16:08:37 INFO - 'LOGNAME': 'cltbld', 16:08:37 INFO - 'MAIL': '/var/mail/cltbld', 16:08:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:08:37 INFO - 'MOZ_NO_REMOTE': '1', 16:08:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:08:37 INFO - 'NO_EM_RESTART': '1', 16:08:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:08:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:08:37 INFO - 'PWD': '/builds/slave/test', 16:08:37 INFO - 'SHELL': '/bin/bash', 16:08:37 INFO - 'SHLVL': '1', 16:08:37 INFO - 'TERM': 'linux', 16:08:37 INFO - 'TMOUT': '86400', 16:08:37 INFO - 'USER': 'cltbld', 16:08:37 INFO - 'XDG_SESSION_COOKIE': 'dac658600622fd96716c9acf000001b3-1504652868.17228-1316107021', 16:08:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:08:37 INFO - '_': '/tools/buildbot/bin/python'} 16:08:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:08:37 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)) 16:08:37 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)) 16:08:37 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)) 16:08:37 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)) 16:08:37 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)) 16:08:37 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)) 16:08:37 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)) 16:08:37 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)) 16:08:37 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)) 16:08:37 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 16:08: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 16:08: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 16:08: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 16:08: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 16:08:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 16:08:38 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)) 16:08:38 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)) 16:08:38 INFO - Installing collected packages: simplejson 16:08:38 INFO - Running setup.py install for simplejson 16:08:38 INFO - building 'simplejson._speedups' extension 16:08:38 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 16:08:39 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 16:08:39 INFO - Successfully installed simplejson 16:08:39 INFO - Cleaning up... 16:08:39 INFO - Return code: 0 16:08:39 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:08:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:08:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:08:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:08:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:08:39 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 0x1cf41f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ebda80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2060a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2064090>, '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 0x2064d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x205ef90>, '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': 'dac658600622fd96716c9acf000001b3-1504652868.17228-1316107021', '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 16:08:39 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 16:08:39 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 16:08:39 INFO - Using env: {'DISPLAY': ':0', 16:08:39 INFO - 'HOME': '/home/cltbld', 16:08:39 INFO - 'LANG': 'en_US.UTF-8', 16:08:39 INFO - 'LANGUAGE': 'en_US:en', 16:08:39 INFO - 'LOGNAME': 'cltbld', 16:08:39 INFO - 'MAIL': '/var/mail/cltbld', 16:08:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:08:39 INFO - 'MOZ_NO_REMOTE': '1', 16:08:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:08:39 INFO - 'NO_EM_RESTART': '1', 16:08:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:08:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:08:39 INFO - 'PWD': '/builds/slave/test', 16:08:39 INFO - 'SHELL': '/bin/bash', 16:08:39 INFO - 'SHLVL': '1', 16:08:39 INFO - 'TERM': 'linux', 16:08:39 INFO - 'TMOUT': '86400', 16:08:39 INFO - 'USER': 'cltbld', 16:08:39 INFO - 'XDG_SESSION_COOKIE': 'dac658600622fd96716c9acf000001b3-1504652868.17228-1316107021', 16:08:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:08:39 INFO - '_': '/tools/buildbot/bin/python'} 16:08:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:08:40 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:08:40 INFO - Cleaning up... 16:08:40 INFO - Return code: 0 16:08:40 INFO - Running post-action listener: _resource_record_post_action 16:08:40 INFO - Running post-action listener: _start_resource_monitoring 16:08:40 INFO - Starting resource monitoring. 16:08:40 INFO - [mozharness: 2017-09-05 23:08:40.078319Z] Finished create-virtualenv step (success) 16:08:40 INFO - [mozharness: 2017-09-05 23:08:40.078834Z] Running install step. 16:08:40 INFO - Running pre-action listener: _resource_record_pre_action 16:08:40 INFO - Running main action method: install 16:08:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:08:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:08:40 INFO - Reading from file tmpfile_stdout 16:08:40 INFO - Detecting whether we're running mozinstall >=1.0... 16:08:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:08:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:08:40 INFO - Reading from file tmpfile_stdout 16:08:40 INFO - Output received: 16:08:40 INFO - Usage: mozinstall [options] installer 16:08:40 INFO - Options: 16:08:40 INFO - -h, --help show this help message and exit 16:08:40 INFO - -d DEST, --destination=DEST 16:08:40 INFO - Directory to install application into. [default: 16:08:40 INFO - "/builds/slave/test"] 16:08:40 INFO - --app=APP Application being installed. [default: firefox] 16:08:40 INFO - mkdir: /builds/slave/test/build/application 16:08:40 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'] 16:08:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 16:08:56 INFO - Reading from file tmpfile_stdout 16:08:56 INFO - Output received: 16:08:56 INFO - /builds/slave/test/build/application/firefox/firefox 16:08:56 INFO - Running post-action listener: _resource_record_post_action 16:08:56 INFO - [mozharness: 2017-09-05 23:08:56.953212Z] Finished install step (success) 16:08:56 INFO - [mozharness: 2017-09-05 23:08:56.953373Z] Running setup-mitmproxy step. 16:08:56 INFO - Running pre-action listener: _resource_record_pre_action 16:08:56 INFO - Running main action method: setup_mitmproxy 16:08:56 INFO - Skipping: mitmproxy is not required 16:08:56 INFO - Running post-action listener: _resource_record_post_action 16:08:56 INFO - [mozharness: 2017-09-05 23:08:56.954505Z] Finished setup-mitmproxy step (success) 16:08:56 INFO - [mozharness: 2017-09-05 23:08:56.954723Z] Running run-tests step. 16:08:56 INFO - Running pre-action listener: _resource_record_pre_action 16:08:56 INFO - Running pre-action listener: _set_gcov_prefix 16:08:56 INFO - Running main action method: run_tests 16:08:56 WARNING - Try message not found. 16:08:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:08:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:08:56 INFO - Python 2.7.3 16:08:56 INFO - Return code: 0 16:08:56 INFO - grabbing minidump binary from tooltool 16:08:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:08:56 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 0x2064090>, '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 0x2064d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x205ef90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:08:56 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 16:08:56 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 16:08:56 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 16:08:57 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:08:57 INFO - Return code: 0 16:08:57 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 16:08:57 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:08:57 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:08:57 INFO - ENV: RUST_BACKTRACE is now full 16:08:57 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 16:08:57 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:08:57 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/VQZMjgW9QkOpdRucLZyMvw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-078', '--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 16:08:57 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/VQZMjgW9QkOpdRucLZyMvw/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-078 --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 16:08:57 INFO - Using env: {'DISPLAY': ':0', 16:08:57 INFO - 'HOME': '/home/cltbld', 16:08:57 INFO - 'LANG': 'en_US.UTF-8', 16:08:57 INFO - 'LANGUAGE': 'en_US:en', 16:08:57 INFO - 'LOGNAME': 'cltbld', 16:08:57 INFO - 'MAIL': '/var/mail/cltbld', 16:08:57 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:08:57 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:08:57 INFO - 'MOZ_ACCELERATED': '1', 16:08:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:08:57 INFO - 'MOZ_NO_REMOTE': '1', 16:08:57 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:08:57 INFO - 'MOZ_WEBRENDER': '1', 16:08:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:08:57 INFO - 'NO_EM_RESTART': '1', 16:08:57 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:08:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:08:57 INFO - 'PWD': '/builds/slave/test', 16:08:57 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:08:57 INFO - 'RUST_BACKTRACE': 'full', 16:08:57 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 16:08:57 INFO - 'SHELL': '/bin/bash', 16:08:57 INFO - 'SHLVL': '1', 16:08:57 INFO - 'TERM': 'linux', 16:08:57 INFO - 'TMOUT': '86400', 16:08:57 INFO - 'USER': 'cltbld', 16:08:57 INFO - 'XDG_SESSION_COOKIE': 'dac658600622fd96716c9acf000001b3-1504652868.17228-1316107021', 16:08:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:08:57 INFO - '_': '/tools/buildbot/bin/python'} 16:08:57 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/VQZMjgW9QkOpdRucLZyMvw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-078', '--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 16:08:57 INFO - mozversion application_buildid: 20170905215634 16:08:57 INFO - mozversion application_changeset: f64e2b4dcf5eec0b4ad456c149680a67b7c26dc4 16:08:57 INFO - mozversion application_display_name: Nightly 16:08:57 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:08:57 INFO - mozversion application_name: Firefox 16:08:57 INFO - mozversion application_remotingname: firefox 16:08:57 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 16:08:57 INFO - mozversion application_vendor: Mozilla 16:08:57 INFO - mozversion application_version: 57.0a1 16:08:57 INFO - mozversion platform_buildid: 20170905215634 16:08:57 INFO - mozversion platform_changeset: f64e2b4dcf5eec0b4ad456c149680a67b7c26dc4 16:08:57 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 16:08:57 INFO - mozversion platform_version: 57.0a1 16:08:57 INFO - using testdate: 1504652937 16:08:57 INFO - actual date: 1504652937 16:08:57 INFO - starting webserver on 'localhost:56284' 16:08:57 INFO - SUITE-START | Running 2 tests 16:08:57 INFO - TEST-START | dromaeo_css 16:08:57 INFO - Initialising browser for dromaeo_css test... 16:08:57 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:56284/getInfo.html -profile /tmp/tmpW1SIA5/profile 16:08:57 INFO - TEST-INFO | started process 9707 (/builds/slave/test/build/application/firefox/firefox http://localhost:56284/getInfo.html) 16:09:03 INFO - TEST-INFO | 9707: exit 0 16:09:04 INFO - Browser initialized. 16:09:04 INFO - Running cycle 1/1 for dromaeo_css test... 16:09:04 INFO - Using env: {'DISPLAY': ':0', 16:09:04 INFO - 'HOME': '/home/cltbld', 16:09:04 INFO - 'JSGC_DISABLE_POISONING': '1', 16:09:04 INFO - 'LANG': 'en_US.UTF-8', 16:09:04 INFO - 'LANGUAGE': 'en_US:en', 16:09:04 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 16:09:04 INFO - 'LOGNAME': 'cltbld', 16:09:04 INFO - 'MAIL': '/var/mail/cltbld', 16:09:04 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:09:04 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:09:04 INFO - 'MOZ_ACCELERATED': '1', 16:09:04 INFO - 'MOZ_CRASHREPORTER': '1', 16:09:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:09:04 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 16:09:04 INFO - 'MOZ_NO_REMOTE': '1', 16:09:04 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:09:04 INFO - 'MOZ_WEBRENDER': '1', 16:09:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:09:04 INFO - 'NO_EM_RESTART': '1', 16:09:04 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:09:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:09:04 INFO - 'PWD': '/builds/slave/test', 16:09:04 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:09:04 INFO - 'RUST_BACKTRACE': 'full', 16:09:04 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 16:09:04 INFO - 'SHELL': '/bin/bash', 16:09:04 INFO - 'SHLVL': '1', 16:09:04 INFO - 'TERM': 'linux', 16:09:04 INFO - 'TMOUT': '86400', 16:09:04 INFO - 'USER': 'cltbld', 16:09:04 INFO - 'XDG_SESSION_COOKIE': 'dac658600622fd96716c9acf000001b3-1504652868.17228-1316107021', 16:09:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:09:04 INFO - '_': '/tools/buildbot/bin/python'} 16:09:04 INFO - TEST-INFO | started process 9905 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpW1SIA5/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 16:09:04 INFO - PID 9905 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 16:09:05 INFO - PID 9905 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 16:09:05 INFO - PID 9905 | 16:09:05 INFO - PID 9905 | (/builds/slave/test/build/application/firefox/firefox:9967): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 16:09:05 INFO - PID 9905 | 16:09:15 INFO - PID 9905 | RSS: Main: 202506240 16:09:15 INFO - PID 9905 | 16:09:19 INFO - PID 9905 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 16:12:12 INFO - PID 9905 | Cycle 1(1): loaded http://localhost:56284/tests/dromaeo/cssquery-dojo.html (next: http://localhost:56284/tests/dromaeo/cssquery-ext.html) 16:12:14 INFO - PID 9905 | RSS: Main: 217890816 16:12:14 INFO - PID 9905 | 16:15:16 INFO - PID 9905 | Cycle 1(1): loaded http://localhost:56284/tests/dromaeo/cssquery-ext.html (next: http://localhost:56284/tests/dromaeo/cssquery-jquery.html) 16:15:16 INFO - PID 9905 | RSS: Main: 214089728 16:15:16 INFO - PID 9905 | 16:18:18 INFO - PID 9905 | Cycle 1(1): loaded http://localhost:56284/tests/dromaeo/cssquery-jquery.html (next: http://localhost:56284/tests/dromaeo/cssquery-mootools.html) 16:18:19 INFO - PID 9905 | RSS: Main: 219668480 16:18:19 INFO - PID 9905 | 16:21:21 INFO - PID 9905 | Cycle 1(1): loaded http://localhost:56284/tests/dromaeo/cssquery-mootools.html (next: http://localhost:56284/tests/dromaeo/cssquery-prototype.html) 16:21:22 INFO - PID 9905 | RSS: Main: 211808256 16:21:22 INFO - PID 9905 | 16:24:25 INFO - PID 9905 | Cycle 1(1): loaded http://localhost:56284/tests/dromaeo/cssquery-prototype.html (next: http://localhost:56284/tests/dromaeo/cssquery-yui.html) 16:24:26 INFO - PID 9905 | RSS: Main: 216068096 16:24:26 INFO - PID 9905 | 16:27:28 INFO - PID 9905 | Cycle 1(1): loaded http://localhost:56284/tests/dromaeo/cssquery-yui.html (next: http://localhost:56284/tests/dromaeo/cssquery-dojo.html) 16:27:28 INFO - PID 9905 | RSS: Main: 219545600 16:27:28 INFO - PID 9905 | 16:27:28 INFO - PID 9905 | __start_tp_report 16:27:28 INFO - PID 9905 | _x_x_mozilla_page_load 16:27:28 INFO - PID 9905 | _x_x_mozilla_page_load_details 16:27:28 INFO - PID 9905 | |i|pagename|runs| 16:27:28 INFO - PID 9905 | |0;dojo.html;3230.6769323067697;3240.432924238258;3264.0860559043476;3298.9505157422636;3316.800991940483;6233.314335423105;6350.4602108820745;6439.549231553793;6472;6476.967615161918;6149.538784591163;6181.474574661159;6243.344448832875;6255.155554000208;6321.051842223657;5644.153376993463;5696.231008813798;5710.800121995731;5741;5774.595778295521;5752.367239603661;5860.267466566679;5862.618929769573;5883.205767221413;5953.136795164699;5817.912731309013;5894.734736936826;5897.528197744192;5899.410058994109;5913.379095195007;5805.883882322372;5915.585908986352;5919.467247947706;5926.8814623707285;5956.315023772257;5535.418781027975;5719.170720245583;5820.185174075633;5842.766289348421;5847.44449277318;5620.859478513029;6212.751489940397;6227.782027629011;6334.1765570476;6337.524685648595;6796.6941487632685;6819.488538359643;6883.965580172067;6924.099867017256;6931.41083007945;6662.600243985328;6754.2907994660345;6799.898001529981;6809.250982391933;6947.7220911163495;6272.749090036439;6303.621782693007;6305.148804911324;6321.936780632181;6331.398517140864;6932.272111428228;6944.583325000516;6946.826329341807;6952.860942781116;6975.930240697629;6537.901931471032;6633.4693224541925;6645.302243264484;6647.501437392215;6656.36764507371;6773.695183716646;6804.08144900442;6819.897701534481;6849.931500684929;6852.5203235773015;6722.865542689119;6740.258571557125;6842.213145488233;6854.725810967506;6875.621840798704;6552.541322107407;6808.557443766139;6822.044913712084;6833.145856767904;6866.725330986805;4946.505349465025;4968.378952630921;5003.5997120230295;5010.298558201855;5011.223260394571;4480.507144214133;4480.551944805558;4522.140793249272;4529.275315949432;4529.297958816389;5814.360420353758;5938.732757025944;5982.880342393215;5983.940160598426;6079.544034197453;6800.013997970365;6801.4898882584;6807.829804254933;6823.829404264933;6824;5924.229850119457;5939.673317967553;5955.404459554096;5960.970195148997;5964.433378829004;5846.619969702041;5848.590598658138;5854.736536855852;5854.941450585525;5860.501857342174;6445;6458.903116453257;6507.544471886922;6576.605403675795;6590.406863382319;5930.851728706817;6840.657967101724;6867.141607299088;6890.586564806128;6970.989206565121;6587.440067594308;6647.135872336566;6731.831704207434;6745;6746.257911629618;6571.507136964651;6639.03733958583;6648.5346025777735;6654.534182607171;6669.032990216295;6737.629430381376;6759.864802704017;6771.255161932282;6775.424088952297;6801.795946121624;6497.902531462127;6517.120188774455;6560.081588577507;6564.343565643398;6568.638724870178;6600.174978127734;6613.107230523818;6631.535792494672;6694.866102678016;6701.32986701326;6493.058506516434;6500.934990650033;6553.737850485927;6580.276169621242;6594.637294948824;6433.8713225736155;6545;6572.737090516326;6620.536562440776;6627.204735431779;5535.640183388067;6331.366863313632;6359.841003974938;6361.109444677656;6401.839954001187 16:27:28 INFO - PID 9905 | |1;ext.html;808.8099296665283;839.374665873924;876.7413612984169;877.8727084572739;1127.8702949160838;28630.570541441895;29959.85020074899;37460.25079498409;39136;39871.20257594853;39505.80247098768;39679.40480892789;39695.206095878064;39853.80073099638;40095.59904400955;7406.07424071991;7624.199459056802;7662.961685191566;7713.305802477776;7791.610419479031;7529.698812047512;7613.314801667849;7635.083789945186;7647.120581133157;7679.57762323072;7647.732329368473;7680.155182929873;7710.074791025057;7725.111612164616;7730;7583.01420815296;7666.923330766676;7690.846183076307;7696.307332340116;7706.922930770676;7598.20218877021;7610.733624323122;7620.161782203953;7639.770806875803;7657.234276572354;35873;35973.82013089944;36028.27943441117;36280;36310.45534316987;3490.2844916791996;3492.772969756983;3516.2791627716256;3525.665061819123;3530.9117272068147;29796.255093622614;29828.254293642833;29905;30094.849525752452;30110.69889301101;4957.355543779322;5135.794568217267;5175.922361164586;5197.610179236573;5271.920921186185;182024;183083;183626;184760;185352.99999999866;45077;45156.77421612871;45213.77393113079;45273.773631132295;45431.31853022207;47142.5285747145;47213.763931180125;47255.5274447258;49835.75082124566;51483.7425812876;12608.873911260953;14150.43398264058;14290.857091429161;14372.137671739729;14426;10272.537735801907;10332.276740628084;10782.838257426145;11103.333799972028;11160.330380177214;13957.092788968685;13962.511312104027;14107.435702571987;14196;14259.928700356431;5564.387917329091;5710.857228569236;5786.189933409326;5840.766369345263;5848.064309710426;1004;1009.1422291052693;1015.8425444056184;1016.2581315639625;1021.9131373833308;22389.664155037688;22429.32712018642;22603.43491412695;22635.547289054128;22879.428014299774;1090.36758679967;1098.653924013933;1100.8403781451646;1105.0606984063481;1115.966521004371;11891.583794567321;11966.162368634112;12099.213551119148;12151;12202.633920982385;229240;230755;231386;232547;233096;49840.252396213364;50005.74997124991;50118.49881501211;50183.24725129126;50382.74808625934;1416.886649068072;1417.2842714429353;1427.8429372768987;1434.5194359889344;1437.6405898525368;21587.460313492287;21790.78209217951;21973.560528789014;22414.77585224192;22489;34834.303313934084;34838.12904677302;34871.65128348785;34876.30247394987;34896.47655285223;187009;187676;188132;188280;188554;32155.784421558594;32274;32540.349193016482;32696.18259543435;32934.83532582322;43015.35476967911;44027.559724402345;44085.559144408144;44242;44594;31924;32070.518942215418;32224;32373.514397283587;32765.672343277216;31319.21701957378;32054.35891282208;32338.868139615002;32434;32695;36064.81967590145;36368.27263454769;37304.25391492209;37339.439908400855;37357.25285494329;31566.36867262688;31924.36151276915;32122;32364.35271294514;32378.67621323851;23303.76696233084;23472.715377969846;23501.294961151194;23771.88114059488;24122.87938560296 16:27:28 INFO - PID 9905 | |2;jquery.html;553.222722075484;567.9290088738908;571.1603942204957;571.7655761137931;580.1471836400491;21388;22086.447838804037;22177;22231;22249.555008899813;23744.88127559364;24895.502089958234;25716.74283257167;25919.09283175089;26241.86879065602;6437.195350581165;6570.211574611029;6589.670516474181;6595.571287866273;6601.042848786923;6478.902816457733;6518.543701940866;6524.804255872332;6525.053867189278;6545.247296560885;5872.236609240793;6414;6432.099506069154;6449.419552240297;6465.321141280168;6370.267419246777;6407.87184256317;6425.614463132204;6428.164338635971;6439.162908821823;5905.173275741399;5939.970300148515;5944.524438044946;5945.672987985659;5965.075413310944;28658.570121448196;29061.273468163254;29603;29834.403311933856;29937;1996.6805311150147;2050.0979568989596;2055.3011975928107;2058.8970551472366;2059.6395630764705;19128.33050843228;19689.80310196894;19780.50548736279;19791.307304244427;19804.207831686716;2257.9548409031727;2357.8938947747392;2395.0060724799187;2417.4802417480328;2420.6248031555137;137902;139882.3005884964;140332;142010;144252;14253.928730356489;15187.544373668808;15249.6187595311;15402.46091386796;15614.76577851333;23244.535109297718;23304.53390932172;23407.180748673716;23456.296311110695;23506.9421876016;9274.21169200612;9758.853617195748;9844.261680374;10010.64962726301;10038.949805250926;8837.29301655866;8945.463272203559;8946.955265223762;8991.235744961752;9047.773805654911;8956.910430895738;9190.540472976325;9210.309226808016;9250.537473126316;9475.336726429085;2802.705715899842;2852.229897927548;2894.1751600793673;2900.7244311790346;2990.551417287424;969.680005598151;998.5955687946393;999.3554157568328;1000.2897942460788;1020.5560581147224;19478.513037174183;20032.89983550102;20467.251103909166;20891.58216835655;20917.89541052285;1053.4100903494068;1057.9947100264449;1068.8183008888507;1070.5664205996584;1070.7911957168433;11294.54821807135;11322.773544529064;11370.715732106599;11394.544218231178;11532.711682208012;421628;425682;427926;430023;439519;115526;117672;118837.40581297211;120226;122754;1163.3252713426193;1166.1894982986912;1196.485511230162;1203.8796120388067;1209.0690168570152;17817.910910445364;17926.641467170324;17950.640987180446;18040.729389058914;18368.448946531626;57969.42030579809;58359.70820145872;58861.41138588559;60732;61174.38825611687;71726.28273717404;71781;71860.64069679618;72373.638131809;73129;14582.416703331748;14612.342444589807;14690.338934748175;14799.630009249855;15143;23675.64486532738;23952;24039.27882163538;24066.15768448096;24110.87944560266;18837.6232475347;18884.811151888305;19146.61706765829;19248.133833977223;19259.807401925802;15753.606159845636;15889.967152135074;16134.031958082553;16257.349706011628;16781.412650557173;17305.30778768835;17470.21384037696;17535.912320438318;17580.296788128333;17626.823731762517;14021.8782497402;14807.333669985092;14814.555563333117;14822.481213157487;14845.40618375296;18479.353222637143;18573.81426185721;18679.346222882134;18683.252669893052;18795.436136915367 16:27:28 INFO - PID 9905 | |3;mootools.html;1305.1581729784289;1353.7969304604308;1392.7423426666066;1397.3711829676647;1398.5314919501966;14733.263336833155;15393.153376564285;15459.304331305077;15462.768058479118;15485.303161357739;14823.481178158767;15775.290111944962;15788.289526971284;15934;15947.681046379066;2714.5792402177694;2775.5420355641295;2777.9027734029314;2780.805343625947;2780.9443811123765;2598.2984594159566;2736;2737.7399147080996;2738.5070687276284;2738.657667791526;2278.851874628152;2316.9536609267802;2337.3805941425535;2337.5091230841545;2342.1802369170823;2595.805314601403;2621.5412302847117;2624.986875065622;2632.6182703507884;2636.538605743997;2571.1772232885482;2589.3138318345655;2625.5930330798756;2627.1067836935536;2627.8160528762996;14730.558083257412;14965.551033469013;14981.250937453084;14981.850181498154;15134.772978405334;7784.182660820589;8098.230668086522;8112.918870811275;8119.3910456715985;8137.75586732399;5323.627346085776;5490.039243132475;5618.6628802272;5635;5642.097264437671;1945.2121890634317;1967.9016049197626;1978.0999645161417;2003.24878170686;2012.9230861489034;23592.88203558971;23777.881110594335;23898.880505597535;23997.520049599087;24008.27975160748;6368.267649220399;6570.638614876228;6634.469242460592;6726.3609957054005;6748.122744043243;16097.597560061093;16133.274002669908;16182.595435114215;16187.838121618634;16188.10965396891;7836.333911617462;7936.642851071716;7936.960315198387;7940.483868548641;8024.358051355877;6057.878842423127;6165.722542485599;6407.327230640809;6415.422611964946;6419.293877673452;5146.845594632167;5259.289995850588;5340.572754179656;5372.946270537323;5377.650452720561;1539.1150088699044;1592.6257329527552;1593.9840601594067;1611.8710503159718;1613.5159452164303;915.771057235691;928.5774972387547;936.0826390137568;952.518977916148;953.7615596100975;7967.641456134488;8706.347023973227;8733.781655458664;8734.519601421856;8816.77958051041;679.4021261290034;681.9693113809891;685.8833998220315;704.9647517624098;706.5442789400911;4808.2547205183255;4893.559579637849;5241.790328386898;5628.04323265046;5673.290838645169;19849.30527431533;20227.393178204384;20586.58826823455;21347;21768.129274829145;21980.340589782332;22136.77863221347;22298.108075677115;22377.104915803186;22379.77620223777;10420.32267902584;10917.344959302467;11064.391458469701;11311.886881131248;11613.30320180792;8918.955405222932;8960.955195223982;8961.955190224007;9050.728478145666;9349.01835307283;18664.533386665444;18752.531186719894;19101.331453399063;19213.519662008563;19696.11367488438;18935;18977;19157.329493467663;19432;19705.80294197097;11573;11938.403079845799;12085.516579336729;12111.939440302742;12118.878811211775;14141;14167.149971001361;14188.574342769733;14228.502002429865;14322.641933951734;11286.153538484483;11402.543898244308;11405.543778248777;11419.14356423255;11443.25618834773;11201.831972520256;11359.602413915472;11475.885241147482;11511.942440287745;11655.067594592243;10290.794184116425;10306.48467576633;10374.688759337232;10387.428691422045;10409.531571079471;7709.845803084019;7936.6428510716005;7965.243301886311;7991.760247192594;8000.15998320168;7576.431767617561;7888.605569721376;7899.328557072713;7929.841403172019;7944.443888927951 16:27:28 INFO - PID 9905 | |4;prototype.html;492.4095172592527;493.9113492450071;521.5071757189457;525.1833399064453;531.8723506358474;6206.7827626033095;6325.335839736825;6335.144755458012;6384;6401.679916004198;6101.955844791989;6179.949408600537;6213.689315534228;6255.718492667829;6268.717907694153;4891.828785992491;4940.308356830046;4957.7769000395065;5028.597712183026;5075.873103172432;4963.801447942078;4992.525710057539;5014.2979982802435;5028.120078986163;5059.861531155479;4996.750162491879;5016.172331565287;5017.247412888059;5092.337996060507;5093.847184584469;5048.545630893237;5053.74731263439;5054.393472783253;5079.3650793650795;5083.313752643383;4485.38526130596;4493.618042466394;4548.158590660738;4564.338170965225;4568.291914753218;6186.7525298987985;6234.220722409699;6248.593841400295;6296.212973378328;6329.493640508748;5778.393268706767;5785.190073389729;5794.130880367937;5799.3620701722775;5807.82576522705;6532.281449040602;6546.396132947459;6571.57284776488;6618.768343107968;6650.5344625876205;4853.805847766085;4871.537203965618;4882.853514394574;4918.286848406961;4938.703677779345;6765.594064356154;6929.3070692930805;6940.514164008572;6944.756933507404;6965.373116419496;4977.32806071183;5014.623903207201;5076.18780995039;5099.23511473282;5102.642815002914;5039.521245481674;5050.49495050492;5079.796808127633;5130.717810520383;5138.3577052868395;5714.7999820006935;5716.742746576414;5720.971395142998;5754.19441278213;5800.941990580124;6651.501137414616;6668.333166683293;6700.195976482756;6703.765368212186;6781.287964763727;1685.502776680882;1693.4327000454984;1693.5766058485378;1697.9660406791795;1771.2029586686042;1164.6447833410891;1217.3730528777687;1218.5125949620224;1231.408923716621;1262.6022802817083;5821.700428330786;5839.474447299764;5842.357340692521;5896.321922978912;5904.350521442638;6605.5376123670885;6616.900746488806;6619.933800661932;6676.365745254193;6681;5723.45627165427;5778.248827652378;5807.593468457252;5808.88382232353;5824.213731146241;3547.3260080584605;3557.875474358385;3560.7507474477056;3596.1908570571413;3606.6573675500786;6508.47050943028;6510.7721229756735;6532.869342613121;6551.886179349522;6580.1445812045085;6821.82624724625;6831.931680683129;6963.40811031068;6987.441004719611;7011.509194356346;6504.414602685875;6533.072743540762;6541;6546.705398256994;6572.967135164294;6670.099536562502;6722.798316050526;6735.73057077731;6748.628825414649;6782.728690852508;6654.306859692917;6673.232578253369;6705.564138330993;6713.966430167818;6724.8991265132045;6509.869802603826;6557.737690492327;6585.539012269095;6620.966895165493;6645.767398141042;5001.199808030698;5084.745762711924;5086.160783470686;5088.59291256706;5101.69389836611;6689.665516724242;6744.8651026978205;6757.864842703217;6778.3221677831825;6832.624205668736;6259.467945224706;6337.176167098248;6501.9674901625185;6526.543141980016;6553.180852393451;6022.939770602238;6431.453326467115;6444.548881578245;6502.382273683992;6526.771562995273;6367.968160159169;6392.707755505825;6456.192975878015;6517.8696426072165;6526.477881769542;6525.477961763143;6539.542232043902;6573;6583.473322134314;6635.056882622284;6157.291911430064;6264.248290205204;6323.304436511888;6334.683265836782;6407.462209069883 16:27:28 INFO - PID 9905 | |5;yui.html;908.5502676175295;926.356182453195;934.8504239321705;937.6905621145023;958.913697767201;18674.626507469842;19553.120109595086;19614.607707845833;19726.70409943852;19766.505837354074;20187.697184542245;22175.334739957827;22235.110595576156;22309;22490.55018899621;985.8176237396086;1001.62439085343;1002.6991902429243;1003.4481035430521;1006.8238058339798;906.6917248135633;990.509697699636;992.2012779712329;1002.989970100297;1006.7382480555035;1042.6298663974294;1042.890496497868;1043.5304113149075;1047.82187028205;1048.4705223861938;1043.9008294212038;1046.6702988558657;1046.8796088449812;1048.1509976918721;1057.0222544146673;758.8178837079116;763.3931024835214;764.4648745877907;768.6348984232501;768.7770546541497;17944.730829037446;19613.313534026383;19960;19994;20249.595008099903;20265.696014559795;20342.5931481371;20829.79170208309;20889.5822083559;21364.25225117129;16830.495085147464;16948.83051169497;17182.65634687299;17296.91351543247;17883.642327153386;3777.376732839078;3789.147441825595;3795.1840354323954;3822.3693090640013;3822.7132965027736;125564.63214906178;127719;130766;131373.99999999904;132109;15402.691946161238;16454.341826326814;16698.916505417637;16843.915780421263;16957.576060598585;30403.847980760398;30575.388492230475;30821.537676934862;30883.536746948816;31060;8723.389362744674;8782.165694259034;8839.779005524913;8844.115588441104;8863.290936725047;4091.5499295077516;4111.506619205715;4186.309258972296;4274.487061552633;4284.89287767802;4607.7004994675235;4813.446453657874;5198.896022079537;5570.136628822539;5746.827595172152;1116.4250411038322;1129.4522156753967;1153.653903828848;1154.3766366162179;1154.6766905266538;691.8339598496325;706.6007705646293;712.4051417066704;719.6329871765332;725.8548290341847;11586.594469193706;12234.877651223553;12351;12925.547605833939;13106.279154646396;694.746417557598;695.7112798188796;705.8094314535047;708.8865781474939;717.4153065251803;5538.9169162462595;5559.916601250984;5596.552275817925;5620.606557541015;5625.240592520039;108912;109962;110206.44896775625;111908;114486;96584.51707741416;97141.51429242949;98519;100652.49673751731;102579.487102564;81521.59239203884;82863.17136828555;84125.57937210397;85879.57060214659;87000;10071;10147.84778228342;10244.53899574506;10497;10506.894931050898;40438.39342409925;40703.1859362817;41183.176336473705;41371.1725765489;41990.79004604957;50336.244956326416;51620.74189629028;51767.741161293954;52235;52796;15407.383704652137;15697.293621787276;15933.442329518411;15946.9202653986;16021.919890400473;17771.73342399891;17809.46571602854;17816.287348505914;17927.37254196097;18038.188281527102;15993.680126397174;16085.517434476984;16277.511674649779;16399.91800040992;16453.177341132658;16246.512604621881;16618.169091545133;16636.168191590616;16656.666866662843;16940.068296243826;15703.136327502098;15955.760663590288;16011.679766404839;16045.438409655606;16045.518634440985;12013.579524716593;12304.75390492203;12406.25562466255;12616.243025418504;12715.554955576881;12311.19977201479;12584.559540416041;13988.790168147689;14743.262836857899;15043.473478428203 16:27:28 INFO - PID 9905 | __end_tp_report 16:27:28 INFO - PID 9905 | __start_cc_report 16:27:28 INFO - PID 9905 | _x_x_mozilla_cycle_collect,357 16:27:28 INFO - PID 9905 | __end_cc_report 16:27:28 INFO - PID 9905 | __startTimestamp1504654048680__endTimestamp 16:27:28 INFO - PID 9905 | 16:27:28 INFO - PID 9905 | ------- Summary: start ------- 16:27:28 INFO - PID 9905 | Number of tests: 6 16:27:28 INFO - PID 9905 | 16:27:28 INFO - PID 9905 | [#0] dojo.html Cycles:165 Average:6211.68 Median:6472.00 stddev:762.05 (11.8%) stddev-sans-first:727.62 16:27:28 INFO - PID 9905 | Values: 3230.7 3240.4 3264.1 3299.0 3316.8 6233.3 6350.5 6439.5 6472.0 6477.0 6149.5 6181.5 6243.3 6255.2 6321.1 5644.2 5696.2 5710.8 5741.0 5774.6 5752.4 5860.3 5862.6 5883.2 5953.1 5817.9 5894.7 5897.5 5899.4 5913.4 5805.9 5915.6 5919.5 5926.9 5956.3 5535.4 5719.2 5820.2 5842.8 5847.4 5620.9 6212.8 6227.8 6334.2 6337.5 6796.7 6819.5 6884.0 6924.1 6931.4 6662.6 6754.3 6799.9 6809.3 6947.7 6272.7 6303.6 6305.1 6321.9 6331.4 6932.3 6944.6 6946.8 6952.9 6975.9 6537.9 6633.5 6645.3 6647.5 6656.4 6773.7 6804.1 6819.9 6849.9 6852.5 6722.9 6740.3 6842.2 6854.7 6875.6 6552.5 6808.6 6822.0 6833.1 6866.7 4946.5 4968.4 5003.6 5010.3 5011.2 4480.5 4480.6 4522.1 4529.3 4529.3 5814.4 5938.7 5982.9 5983.9 6079.5 6800.0 6801.5 6807.8 6823.8 6824.0 5924.2 5939.7 5955.4 5961.0 5964.4 5846.6 5848.6 5854.7 5854.9 5860.5 6445.0 6458.9 6507.5 6576.6 6590.4 5930.9 6840.7 6867.1 6890.6 6971.0 6587.4 6647.1 6731.8 6745.0 6746.3 6571.5 6639.0 6648.5 6654.5 6669.0 6737.6 6759.9 6771.3 6775.4 6801.8 6497.9 6517.1 6560.1 6564.3 6568.6 6600.2 6613.1 6631.5 6694.9 6701.3 6493.1 6500.9 6553.7 6580.3 6594.6 6433.9 6545.0 6572.7 6620.5 6627.2 5535.6 6331.4 6359.8 6361.1 6401.8 16:27:28 INFO - PID 9905 | 16:27:28 INFO - PID 9905 | [#1] ext.html Cycles:180 Average:36357.26 Median:32730.93 stddev:52389.44 (160.1%) stddev-sans-first:52468.41 16:27:28 INFO - PID 9905 | Values: 808.8 839.4 876.7 877.9 1127.9 28630.6 29959.9 37460.3 39136.0 39871.2 39505.8 39679.4 39695.2 39853.8 40095.6 7406.1 7624.2 7663.0 7713.3 7791.6 7529.7 7613.3 7635.1 7647.1 7679.6 7647.7 7680.2 7710.1 7725.1 7730.0 7583.0 7666.9 7690.8 7696.3 7706.9 7598.2 7610.7 7620.2 7639.8 7657.2 35873.0 35973.8 36028.3 36280.0 36310.5 3490.3 3492.8 3516.3 3525.7 3530.9 29796.3 29828.3 29905.0 30094.8 30110.7 4957.4 5135.8 5175.9 5197.6 5271.9 182024.0 183083.0 183626.0 184760.0 185353.0 45077.0 45156.8 45213.8 45273.8 45431.3 47142.5 47213.8 47255.5 49835.8 51483.7 12608.9 14150.4 14290.9 14372.1 14426.0 10272.5 10332.3 10782.8 11103.3 11160.3 13957.1 13962.5 14107.4 14196.0 14259.9 5564.4 5710.9 5786.2 5840.8 5848.1 1004.0 1009.1 1015.8 1016.3 1021.9 22389.7 22429.3 22603.4 22635.5 22879.4 1090.4 1098.7 1100.8 1105.1 1116.0 11891.6 11966.2 12099.2 12151.0 12202.6 229240.0 230755.0 231386.0 232547.0 233096.0 49840.3 50005.7 50118.5 50183.2 50382.7 1416.9 1417.3 1427.8 1434.5 1437.6 21587.5 21790.8 21973.6 22414.8 22489.0 34834.3 34838.1 34871.7 34876.3 34896.5 187009.0 187676.0 188132.0 188280.0 188554.0 32155.8 32274.0 32540.3 32696.2 32934.8 43015.4 44027.6 44085.6 44242.0 44594.0 31924.0 32070.5 32224.0 32373.5 32765.7 31319.2 32054.4 32338.9 32434.0 32695.0 36064.8 36368.3 37304.3 37339.4 37357.3 31566.4 31924.4 32122.0 32364.4 32378.7 23303.8 23472.7 23501.3 23771.9 24122.9 16:27:28 INFO - PID 9905 | 16:27:28 INFO - PID 9905 | [#2] jquery.html Cycles:180 Average:33353.29 Median:23274.53 stddev:73527.55 (315.9%) stddev-sans-first:73692.58 16:27:28 INFO - PID 9905 | Values: 553.2 567.9 571.2 571.8 580.1 21388.0 22086.4 22177.0 22231.0 22249.6 23744.9 24895.5 25716.7 25919.1 26241.9 6437.2 6570.2 6589.7 6595.6 6601.0 6478.9 6518.5 6524.8 6525.1 6545.2 5872.2 6414.0 6432.1 6449.4 6465.3 6370.3 6407.9 6425.6 6428.2 6439.2 5905.2 5940.0 5944.5 5945.7 5965.1 28658.6 29061.3 29603.0 29834.4 29937.0 1996.7 2050.1 2055.3 2058.9 2059.6 19128.3 19689.8 19780.5 19791.3 19804.2 2258.0 2357.9 2395.0 2417.5 2420.6 137902.0 139882.3 140332.0 142010.0 144252.0 14253.9 15187.5 15249.6 15402.5 15614.8 23244.5 23304.5 23407.2 23456.3 23506.9 9274.2 9758.9 9844.3 10010.6 10038.9 8837.3 8945.5 8947.0 8991.2 9047.8 8956.9 9190.5 9210.3 9250.5 9475.3 2802.7 2852.2 2894.2 2900.7 2990.6 969.7 998.6 999.4 1000.3 1020.6 19478.5 20032.9 20467.3 20891.6 20917.9 1053.4 1058.0 1068.8 1070.6 1070.8 11294.5 11322.8 11370.7 11394.5 11532.7 421628.0 425682.0 427926.0 430023.0 439519.0 115526.0 117672.0 118837.4 120226.0 122754.0 1163.3 1166.2 1196.5 1203.9 1209.1 17817.9 17926.6 17950.6 18040.7 18368.4 57969.4 58359.7 58861.4 60732.0 61174.4 71726.3 71781.0 71860.6 72373.6 73129.0 14582.4 14612.3 14690.3 14799.6 15143.0 23675.6 23952.0 24039.3 24066.2 24110.9 18837.6 18884.8 19146.6 19248.1 19259.8 15753.6 15890.0 16134.0 16257.3 16781.4 17305.3 17470.2 17535.9 17580.3 17626.8 14021.9 14807.3 14814.6 14822.5 14845.4 18479.4 18573.8 18679.3 18683.3 18795.4 16:27:28 INFO - PID 9905 | 16:27:28 INFO - PID 9905 | [#3] mootools.html Cycles:180 Average:9323.95 Median:23685.38 stddev:6519.07 (27.5%) stddev-sans-first:6509.51 16:27:28 INFO - PID 9905 | Values: 1305.2 1353.8 1392.7 1397.4 1398.5 14733.3 15393.2 15459.3 15462.8 15485.3 14823.5 15775.3 15788.3 15934.0 15947.7 2714.6 2775.5 2777.9 2780.8 2780.9 2598.3 2736.0 2737.7 2738.5 2738.7 2278.9 2317.0 2337.4 2337.5 2342.2 2595.8 2621.5 2625.0 2632.6 2636.5 2571.2 2589.3 2625.6 2627.1 2627.8 14730.6 14965.6 14981.3 14981.9 15134.8 7784.2 8098.2 8112.9 8119.4 8137.8 5323.6 5490.0 5618.7 5635.0 5642.1 1945.2 1967.9 1978.1 2003.2 2012.9 23592.9 23777.9 23898.9 23997.5 24008.3 6368.3 6570.6 6634.5 6726.4 6748.1 16097.6 16133.3 16182.6 16187.8 16188.1 7836.3 7936.6 7937.0 7940.5 8024.4 6057.9 6165.7 6407.3 6415.4 6419.3 5146.8 5259.3 5340.6 5372.9 5377.7 1539.1 1592.6 1594.0 1611.9 1613.5 915.8 928.6 936.1 952.5 953.8 7967.6 8706.3 8733.8 8734.5 8816.8 679.4 682.0 685.9 705.0 706.5 4808.3 4893.6 5241.8 5628.0 5673.3 19849.3 20227.4 20586.6 21347.0 21768.1 21980.3 22136.8 22298.1 22377.1 22379.8 10420.3 10917.3 11064.4 11311.9 11613.3 8919.0 8961.0 8962.0 9050.7 9349.0 18664.5 18752.5 19101.3 19213.5 19696.1 18935.0 18977.0 19157.3 19432.0 19705.8 11573.0 11938.4 12085.5 12111.9 12118.9 14141.0 14167.1 14188.6 14228.5 14322.6 11286.2 11402.5 11405.5 11419.1 11443.3 11201.8 11359.6 11475.9 11511.9 11655.1 10290.8 10306.5 10374.7 10387.4 10409.5 7709.8 7936.6 7965.2 7991.8 8000.2 7576.4 7888.6 7899.3 7929.8 7944.4 16:27:28 INFO - PID 9905 | 16:27:28 INFO - PID 9905 | [#4] prototype.html Cycles:180 Average:5540.98 Median:6210.24 stddev:1553.24 (25.0%) stddev-sans-first:1510.66 16:27:28 INFO - PID 9905 | Values: 492.4 493.9 521.5 525.2 531.9 6206.8 6325.3 6335.1 6384.0 6401.7 6102.0 6179.9 6213.7 6255.7 6268.7 4891.8 4940.3 4957.8 5028.6 5075.9 4963.8 4992.5 5014.3 5028.1 5059.9 4996.8 5016.2 5017.2 5092.3 5093.8 5048.5 5053.7 5054.4 5079.4 5083.3 4485.4 4493.6 4548.2 4564.3 4568.3 6186.8 6234.2 6248.6 6296.2 6329.5 5778.4 5785.2 5794.1 5799.4 5807.8 6532.3 6546.4 6571.6 6618.8 6650.5 4853.8 4871.5 4882.9 4918.3 4938.7 6765.6 6929.3 6940.5 6944.8 6965.4 4977.3 5014.6 5076.2 5099.2 5102.6 5039.5 5050.5 5079.8 5130.7 5138.4 5714.8 5716.7 5721.0 5754.2 5800.9 6651.5 6668.3 6700.2 6703.8 6781.3 1685.5 1693.4 1693.6 1698.0 1771.2 1164.6 1217.4 1218.5 1231.4 1262.6 5821.7 5839.5 5842.4 5896.3 5904.4 6605.5 6616.9 6619.9 6676.4 6681.0 5723.5 5778.2 5807.6 5808.9 5824.2 3547.3 3557.9 3560.8 3596.2 3606.7 6508.5 6510.8 6532.9 6551.9 6580.1 6821.8 6831.9 6963.4 6987.4 7011.5 6504.4 6533.1 6541.0 6546.7 6573.0 6670.1 6722.8 6735.7 6748.6 6782.7 6654.3 6673.2 6705.6 6714.0 6724.9 6509.9 6557.7 6585.5 6621.0 6645.8 5001.2 5084.7 5086.2 5088.6 5101.7 6689.7 6744.9 6757.9 6778.3 6832.6 6259.5 6337.2 6502.0 6526.5 6553.2 6022.9 6431.5 6444.5 6502.4 6526.8 6368.0 6392.7 6456.2 6517.9 6526.5 6525.5 6539.5 6573.0 6583.5 6635.1 6157.3 6264.2 6323.3 6334.7 6407.5 16:27:28 INFO - PID 9905 | 16:27:28 INFO - PID 9905 | [#5] yui.html Cycles:180 Average:23061.77 Median:19583.22 stddev:31979.00 (163.3%) stddev-sans-first:32025.45 16:27:28 INFO - PID 9905 | Values: 908.6 926.4 934.9 937.7 958.9 18674.6 19553.1 19614.6 19726.7 19766.5 20187.7 22175.3 22235.1 22309.0 22490.6 985.8 1001.6 1002.7 1003.4 1006.8 906.7 990.5 992.2 1003.0 1006.7 1042.6 1042.9 1043.5 1047.8 1048.5 1043.9 1046.7 1046.9 1048.2 1057.0 758.8 763.4 764.5 768.6 768.8 17944.7 19613.3 19960.0 19994.0 20249.6 20265.7 20342.6 20829.8 20889.6 21364.3 16830.5 16948.8 17182.7 17296.9 17883.6 3777.4 3789.1 3795.2 3822.4 3822.7 125564.6 127719.0 130766.0 131374.0 132109.0 15402.7 16454.3 16698.9 16843.9 16957.6 30403.8 30575.4 30821.5 30883.5 31060.0 8723.4 8782.2 8839.8 8844.1 8863.3 4091.5 4111.5 4186.3 4274.5 4284.9 4607.7 4813.4 5198.9 5570.1 5746.8 1116.4 1129.5 1153.7 1154.4 1154.7 691.8 706.6 712.4 719.6 725.9 11586.6 12234.9 12351.0 12925.5 13106.3 694.7 695.7 705.8 708.9 717.4 5538.9 5559.9 5596.6 5620.6 5625.2 108912.0 109962.0 110206.4 111908.0 114486.0 96584.5 97141.5 98519.0 100652.5 102579.5 81521.6 82863.2 84125.6 85879.6 87000.0 10071.0 10147.8 10244.5 10497.0 10506.9 40438.4 40703.2 41183.2 41371.2 41990.8 50336.2 51620.7 51767.7 52235.0 52796.0 15407.4 15697.3 15933.4 15946.9 16021.9 17771.7 17809.5 17816.3 17927.4 18038.2 15993.7 16085.5 16277.5 16399.9 16453.2 16246.5 16618.2 16636.2 16656.7 16940.1 15703.1 15955.8 16011.7 16045.4 16045.5 12013.6 12304.8 12406.3 12616.2 12715.6 12311.2 12584.6 13988.8 14743.3 15043.5 16:27:28 INFO - PID 9905 | -------- Summary: end -------- 16:27:28 INFO - PID 9905 | 16:27:29 INFO - TEST-INFO | 9905: exit 0 16:27:29 INFO - TEST-OK | dromaeo_css | took 1112411ms 16:27:29 INFO - TEST-START | kraken 16:27:29 INFO - Initialising browser for kraken test... 16:27:29 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:56284/getInfo.html -profile /tmp/tmppbipCK/profile 16:27:29 INFO - TEST-INFO | started process 10289 (/builds/slave/test/build/application/firefox/firefox http://localhost:56284/getInfo.html) 16:27:36 INFO - TEST-INFO | 10289: exit 0 16:27:36 INFO - Browser initialized. 16:27:36 INFO - Running cycle 1/1 for kraken test... 16:27:36 INFO - Using env: {'DISPLAY': ':0', 16:27:36 INFO - 'HOME': '/home/cltbld', 16:27:36 INFO - 'JSGC_DISABLE_POISONING': '1', 16:27:36 INFO - 'LANG': 'en_US.UTF-8', 16:27:36 INFO - 'LANGUAGE': 'en_US:en', 16:27:36 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 16:27:36 INFO - 'LOGNAME': 'cltbld', 16:27:36 INFO - 'MAIL': '/var/mail/cltbld', 16:27:36 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:27:36 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:27:36 INFO - 'MOZ_ACCELERATED': '1', 16:27:36 INFO - 'MOZ_CRASHREPORTER': '1', 16:27:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:27:36 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 16:27:36 INFO - 'MOZ_NO_REMOTE': '1', 16:27:36 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:27:36 INFO - 'MOZ_WEBRENDER': '1', 16:27:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:27:36 INFO - 'NO_EM_RESTART': '1', 16:27:36 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:27:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:27:36 INFO - 'PWD': '/builds/slave/test', 16:27:36 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:27:36 INFO - 'RUST_BACKTRACE': 'full', 16:27:36 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 16:27:36 INFO - 'SHELL': '/bin/bash', 16:27:36 INFO - 'SHLVL': '1', 16:27:36 INFO - 'TERM': 'linux', 16:27:36 INFO - 'TMOUT': '86400', 16:27:36 INFO - 'USER': 'cltbld', 16:27:36 INFO - 'XDG_SESSION_COOKIE': 'dac658600622fd96716c9acf000001b3-1504652868.17228-1316107021', 16:27:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:27:36 INFO - '_': '/tools/buildbot/bin/python'} 16:27:36 INFO - TEST-INFO | started process 10486 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmppbipCK/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 16:27:37 INFO - PID 10486 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 16:27:37 INFO - PID 10486 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 16:27:37 INFO - PID 10486 | 16:27:37 INFO - PID 10486 | (/builds/slave/test/build/application/firefox/firefox:10548): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 16:27:37 INFO - PID 10486 | 16:27:47 INFO - PID 10486 | RSS: Main: 202059776 16:27:47 INFO - PID 10486 | 16:27:50 INFO - PID 10486 | Cycle 1(1): loaded http://localhost:56284/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:56284/tests/kraken/driver.html?testName=audio-beat-detection) 16:27:51 INFO - PID 10486 | RSS: Main: 208146432 16:27:51 INFO - PID 10486 | 16:27:54 INFO - PID 10486 | Cycle 1(1): loaded http://localhost:56284/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:56284/tests/kraken/driver.html?testName=audio-dft) 16:27:54 INFO - PID 10486 | RSS: Main: 209301504 16:27:54 INFO - PID 10486 | 16:27:57 INFO - PID 10486 | Cycle 1(1): loaded http://localhost:56284/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:56284/tests/kraken/driver.html?testName=audio-fft) 16:27:58 INFO - PID 10486 | RSS: Main: 203726848 16:27:58 INFO - PID 10486 | 16:28:00 INFO - PID 10486 | Cycle 1(1): loaded http://localhost:56284/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:56284/tests/kraken/driver.html?testName=audio-oscillator) 16:28:01 INFO - PID 10486 | RSS: Main: 204816384 16:28:01 INFO - PID 10486 | 16:28:03 INFO - PID 10486 | Cycle 1(1): loaded http://localhost:56284/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:56284/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:28:04 INFO - PID 10486 | RSS: Main: 204439552 16:28:04 INFO - PID 10486 | 16:28:07 INFO - PID 10486 | Cycle 1(1): loaded http://localhost:56284/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:56284/tests/kraken/driver.html?testName=imaging-darkroom) 16:28:07 INFO - PID 10486 | RSS: Main: 208080896 16:28:07 INFO - PID 10486 | 16:28:11 INFO - PID 10486 | Cycle 1(1): loaded http://localhost:56284/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:56284/tests/kraken/driver.html?testName=imaging-desaturate) 16:28:11 INFO - PID 10486 | RSS: Main: 206995456 16:28:11 INFO - PID 10486 | 16:28:14 INFO - PID 10486 | Cycle 1(1): loaded http://localhost:56284/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:56284/tests/kraken/driver.html?testName=json-parse-financial) 16:28:15 INFO - PID 10486 | RSS: Main: 208203776 16:28:15 INFO - PID 10486 | 16:28:17 INFO - PID 10486 | Cycle 1(1): loaded http://localhost:56284/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:56284/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:28:17 INFO - PID 10486 | RSS: Main: 207257600 16:28:17 INFO - PID 10486 | 16:28:19 INFO - PID 10486 | Cycle 1(1): loaded http://localhost:56284/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:56284/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:28:20 INFO - PID 10486 | RSS: Main: 207962112 16:28:20 INFO - PID 10486 | 16:28:22 INFO - PID 10486 | Cycle 1(1): loaded http://localhost:56284/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:56284/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:28:22 INFO - PID 10486 | RSS: Main: 207826944 16:28:22 INFO - PID 10486 | 16:28:25 INFO - PID 10486 | Cycle 1(1): loaded http://localhost:56284/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:56284/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:28:26 INFO - PID 10486 | RSS: Main: 207925248 16:28:26 INFO - PID 10486 | 16:28:29 INFO - PID 10486 | Cycle 1(1): loaded http://localhost:56284/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:56284/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:28:30 INFO - PID 10486 | RSS: Main: 208515072 16:28:30 INFO - PID 10486 | 16:28:31 INFO - PID 10486 | Cycle 1(1): loaded http://localhost:56284/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:56284/tests/kraken/driver.html?testName=ai-astar) 16:28:32 INFO - PID 10486 | RSS: Main: 209747968 16:28:32 INFO - PID 10486 | 16:28:32 INFO - PID 10486 | __start_tp_report 16:28:32 INFO - PID 10486 | _x_x_mozilla_page_load 16:28:32 INFO - PID 10486 | _x_x_mozilla_page_load_details 16:28:32 INFO - PID 10486 | |i|pagename|runs| 16:28:32 INFO - PID 10486 | |0;ai-astar;99;116;100;109;111;110;109;98;109;110 16:28:32 INFO - PID 10486 | |1;audio-beat-detection;148;117;150;177;118;148;146;120;170;118 16:28:32 INFO - PID 10486 | |2;audio-dft;166;165;165;166;162;166;166;167;169;168 16:28:32 INFO - PID 10486 | |3;audio-fft;78;80;79;170;78;79;78;77;151;77 16:28:32 INFO - PID 10486 | |4;audio-oscillator;85;83;135;83;82;81;138;79;87;130 16:28:32 INFO - PID 10486 | |5;imaging-gaussian-blur;115;114;114;115;114;115;114;115;115;114 16:28:32 INFO - PID 10486 | |6;imaging-darkroom;148;153;154;147;148;147;148;150;149;149 16:28:32 INFO - PID 10486 | |7;imaging-desaturate;102;100;94;101;94;98;95;99;108;99 16:28:32 INFO - PID 10486 | |8;json-parse-financial;53;53;55;56;62;53;53;53;50;53 16:28:32 INFO - PID 10486 | |9;json-stringify-tinderbox;39;38;40;42;39;43;38;47;47;39 16:28:32 INFO - PID 10486 | |10;stanford-crypto-aes;78;74;87;75;77;80;75;77;78;80 16:28:32 INFO - PID 10486 | |11;stanford-crypto-ccm;127;139;125;128;128;123;122;131;124;164 16:28:32 INFO - PID 10486 | |12;stanford-crypto-pbkdf2;177;186;174;173;178;177;172;177;191;199 16:28:32 INFO - PID 10486 | |13;stanford-crypto-sha256-iterative;68;68;68;74;64;66;63;63;63;64 16:28:32 INFO - PID 10486 | __end_tp_report 16:28:32 INFO - PID 10486 | __start_cc_report 16:28:32 INFO - PID 10486 | _x_x_mozilla_cycle_collect,921 16:28:32 INFO - PID 10486 | __end_cc_report 16:28:32 INFO - PID 10486 | __startTimestamp1504654112051__endTimestamp 16:28:32 INFO - PID 10486 | 16:28:32 INFO - PID 10486 | ------- Summary: start ------- 16:28:32 INFO - PID 10486 | Number of tests: 14 16:28:32 INFO - PID 10486 | 16:28:32 INFO - PID 10486 | [#0] ai-astar Cycles:10 Average:107.10 Median:110.50 stddev:5.97 (5.4%) stddev-sans-first:5.57 16:28:32 INFO - PID 10486 | Values: 99.0 116.0 100.0 109.0 111.0 110.0 109.0 98.0 109.0 110.0 16:28:32 INFO - PID 10486 | 16:28:32 INFO - PID 10486 | [#1] audio-beat-detection Cycles:10 Average:141.20 Median:148.00 stddev:22.15 (15.0%) stddev-sans-first:23.36 16:28:32 INFO - PID 10486 | Values: 148.0 117.0 150.0 177.0 118.0 148.0 146.0 120.0 170.0 118.0 16:28:32 INFO - PID 10486 | 16:28:32 INFO - PID 10486 | [#2] audio-dft Cycles:10 Average:166.00 Median:166.00 stddev:1.89 (1.1%) stddev-sans-first:2.00 16:28:32 INFO - PID 10486 | Values: 166.0 165.0 165.0 166.0 162.0 166.0 166.0 167.0 169.0 168.0 16:28:32 INFO - PID 10486 | 16:28:32 INFO - PID 10486 | [#3] audio-fft Cycles:10 Average:94.70 Median:78.00 stddev:34.98 (44.8%) stddev-sans-first:36.58 16:28:32 INFO - PID 10486 | Values: 78.0 80.0 79.0 170.0 78.0 79.0 78.0 77.0 151.0 77.0 16:28:32 INFO - PID 10486 | 16:28:32 INFO - PID 10486 | [#4] audio-oscillator Cycles:10 Average:98.30 Median:82.50 stddev:25.03 (30.3%) stddev-sans-first:26.08 16:28:32 INFO - PID 10486 | Values: 85.0 83.0 135.0 83.0 82.0 81.0 138.0 79.0 87.0 130.0 16:28:32 INFO - PID 10486 | 16:28:32 INFO - PID 10486 | [#5] imaging-gaussian-blur Cycles:10 Average:114.50 Median:115.00 stddev:0.53 (0.5%) stddev-sans-first:0.53 16:28:32 INFO - PID 10486 | Values: 115.0 114.0 114.0 115.0 114.0 115.0 114.0 115.0 115.0 114.0 16:28:32 INFO - PID 10486 | 16:28:32 INFO - PID 10486 | [#6] imaging-darkroom Cycles:10 Average:149.30 Median:149.00 stddev:2.41 (1.6%) stddev-sans-first:2.51 16:28:32 INFO - PID 10486 | Values: 148.0 153.0 154.0 147.0 148.0 147.0 148.0 150.0 149.0 149.0 16:28:32 INFO - PID 10486 | 16:28:32 INFO - PID 10486 | [#7] imaging-desaturate Cycles:10 Average:99.00 Median:94.50 stddev:4.24 (4.5%) stddev-sans-first:4.36 16:28:32 INFO - PID 10486 | Values: 102.0 100.0 94.0 101.0 94.0 98.0 95.0 99.0 108.0 99.0 16:28:32 INFO - PID 10486 | 16:28:32 INFO - PID 10486 | [#8] json-parse-financial Cycles:10 Average:54.10 Median:53.00 stddev:3.18 (6.0%) stddev-sans-first:3.35 16:28:32 INFO - PID 10486 | Values: 53.0 53.0 55.0 56.0 62.0 53.0 53.0 53.0 50.0 53.0 16:28:32 INFO - PID 10486 | 16:28:32 INFO - PID 10486 | [#9] json-stringify-tinderbox Cycles:10 Average:41.20 Median:41.00 stddev:3.46 (8.4%) stddev-sans-first:3.57 16:28:32 INFO - PID 10486 | Values: 39.0 38.0 40.0 42.0 39.0 43.0 38.0 47.0 47.0 39.0 16:28:32 INFO - PID 10486 | 16:28:32 INFO - PID 10486 | [#10] stanford-crypto-aes Cycles:10 Average:78.10 Median:78.00 stddev:3.73 (4.8%) stddev-sans-first:3.95 16:28:32 INFO - PID 10486 | Values: 78.0 74.0 87.0 75.0 77.0 80.0 75.0 77.0 78.0 80.0 16:28:32 INFO - PID 10486 | 16:28:32 INFO - PID 10486 | [#11] stanford-crypto-ccm Cycles:10 Average:131.10 Median:128.00 stddev:12.55 (9.8%) stddev-sans-first:13.22 16:28:32 INFO - PID 10486 | Values: 127.0 139.0 125.0 128.0 128.0 123.0 122.0 131.0 124.0 164.0 16:28:32 INFO - PID 10486 | 16:28:32 INFO - PID 10486 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:180.40 Median:177.50 stddev:8.80 (5.0%) stddev-sans-first:9.24 16:28:32 INFO - PID 10486 | Values: 177.0 186.0 174.0 173.0 178.0 177.0 172.0 177.0 191.0 199.0 16:28:32 INFO - PID 10486 | 16:28:32 INFO - PID 10486 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.10 Median:67.00 stddev:3.51 (5.2%) stddev-sans-first:3.66 16:28:32 INFO - PID 10486 | Values: 68.0 68.0 68.0 74.0 64.0 66.0 63.0 63.0 63.0 64.0 16:28:32 INFO - PID 10486 | -------- Summary: end -------- 16:28:32 INFO - PID 10486 | 16:28:32 INFO - PID 10486 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 16:28:32 INFO - TEST-INFO | 10486: exit 0 16:28:32 INFO - TEST-OK | kraken | took 63259ms 16:28:32 INFO - SUITE-END | took 1175s 16:28:33 INFO - Completed test suite (00:19:35) 16:28:33 INFO - javascript benchmark 16:28:33 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8474.58524356786, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3230.6769323067697, 3240.432924238258, 3264.0860559043476, 3298.9505157422636, 3316.800991940483, 6233.314335423105, 6350.4602108820745, 6439.549231553793, 6472.0, 6476.967615161918, 6149.538784591163, 6181.474574661159, 6243.344448832875, 6255.155554000208, 6321.051842223657, 5644.153376993463, 5696.231008813798, 5710.800121995731, 5741.0, 5774.595778295521, 5752.367239603661, 5860.267466566679, 5862.618929769573, 5883.205767221413, 5953.136795164699, 5817.912731309013, 5894.734736936826, 5897.528197744192, 5899.410058994109, 5913.379095195007, 5805.883882322372, 5915.585908986352, 5919.467247947706, 5926.8814623707285, 5956.315023772257, 5535.418781027975, 5719.170720245583, 5820.185174075633, 5842.766289348421, 5847.44449277318, 5620.859478513029, 6212.751489940397, 6227.782027629011, 6334.1765570476, 6337.524685648595, 6796.6941487632685, 6819.488538359643, 6883.965580172067, 6924.099867017256, 6931.41083007945, 6662.600243985328, 6754.2907994660345, 6799.898001529981, 6809.250982391933, 6947.7220911163495, 6272.749090036439, 6303.621782693007, 6305.148804911324, 6321.936780632181, 6331.398517140864, 6932.272111428228, 6944.583325000516, 6946.826329341807, 6952.860942781116, 6975.930240697629, 6537.901931471032, 6633.4693224541925, 6645.302243264484, 6647.501437392215, 6656.36764507371, 6773.695183716646, 6804.08144900442, 6819.897701534481, 6849.931500684929, 6852.5203235773015, 6722.865542689119, 6740.258571557125, 6842.213145488233, 6854.725810967506, 6875.621840798704, 6552.541322107407, 6808.557443766139, 6822.044913712084, 6833.145856767904, 6866.725330986805, 4946.505349465025, 4968.378952630921, 5003.5997120230295, 5010.298558201855, 5011.223260394571, 4480.507144214133, 4480.551944805558, 4522.140793249272, 4529.275315949432, 4529.297958816389, 5814.360420353758, 5938.732757025944, 5982.880342393215, 5983.940160598426, 6079.544034197453, 6800.013997970365, 6801.4898882584, 6807.829804254933, 6823.829404264933, 6824.0, 5924.229850119457, 5939.673317967553, 5955.404459554096, 5960.970195148997, 5964.433378829004, 5846.619969702041, 5848.590598658138, 5854.736536855852, 5854.941450585525, 5860.501857342174, 6445.0, 6458.903116453257, 6507.544471886922, 6576.605403675795, 6590.406863382319, 5930.851728706817, 6840.657967101724, 6867.141607299088, 6890.586564806128, 6970.989206565121, 6587.440067594308, 6647.135872336566, 6731.831704207434, 6745.0, 6746.257911629618, 6571.507136964651, 6639.03733958583, 6648.5346025777735, 6654.534182607171, 6669.032990216295, 6737.629430381376, 6759.864802704017, 6771.255161932282, 6775.424088952297, 6801.795946121624, 6497.902531462127, 6517.120188774455, 6560.081588577507, 6564.343565643398, 6568.638724870178, 6600.174978127734, 6613.107230523818, 6631.535792494672, 6694.866102678016, 6701.32986701326, 6493.058506516434, 6500.934990650033, 6553.737850485927, 6580.276169621242, 6594.637294948824, 6433.8713225736155, 6545.0, 6572.737090516326, 6620.536562440776, 6627.204735431779, 5535.640183388067, 6331.366863313632, 6359.841003974938, 6361.109444677656, 6401.839954001187], "value": 6154.320399277576, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [808.8099296665283, 839.374665873924, 876.7413612984169, 877.8727084572739, 1127.8702949160838, 28630.570541441895, 29959.85020074899, 37460.25079498409, 39136.0, 39871.20257594853, 39505.80247098768, 39679.40480892789, 39695.206095878064, 39853.80073099638, 40095.59904400955, 7406.07424071991, 7624.199459056802, 7662.961685191566, 7713.305802477776, 7791.610419479031, 7529.698812047512, 7613.314801667849, 7635.083789945186, 7647.120581133157, 7679.57762323072, 7647.732329368473, 7680.155182929873, 7710.074791025057, 7725.111612164616, 7730.0, 7583.01420815296, 7666.923330766676, 7690.846183076307, 7696.307332340116, 7706.922930770676, 7598.20218877021, 7610.733624323122, 7620.161782203953, 7639.770806875803, 7657.234276572354, 35873.0, 35973.82013089944, 36028.27943441117, 36280.0, 36310.45534316987, 3490.2844916791996, 3492.772969756983, 3516.2791627716256, 3525.665061819123, 3530.9117272068147, 29796.255093622614, 29828.254293642833, 29905.0, 30094.849525752452, 30110.69889301101, 4957.355543779322, 5135.794568217267, 5175.922361164586, 5197.610179236573, 5271.920921186185, 182024.0, 183083.0, 183626.0, 184760.0, 185352.99999999866, 45077.0, 45156.77421612871, 45213.77393113079, 45273.773631132295, 45431.31853022207, 47142.5285747145, 47213.763931180125, 47255.5274447258, 49835.75082124566, 51483.7425812876, 12608.873911260953, 14150.43398264058, 14290.857091429161, 14372.137671739729, 14426.0, 10272.537735801907, 10332.276740628084, 10782.838257426145, 11103.333799972028, 11160.330380177214, 13957.092788968685, 13962.511312104027, 14107.435702571987, 14196.0, 14259.928700356431, 5564.387917329091, 5710.857228569236, 5786.189933409326, 5840.766369345263, 5848.064309710426, 1004.0, 1009.1422291052693, 1015.8425444056184, 1016.2581315639625, 1021.9131373833308, 22389.664155037688, 22429.32712018642, 22603.43491412695, 22635.547289054128, 22879.428014299774, 1090.36758679967, 1098.653924013933, 1100.8403781451646, 1105.0606984063481, 1115.966521004371, 11891.583794567321, 11966.162368634112, 12099.213551119148, 12151.0, 12202.633920982385, 229240.0, 230755.0, 231386.0, 232547.0, 233096.0, 49840.252396213364, 50005.74997124991, 50118.49881501211, 50183.24725129126, 50382.74808625934, 1416.886649068072, 1417.2842714429353, 1427.8429372768987, 1434.5194359889344, 1437.6405898525368, 21587.460313492287, 21790.78209217951, 21973.560528789014, 22414.77585224192, 22489.0, 34834.303313934084, 34838.12904677302, 34871.65128348785, 34876.30247394987, 34896.47655285223, 187009.0, 187676.0, 188132.0, 188280.0, 188554.0, 32155.784421558594, 32274.0, 32540.349193016482, 32696.18259543435, 32934.83532582322, 43015.35476967911, 44027.559724402345, 44085.559144408144, 44242.0, 44594.0, 31924.0, 32070.518942215418, 32224.0, 32373.514397283587, 32765.672343277216, 31319.21701957378, 32054.35891282208, 32338.868139615002, 32434.0, 32695.0, 36064.81967590145, 36368.27263454769, 37304.25391492209, 37339.439908400855, 37357.25285494329, 31566.36867262688, 31924.36151276915, 32122.0, 32364.35271294514, 32378.67621323851, 23303.76696233084, 23472.715377969846, 23501.294961151194, 23771.88114059488, 24122.87938560296], "value": 16662.584033632866, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [553.222722075484, 567.9290088738908, 571.1603942204957, 571.7655761137931, 580.1471836400491, 21388.0, 22086.447838804037, 22177.0, 22231.0, 22249.555008899813, 23744.88127559364, 24895.502089958234, 25716.74283257167, 25919.09283175089, 26241.86879065602, 6437.195350581165, 6570.211574611029, 6589.670516474181, 6595.571287866273, 6601.042848786923, 6478.902816457733, 6518.543701940866, 6524.804255872332, 6525.053867189278, 6545.247296560885, 5872.236609240793, 6414.0, 6432.099506069154, 6449.419552240297, 6465.321141280168, 6370.267419246777, 6407.87184256317, 6425.614463132204, 6428.164338635971, 6439.162908821823, 5905.173275741399, 5939.970300148515, 5944.524438044946, 5945.672987985659, 5965.075413310944, 28658.570121448196, 29061.273468163254, 29603.0, 29834.403311933856, 29937.0, 1996.6805311150147, 2050.0979568989596, 2055.3011975928107, 2058.8970551472366, 2059.6395630764705, 19128.33050843228, 19689.80310196894, 19780.50548736279, 19791.307304244427, 19804.207831686716, 2257.9548409031727, 2357.8938947747392, 2395.0060724799187, 2417.4802417480328, 2420.6248031555137, 137902.0, 139882.3005884964, 140332.0, 142010.0, 144252.0, 14253.928730356489, 15187.544373668808, 15249.6187595311, 15402.46091386796, 15614.76577851333, 23244.535109297718, 23304.53390932172, 23407.180748673716, 23456.296311110695, 23506.9421876016, 9274.21169200612, 9758.853617195748, 9844.261680374, 10010.64962726301, 10038.949805250926, 8837.29301655866, 8945.463272203559, 8946.955265223762, 8991.235744961752, 9047.773805654911, 8956.910430895738, 9190.540472976325, 9210.309226808016, 9250.537473126316, 9475.336726429085, 2802.705715899842, 2852.229897927548, 2894.1751600793673, 2900.7244311790346, 2990.551417287424, 969.680005598151, 998.5955687946393, 999.3554157568328, 1000.2897942460788, 1020.5560581147224, 19478.513037174183, 20032.89983550102, 20467.251103909166, 20891.58216835655, 20917.89541052285, 1053.4100903494068, 1057.9947100264449, 1068.8183008888507, 1070.5664205996584, 1070.7911957168433, 11294.54821807135, 11322.773544529064, 11370.715732106599, 11394.544218231178, 11532.711682208012, 421628.0, 425682.0, 427926.0, 430023.0, 439519.0, 115526.0, 117672.0, 118837.40581297211, 120226.0, 122754.0, 1163.3252713426193, 1166.1894982986912, 1196.485511230162, 1203.8796120388067, 1209.0690168570152, 17817.910910445364, 17926.641467170324, 17950.640987180446, 18040.729389058914, 18368.448946531626, 57969.42030579809, 58359.70820145872, 58861.41138588559, 60732.0, 61174.38825611687, 71726.28273717404, 71781.0, 71860.64069679618, 72373.638131809, 73129.0, 14582.416703331748, 14612.342444589807, 14690.338934748175, 14799.630009249855, 15143.0, 23675.64486532738, 23952.0, 24039.27882163538, 24066.15768448096, 24110.87944560266, 18837.6232475347, 18884.811151888305, 19146.61706765829, 19248.133833977223, 19259.807401925802, 15753.606159845636, 15889.967152135074, 16134.031958082553, 16257.349706011628, 16781.412650557173, 17305.30778768835, 17470.21384037696, 17535.912320438318, 17580.296788128333, 17626.823731762517, 14021.8782497402, 14807.333669985092, 14814.555563333117, 14822.481213157487, 14845.40618375296, 18479.353222637143, 18573.81426185721, 18679.346222882134, 18683.252669893052, 18795.436136915367], "value": 11967.80597531332, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1305.1581729784289, 1353.7969304604308, 1392.7423426666066, 1397.3711829676647, 1398.5314919501966, 14733.263336833155, 15393.153376564285, 15459.304331305077, 15462.768058479118, 15485.303161357739, 14823.481178158767, 15775.290111944962, 15788.289526971284, 15934.0, 15947.681046379066, 2714.5792402177694, 2775.5420355641295, 2777.9027734029314, 2780.805343625947, 2780.9443811123765, 2598.2984594159566, 2736.0, 2737.7399147080996, 2738.5070687276284, 2738.657667791526, 2278.851874628152, 2316.9536609267802, 2337.3805941425535, 2337.5091230841545, 2342.1802369170823, 2595.805314601403, 2621.5412302847117, 2624.986875065622, 2632.6182703507884, 2636.538605743997, 2571.1772232885482, 2589.3138318345655, 2625.5930330798756, 2627.1067836935536, 2627.8160528762996, 14730.558083257412, 14965.551033469013, 14981.250937453084, 14981.850181498154, 15134.772978405334, 7784.182660820589, 8098.230668086522, 8112.918870811275, 8119.3910456715985, 8137.75586732399, 5323.627346085776, 5490.039243132475, 5618.6628802272, 5635.0, 5642.097264437671, 1945.2121890634317, 1967.9016049197626, 1978.0999645161417, 2003.24878170686, 2012.9230861489034, 23592.88203558971, 23777.881110594335, 23898.880505597535, 23997.520049599087, 24008.27975160748, 6368.267649220399, 6570.638614876228, 6634.469242460592, 6726.3609957054005, 6748.122744043243, 16097.597560061093, 16133.274002669908, 16182.595435114215, 16187.838121618634, 16188.10965396891, 7836.333911617462, 7936.642851071716, 7936.960315198387, 7940.483868548641, 8024.358051355877, 6057.878842423127, 6165.722542485599, 6407.327230640809, 6415.422611964946, 6419.293877673452, 5146.845594632167, 5259.289995850588, 5340.572754179656, 5372.946270537323, 5377.650452720561, 1539.1150088699044, 1592.6257329527552, 1593.9840601594067, 1611.8710503159718, 1613.5159452164303, 915.771057235691, 928.5774972387547, 936.0826390137568, 952.518977916148, 953.7615596100975, 7967.641456134488, 8706.347023973227, 8733.781655458664, 8734.519601421856, 8816.77958051041, 679.4021261290034, 681.9693113809891, 685.8833998220315, 704.9647517624098, 706.5442789400911, 4808.2547205183255, 4893.559579637849, 5241.790328386898, 5628.04323265046, 5673.290838645169, 19849.30527431533, 20227.393178204384, 20586.58826823455, 21347.0, 21768.129274829145, 21980.340589782332, 22136.77863221347, 22298.108075677115, 22377.104915803186, 22379.77620223777, 10420.32267902584, 10917.344959302467, 11064.391458469701, 11311.886881131248, 11613.30320180792, 8918.955405222932, 8960.955195223982, 8961.955190224007, 9050.728478145666, 9349.01835307283, 18664.533386665444, 18752.531186719894, 19101.331453399063, 19213.519662008563, 19696.11367488438, 18935.0, 18977.0, 19157.329493467663, 19432.0, 19705.80294197097, 11573.0, 11938.403079845799, 12085.516579336729, 12111.939440302742, 12118.878811211775, 14141.0, 14167.149971001361, 14188.574342769733, 14228.502002429865, 14322.641933951734, 11286.153538484483, 11402.543898244308, 11405.543778248777, 11419.14356423255, 11443.25618834773, 11201.831972520256, 11359.602413915472, 11475.885241147482, 11511.942440287745, 11655.067594592243, 10290.794184116425, 10306.48467576633, 10374.688759337232, 10387.428691422045, 10409.531571079471, 7709.845803084019, 7936.6428510716005, 7965.243301886311, 7991.760247192594, 8000.15998320168, 7576.431767617561, 7888.605569721376, 7899.328557072713, 7929.841403172019, 7944.443888927951], "value": 6638.6116224517755, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [492.4095172592527, 493.9113492450071, 521.5071757189457, 525.1833399064453, 531.8723506358474, 6206.7827626033095, 6325.335839736825, 6335.144755458012, 6384.0, 6401.679916004198, 6101.955844791989, 6179.949408600537, 6213.689315534228, 6255.718492667829, 6268.717907694153, 4891.828785992491, 4940.308356830046, 4957.7769000395065, 5028.597712183026, 5075.873103172432, 4963.801447942078, 4992.525710057539, 5014.2979982802435, 5028.120078986163, 5059.861531155479, 4996.750162491879, 5016.172331565287, 5017.247412888059, 5092.337996060507, 5093.847184584469, 5048.545630893237, 5053.74731263439, 5054.393472783253, 5079.3650793650795, 5083.313752643383, 4485.38526130596, 4493.618042466394, 4548.158590660738, 4564.338170965225, 4568.291914753218, 6186.7525298987985, 6234.220722409699, 6248.593841400295, 6296.212973378328, 6329.493640508748, 5778.393268706767, 5785.190073389729, 5794.130880367937, 5799.3620701722775, 5807.82576522705, 6532.281449040602, 6546.396132947459, 6571.57284776488, 6618.768343107968, 6650.5344625876205, 4853.805847766085, 4871.537203965618, 4882.853514394574, 4918.286848406961, 4938.703677779345, 6765.594064356154, 6929.3070692930805, 6940.514164008572, 6944.756933507404, 6965.373116419496, 4977.32806071183, 5014.623903207201, 5076.18780995039, 5099.23511473282, 5102.642815002914, 5039.521245481674, 5050.49495050492, 5079.796808127633, 5130.717810520383, 5138.3577052868395, 5714.7999820006935, 5716.742746576414, 5720.971395142998, 5754.19441278213, 5800.941990580124, 6651.501137414616, 6668.333166683293, 6700.195976482756, 6703.765368212186, 6781.287964763727, 1685.502776680882, 1693.4327000454984, 1693.5766058485378, 1697.9660406791795, 1771.2029586686042, 1164.6447833410891, 1217.3730528777687, 1218.5125949620224, 1231.408923716621, 1262.6022802817083, 5821.700428330786, 5839.474447299764, 5842.357340692521, 5896.321922978912, 5904.350521442638, 6605.5376123670885, 6616.900746488806, 6619.933800661932, 6676.365745254193, 6681.0, 5723.45627165427, 5778.248827652378, 5807.593468457252, 5808.88382232353, 5824.213731146241, 3547.3260080584605, 3557.875474358385, 3560.7507474477056, 3596.1908570571413, 3606.6573675500786, 6508.47050943028, 6510.7721229756735, 6532.869342613121, 6551.886179349522, 6580.1445812045085, 6821.82624724625, 6831.931680683129, 6963.40811031068, 6987.441004719611, 7011.509194356346, 6504.414602685875, 6533.072743540762, 6541.0, 6546.705398256994, 6572.967135164294, 6670.099536562502, 6722.798316050526, 6735.73057077731, 6748.628825414649, 6782.728690852508, 6654.306859692917, 6673.232578253369, 6705.564138330993, 6713.966430167818, 6724.8991265132045, 6509.869802603826, 6557.737690492327, 6585.539012269095, 6620.966895165493, 6645.767398141042, 5001.199808030698, 5084.745762711924, 5086.160783470686, 5088.59291256706, 5101.69389836611, 6689.665516724242, 6744.8651026978205, 6757.864842703217, 6778.3221677831825, 6832.624205668736, 6259.467945224706, 6337.176167098248, 6501.9674901625185, 6526.543141980016, 6553.180852393451, 6022.939770602238, 6431.453326467115, 6444.548881578245, 6502.382273683992, 6526.771562995273, 6367.968160159169, 6392.707755505825, 6456.192975878015, 6517.8696426072165, 6526.477881769542, 6525.477961763143, 6539.542232043902, 6573.0, 6583.473322134314, 6635.056882622284, 6157.291911430064, 6264.248290205204, 6323.304436511888, 6334.683265836782, 6407.462209069883], "value": 5080.083005148054, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [908.5502676175295, 926.356182453195, 934.8504239321705, 937.6905621145023, 958.913697767201, 18674.626507469842, 19553.120109595086, 19614.607707845833, 19726.70409943852, 19766.505837354074, 20187.697184542245, 22175.334739957827, 22235.110595576156, 22309.0, 22490.55018899621, 985.8176237396086, 1001.62439085343, 1002.6991902429243, 1003.4481035430521, 1006.8238058339798, 906.6917248135633, 990.509697699636, 992.2012779712329, 1002.989970100297, 1006.7382480555035, 1042.6298663974294, 1042.890496497868, 1043.5304113149075, 1047.82187028205, 1048.4705223861938, 1043.9008294212038, 1046.6702988558657, 1046.8796088449812, 1048.1509976918721, 1057.0222544146673, 758.8178837079116, 763.3931024835214, 764.4648745877907, 768.6348984232501, 768.7770546541497, 17944.730829037446, 19613.313534026383, 19960.0, 19994.0, 20249.595008099903, 20265.696014559795, 20342.5931481371, 20829.79170208309, 20889.5822083559, 21364.25225117129, 16830.495085147464, 16948.83051169497, 17182.65634687299, 17296.91351543247, 17883.642327153386, 3777.376732839078, 3789.147441825595, 3795.1840354323954, 3822.3693090640013, 3822.7132965027736, 125564.63214906178, 127719.0, 130766.0, 131373.99999999904, 132109.0, 15402.691946161238, 16454.341826326814, 16698.916505417637, 16843.915780421263, 16957.576060598585, 30403.847980760398, 30575.388492230475, 30821.537676934862, 30883.536746948816, 31060.0, 8723.389362744674, 8782.165694259034, 8839.779005524913, 8844.115588441104, 8863.290936725047, 4091.5499295077516, 4111.506619205715, 4186.309258972296, 4274.487061552633, 4284.89287767802, 4607.7004994675235, 4813.446453657874, 5198.896022079537, 5570.136628822539, 5746.827595172152, 1116.4250411038322, 1129.4522156753967, 1153.653903828848, 1154.3766366162179, 1154.6766905266538, 691.8339598496325, 706.6007705646293, 712.4051417066704, 719.6329871765332, 725.8548290341847, 11586.594469193706, 12234.877651223553, 12351.0, 12925.547605833939, 13106.279154646396, 694.746417557598, 695.7112798188796, 705.8094314535047, 708.8865781474939, 717.4153065251803, 5538.9169162462595, 5559.916601250984, 5596.552275817925, 5620.606557541015, 5625.240592520039, 108912.0, 109962.0, 110206.44896775625, 111908.0, 114486.0, 96584.51707741416, 97141.51429242949, 98519.0, 100652.49673751731, 102579.487102564, 81521.59239203884, 82863.17136828555, 84125.57937210397, 85879.57060214659, 87000.0, 10071.0, 10147.84778228342, 10244.53899574506, 10497.0, 10506.894931050898, 40438.39342409925, 40703.1859362817, 41183.176336473705, 41371.1725765489, 41990.79004604957, 50336.244956326416, 51620.74189629028, 51767.741161293954, 52235.0, 52796.0, 15407.383704652137, 15697.293621787276, 15933.442329518411, 15946.9202653986, 16021.919890400473, 17771.73342399891, 17809.46571602854, 17816.287348505914, 17927.37254196097, 18038.188281527102, 15993.680126397174, 16085.517434476984, 16277.511674649779, 16399.91800040992, 16453.177341132658, 16246.512604621881, 16618.169091545133, 16636.168191590616, 16656.666866662843, 16940.068296243826, 15703.136327502098, 15955.760663590288, 16011.679766404839, 16045.438409655606, 16045.518634440985, 12013.579524716593, 12304.75390492203, 12406.25562466255, 12616.243025418504, 12715.554955576881, 12311.19977201479, 12584.559540416041, 13988.790168147689, 14743.262836857899, 15043.473478428203], "value": 8949.541370202522, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1521.0999999999997, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 116.0, 100.0, 109.0, 111.0, 110.0, 109.0, 98.0, 109.0, 110.0], "value": 107.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 117.0, 150.0, 177.0, 118.0, 148.0, 146.0, 120.0, 170.0, 118.0], "value": 141.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 165.0, 165.0, 166.0, 162.0, 166.0, 166.0, 167.0, 169.0, 168.0], "value": 166.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 80.0, 79.0, 170.0, 78.0, 79.0, 78.0, 77.0, 151.0, 77.0], "value": 94.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 83.0, 135.0, 83.0, 82.0, 81.0, 138.0, 79.0, 87.0, 130.0], "value": 98.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 114.0, 114.0, 115.0, 114.0, 115.0, 114.0, 115.0, 115.0, 114.0], "value": 114.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 153.0, 154.0, 147.0, 148.0, 147.0, 148.0, 150.0, 149.0, 149.0], "value": 149.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 100.0, 94.0, 101.0, 94.0, 98.0, 95.0, 99.0, 108.0, 99.0], "value": 99.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 53.0, 55.0, 56.0, 62.0, 53.0, 53.0, 53.0, 50.0, 53.0], "value": 54.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [39.0, 38.0, 40.0, 42.0, 39.0, 43.0, 38.0, 47.0, 47.0, 39.0], "value": 41.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 74.0, 87.0, 75.0, 77.0, 80.0, 75.0, 77.0, 78.0, 80.0], "value": 78.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [127.0, 139.0, 125.0, 128.0, 128.0, 123.0, 122.0, 131.0, 124.0, 164.0], "value": 131.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [177.0, 186.0, 174.0, 173.0, 178.0, 177.0, 172.0, 177.0, 191.0, 199.0], "value": 180.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 68.0, 74.0, 64.0, 66.0, 63.0, 63.0, 63.0, 64.0], "value": 66.1, "unit": "score"}]}]} 16:28:33 INFO - Return code: 0 16:28:33 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:28:33 INFO - # TBPL SUCCESS # 16:28:33 INFO - Running post-action listener: _package_coverage_data 16:28:33 INFO - Running post-action listener: _resource_record_post_action 16:28:33 INFO - [mozharness: 2017-09-05 23:28:33.321540Z] Finished run-tests step (success) 16:28:33 INFO - Running post-run listener: _resource_record_post_run 16:28:33 INFO - Total resource usage - Wall time: 1193s; CPU: 13.0%; Read bytes: 385024; Write bytes: 405000192; Read time: 188; Write time: 239628 16:28:33 INFO - TinderboxPrint: CPU usage
13.3% 16:28:33 INFO - TinderboxPrint: I/O read bytes / time
385,024 / 188 16:28:33 INFO - TinderboxPrint: I/O write bytes / time
405,000,192 / 239,628 16:28:33 INFO - TinderboxPrint: CPU idle
8,245.1 (86.7%) 16:28:33 INFO - TinderboxPrint: CPU user
1,199.7 (12.6%) 16:28:33 INFO - TinderboxPrint: Swap in / out
0 / 0 16:28:33 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 120344576; Read time: 0; Write time: 92152 16:28:33 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 16:28:33 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 385024; Write bytes: 284655616; Read time: 188; Write time: 147476 16:28:33 INFO - Running post-run listener: _upload_blobber_files 16:28:33 INFO - Blob upload gear active. 16:28:33 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:28:33 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 16:28:33 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'] 16:28:33 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 16:28:34 INFO - (blobuploader) - INFO - Open directory for files ... 16:28:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:28:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:28:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:28:41 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:28:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:28:41 INFO - (blobuploader) - INFO - Done attempting. 16:28:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 16:28:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:28:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:28:42 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 16:28:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:28:42 INFO - (blobuploader) - INFO - Done attempting. 16:28:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 16:28:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:28:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:28:43 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 16:28:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:28:43 INFO - (blobuploader) - INFO - Done attempting. 16:28:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 16:28:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:28:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:28:44 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 16:28:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:28:44 INFO - (blobuploader) - INFO - Done attempting. 16:28:44 INFO - (blobuploader) - INFO - Iteration through files over. 16:28:44 INFO - Return code: 0 16:28:44 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:28:44 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:28:44 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c78c78c3f1a77134ceb4f219c61eb96d66c098db0d6cb6ab9b27fdc7501e3b68ba3cb6dbbc15fb6df44b261ef62e13daa994f6ca067a488dcb2cb2170c491bc5", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/483b8c9e4e275048e9c9c419411a1847e023292d67b2a774b2a499cb66eabfe23c5f2c882f10c8ad66b9be79247587ba21a454223c98dd666d446a0c3e6364b1", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c6e3a88e380ead0ee3422102d77b41602eda360df1a770bd139ee66532946c5a952762ccc18e6302f37f935a62599c29807475fcacf174b28397e8518e369065", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/94f875aa42c5e5d37abd50137efba329100cd55cd4daa09f67bdc97825e27e9082247c47fc86508b0078b0309b36d39b320369e596eac4be188ee172c21597c2"} 16:28:44 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:28:44 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:28:44 INFO - Contents: 16:28:44 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c78c78c3f1a77134ceb4f219c61eb96d66c098db0d6cb6ab9b27fdc7501e3b68ba3cb6dbbc15fb6df44b261ef62e13daa994f6ca067a488dcb2cb2170c491bc5", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/483b8c9e4e275048e9c9c419411a1847e023292d67b2a774b2a499cb66eabfe23c5f2c882f10c8ad66b9be79247587ba21a454223c98dd666d446a0c3e6364b1", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c6e3a88e380ead0ee3422102d77b41602eda360df1a770bd139ee66532946c5a952762ccc18e6302f37f935a62599c29807475fcacf174b28397e8518e369065", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/94f875aa42c5e5d37abd50137efba329100cd55cd4daa09f67bdc97825e27e9082247c47fc86508b0078b0309b36d39b320369e596eac4be188ee172c21597c2"} 16:28:44 INFO - Running post-run listener: copy_logs_to_upload_dir 16:28:44 INFO - Copying logs to upload dir... 16:28:44 INFO - mkdir: /builds/slave/test/build/upload/logs 16:28:44 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1253.561787 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 53 secs) (at 2017-09-05 16:28:44.044526) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-05 16:28:44.049419) ========= 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=dac658600622fd96716c9acf000001b3-1504652868.17228-1316107021 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c78c78c3f1a77134ceb4f219c61eb96d66c098db0d6cb6ab9b27fdc7501e3b68ba3cb6dbbc15fb6df44b261ef62e13daa994f6ca067a488dcb2cb2170c491bc5", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/483b8c9e4e275048e9c9c419411a1847e023292d67b2a774b2a499cb66eabfe23c5f2c882f10c8ad66b9be79247587ba21a454223c98dd666d446a0c3e6364b1", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c6e3a88e380ead0ee3422102d77b41602eda360df1a770bd139ee66532946c5a952762ccc18e6302f37f935a62599c29807475fcacf174b28397e8518e369065", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/94f875aa42c5e5d37abd50137efba329100cd55cd4daa09f67bdc97825e27e9082247c47fc86508b0078b0309b36d39b320369e596eac4be188ee172c21597c2"} build_url:https://queue.taskcluster.net/v1/task/VQZMjgW9QkOpdRucLZyMvw/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011353 build_url: 'https://queue.taskcluster.net/v1/task/VQZMjgW9QkOpdRucLZyMvw/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c78c78c3f1a77134ceb4f219c61eb96d66c098db0d6cb6ab9b27fdc7501e3b68ba3cb6dbbc15fb6df44b261ef62e13daa994f6ca067a488dcb2cb2170c491bc5", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/483b8c9e4e275048e9c9c419411a1847e023292d67b2a774b2a499cb66eabfe23c5f2c882f10c8ad66b9be79247587ba21a454223c98dd666d446a0c3e6364b1", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c6e3a88e380ead0ee3422102d77b41602eda360df1a770bd139ee66532946c5a952762ccc18e6302f37f935a62599c29807475fcacf174b28397e8518e369065", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/94f875aa42c5e5d37abd50137efba329100cd55cd4daa09f67bdc97825e27e9082247c47fc86508b0078b0309b36d39b320369e596eac4be188ee172c21597c2"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-05 16:28:44.080693) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-05 16:28:44.081070) ========= 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=dac658600622fd96716c9acf000001b3-1504652868.17228-1316107021 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004557 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-05 16:28:44.134094) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-05 16:28:44.134409) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-05 16:28:44.134712) ========= ========= Total master_lag: 0.19 =========