builder: mozilla-central_ubuntu64_hw_qr_test-dromaeojs-e10s slave: talos-linux64-ix-045 starttime: 1504787538.23 results: success (0) revision: 37b95547f0d27565452136d16b2df2857be840f6 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-07 05:32:18.225670) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-07 05:32:18.226044) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-07 05:32:18.226326) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-07 05:32:18.254378) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-07 05:32:18.254689) ========= 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=4300f881736b422476174874000001bc-1504787535.681553-1137939653 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-07 05:32:18-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 70.0M=0s 2017-09-07 05:32:18 (70.0 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.182484 ========= master_lag: 0.15 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-07 05:32:18.583873) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-07 05:32:18.584374) ========= 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=4300f881736b422476174874000001bc-1504787535.681553-1137939653 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.134891 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-07 05:32:18.739999) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-07 05:32:18.740457) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 37b95547f0d27565452136d16b2df2857be840f6 --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 37b95547f0d27565452136d16b2df2857be840f6 --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=4300f881736b422476174874000001bc-1504787535.681553-1137939653 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-07 05:32:18,794 truncating revision to first 12 chars 2017-09-07 05:32:18,794 Setting DEBUG logging. 2017-09-07 05:32:18,794 attempt 1/10 2017-09-07 05:32:18,794 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/37b95547f0d2?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-07 05:32:19,148 unpacking tar archive at: mozilla-central-37b95547f0d2/testing/mozharness/ program finished with exit code 0 elapsedTime=0.662396 ========= master_lag: 0.09 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-07 05:32:19.490964) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-07 05:32:19.491281) ========= script_repo_revision: 37b95547f0d27565452136d16b2df2857be840f6 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-07 05:32:19.491671) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-07 05:32:19.492151) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-07 05:32:19.510226) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2017-09-07 05:32:19.510513) ========= /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=4300f881736b422476174874000001bc-1504787535.681553-1137939653 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 05:32:19 INFO - MultiFileLogger online at 20170907 05:32:19 in /builds/slave/test 05:32:19 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 05:32:19 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:32:19 INFO - {'append_to_log': False, 05:32:19 INFO - 'base_work_dir': '/builds/slave/test', 05:32:19 INFO - 'blob_upload_branch': 'Firefox', 05:32:19 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:32:19 INFO - 'branch': 'Firefox', 05:32:19 INFO - 'buildbot_json_path': 'buildprops.json', 05:32:19 INFO - 'code_coverage': False, 05:32:19 INFO - 'config_files': ('talos/linux_config.py',), 05:32:19 INFO - 'default_actions': ('clobber', 05:32:19 INFO - 'read-buildbot-config', 05:32:19 INFO - 'download-and-extract', 05:32:19 INFO - 'populate-webroot', 05:32:19 INFO - 'create-virtualenv', 05:32:19 INFO - 'install', 05:32:19 INFO - 'setup-mitmproxy', 05:32:19 INFO - 'run-tests'), 05:32:19 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:32:19 INFO - 'disable_ccov_upload': False, 05:32:19 INFO - 'disable_stylo': False, 05:32:19 INFO - 'download_minidump_stackwalk': True, 05:32:19 INFO - 'download_symbols': 'ondemand', 05:32:19 INFO - 'enable_stylo': False, 05:32:19 INFO - 'enable_webrender': False, 05:32:19 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 05:32:19 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:32:19 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:32:19 INFO - 'gecko_profile': False, 05:32:19 INFO - 'gecko_profile_interval': 0, 05:32:19 INFO - 'installer_path': 'installer.exe', 05:32:19 INFO - 'log_level': 'info', 05:32:19 INFO - 'log_name': 'talos', 05:32:19 INFO - 'log_to_console': True, 05:32:19 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 05:32:19 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 05:32:19 INFO - 'opt_config_files': (), 05:32:19 INFO - 'pip_index': False, 05:32:19 INFO - 'suite': 'dromaeojs-e10s', 05:32:19 INFO - 'system_bits': '32', 05:32:19 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:32:19 INFO - 'title': 'talos-linux64-ix-045', 05:32:19 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:32:19 INFO - 'use_talos_json': True, 05:32:19 INFO - 'verify': 'False', 05:32:19 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:32:19 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:32:19 INFO - 'work_dir': 'build'} 05:32:19 INFO - [mozharness: 2017-09-07 12:32:19.784925Z] Running clobber step. 05:32:19 INFO - Running pre-action listener: _resource_record_pre_action 05:32:19 INFO - Running main action method: clobber 05:32:19 INFO - rmtree: /builds/slave/test/build 05:32:19 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:32:21 INFO - Running post-action listener: _resource_record_post_action 05:32:21 INFO - [mozharness: 2017-09-07 12:32:21.839862Z] Finished clobber step (success) 05:32:21 INFO - [mozharness: 2017-09-07 12:32:21.839959Z] Running read-buildbot-config step. 05:32:21 INFO - Running pre-action listener: _resource_record_pre_action 05:32:21 INFO - Running main action method: read_buildbot_config 05:32:21 INFO - Using buildbot properties: 05:32:21 INFO - { 05:32:21 INFO - "project": "", 05:32:21 INFO - "product": "firefox", 05:32:21 INFO - "who": "archaeopteryx@coole-files.de", 05:32:21 INFO - "installer_path": "public/build/target.tar.bz2", 05:32:21 INFO - "builddir": "mozilla-central_ubuntu64_hw_qr_test-dromaeojs-e10s", 05:32:21 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 05:32:21 INFO - "buildername": "Ubuntu HW 12.04 x64 qr mozilla-central talos dromaeojs-e10s", 05:32:21 INFO - "stage_platform": "linux64-qr", 05:32:21 INFO - "basedir": "/builds/slave/test", 05:32:21 INFO - "buildnumber": 34, 05:32:21 INFO - "platform": "ubuntu64_hw_qr", 05:32:21 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 05:32:21 INFO - "slavebuilddir": "test", 05:32:21 INFO - "taskId": "PcMzTN9LTciaU3nu_ErFDw", 05:32:21 INFO - "branch": "mozilla-central", 05:32:21 INFO - "script_repo_revision": "production", 05:32:21 INFO - "revision": "37b95547f0d27565452136d16b2df2857be840f6", 05:32:21 INFO - "slavename": "talos-linux64-ix-045", 05:32:21 INFO - "repo_path": "mozilla-central" 05:32:21 INFO - } 05:32:21 INFO - Finding installer, test and symbols from parent task. 05:32:21 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/PcMzTN9LTciaU3nu_ErFDw'}, attempt #1 05:32:22 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/PcMzTN9LTciaU3nu_ErFDw'}, attempt #1 05:32:22 INFO - Task dependencies: O4Y3kdYKTk6AYNU8oh2jiA 05:32:22 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/O4Y3kdYKTk6AYNU8oh2jiA'}, attempt #1 05:32:23 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/O4Y3kdYKTk6AYNU8oh2jiA/artifacts/public/build/target.tar.bz2 05:32:23 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/O4Y3kdYKTk6AYNU8oh2jiA/artifacts/public/build/target.test_packages.json 05:32:23 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/O4Y3kdYKTk6AYNU8oh2jiA/artifacts/public/build/target.crashreporter-symbols.zip 05:32:23 INFO - Running post-action listener: _resource_record_post_action 05:32:23 INFO - [mozharness: 2017-09-07 12:32:23.190534Z] Finished read-buildbot-config step (success) 05:32:23 INFO - [mozharness: 2017-09-07 12:32:23.190753Z] Running download-and-extract step. 05:32:23 INFO - Running pre-action listener: _resource_record_pre_action 05:32:23 INFO - Running main action method: download_and_extract 05:32:23 INFO - mkdir: /builds/slave/test/build/tests 05:32: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')]} 05:32:23 INFO - trying https://queue.taskcluster.net/v1/task/O4Y3kdYKTk6AYNU8oh2jiA/artifacts/public/build/target.test_packages.json 05:32:23 INFO - Downloading https://queue.taskcluster.net/v1/task/O4Y3kdYKTk6AYNU8oh2jiA/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 05:32:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/O4Y3kdYKTk6AYNU8oh2jiA/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 05:32:23 INFO - Downloaded 989 bytes. 05:32:23 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 05:32:23 INFO - Using the following test package requirements: 05:32:23 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 05:32:23 INFO - u'common': [u'target.common.tests.zip'], 05:32:23 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 05:32:23 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 05:32:23 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 05:32:23 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 05:32:23 INFO - u'mozbase': [u'target.common.tests.zip'], 05:32:23 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 05:32:23 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 05:32:23 INFO - u'web-platform': [u'target.common.tests.zip', 05:32:23 INFO - u'target.web-platform.tests.tar.gz'], 05:32:23 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 05:32:23 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 05:32:23 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/O4Y3kdYKTk6AYNU8oh2jiA/artifacts/public/build/target.common.tests.zip 05:32:23 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/O4Y3kdYKTk6AYNU8oh2jiA/artifacts/public/build/target.common.tests.zip'}, attempt #1 05:32:23 INFO - Fetch https://queue.taskcluster.net/v1/task/O4Y3kdYKTk6AYNU8oh2jiA/artifacts/public/build/target.common.tests.zip into memory 05:32:25 INFO - Content-Length response header: 55021160 05:32:25 INFO - Bytes received: 55021160 05:32:36 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 05:32:36 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/O4Y3kdYKTk6AYNU8oh2jiA/artifacts/public/build/target.common.tests.zip 05:32:36 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/O4Y3kdYKTk6AYNU8oh2jiA/artifacts/public/build/target.common.tests.zip'}, attempt #1 05:32:36 INFO - Fetch https://queue.taskcluster.net/v1/task/O4Y3kdYKTk6AYNU8oh2jiA/artifacts/public/build/target.common.tests.zip into memory 05:32:37 INFO - Content-Length response header: 55021160 05:32:37 INFO - Bytes received: 55021160 05:32:52 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/O4Y3kdYKTk6AYNU8oh2jiA/artifacts/public/build/target.talos.tests.zip 05:32:52 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/O4Y3kdYKTk6AYNU8oh2jiA/artifacts/public/build/target.talos.tests.zip'}, attempt #1 05:32:52 INFO - Fetch https://queue.taskcluster.net/v1/task/O4Y3kdYKTk6AYNU8oh2jiA/artifacts/public/build/target.talos.tests.zip into memory 05:32:52 INFO - Content-Length response header: 14496665 05:32:52 INFO - Bytes received: 14496665 05:32: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')]} 05:32:53 INFO - trying https://queue.taskcluster.net/v1/task/O4Y3kdYKTk6AYNU8oh2jiA/artifacts/public/build/target.tar.bz2 05:32:53 INFO - Downloading https://queue.taskcluster.net/v1/task/O4Y3kdYKTk6AYNU8oh2jiA/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 05:32:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/O4Y3kdYKTk6AYNU8oh2jiA/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 05:32:54 INFO - Downloaded 62729505 bytes. 05:32:54 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/O4Y3kdYKTk6AYNU8oh2jiA/artifacts/public/build/target.tar.bz2 05:32:54 INFO - mkdir: /builds/slave/test/properties 05:32:54 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:32:54 INFO - Writing to file /builds/slave/test/properties/build_url 05:32:54 INFO - Contents: 05:32:54 INFO - build_url:https://queue.taskcluster.net/v1/task/O4Y3kdYKTk6AYNU8oh2jiA/artifacts/public/build/target.tar.bz2 05:32:54 INFO - Running post-action listener: _resource_record_post_action 05:32:54 INFO - Running post-action listener: find_tests_for_verification 05:32:54 INFO - Running post-action listener: set_extra_try_arguments 05:32:54 INFO - [mozharness: 2017-09-07 12:32:54.195814Z] Finished download-and-extract step (success) 05:32:54 INFO - [mozharness: 2017-09-07 12:32:54.195911Z] Running populate-webroot step. 05:32:54 INFO - Running pre-action listener: _resource_record_pre_action 05:32:54 INFO - Running main action method: populate_webroot 05:32:54 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:32:54 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 05:32:54 INFO - 'tests': ['tresize', 05:32:54 INFO - 'tcanvasmark']}, 05:32:54 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:32:54 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 05:32:54 INFO - 'tests': ['dromaeo_css', 05:32:54 INFO - 'kraken']}, 05:32:54 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 05:32:54 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:32:54 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 05:32:54 INFO - 'talos_options': ['--disable-stylo'], 05:32:54 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:32:54 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 05:32:54 INFO - 'tests': ['damp', 'tps']}, 05:32:54 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 05:32:54 INFO - 'talos_options': ['--disable-stylo'], 05:32:54 INFO - 'tests': ['damp', 'tps']}, 05:32:54 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:32:54 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 05:32:54 INFO - 'tests': ['dromaeo_dom']}, 05:32:54 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 05:32:54 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 05:32:54 INFO - 'tests': ['basic_compositor_video', 05:32:54 INFO - 'glvideo']}, 05:32:54 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 05:32:54 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 05:32:54 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 05:32:54 INFO - 'talos_options': ['--disable-stylo'], 05:32:54 INFO - 'tests': ['ts_paint_webext', 05:32:54 INFO - 'tp5o_webext']}, 05:32:54 INFO - 'other-e10s': {'tests': ['a11yr', 05:32:54 INFO - 'ts_paint', 05:32:54 INFO - 'tpaint', 05:32:54 INFO - 'sessionrestore', 05:32:54 INFO - 'sessionrestore_many_windows', 05:32:54 INFO - 'sessionrestore_no_auto_restore', 05:32:54 INFO - 'tabpaint']}, 05:32:54 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 05:32:54 INFO - 'tests': ['a11yr', 05:32:54 INFO - 'ts_paint', 05:32:54 INFO - 'tpaint', 05:32:54 INFO - 'sessionrestore', 05:32:54 INFO - 'sessionrestore_many_windows', 05:32:54 INFO - 'sessionrestore_no_auto_restore', 05:32:54 INFO - 'tabpaint']}, 05:32:54 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 05:32:54 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 05:32:54 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 05:32:54 INFO - 'tests': ['bloom_basic_singleton']}, 05:32:54 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 05:32:54 INFO - 'tests': ['bloom_basic']}, 05:32:54 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:32:54 INFO - 'tsvgr_opacity', 05:32:54 INFO - 'tart', 05:32:54 INFO - 'tscrollx', 05:32:54 INFO - 'tsvg_static']}, 05:32:54 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 05:32:54 INFO - 'tests': ['tsvgx', 05:32:54 INFO - 'tsvgr_opacity', 05:32:54 INFO - 'tart', 05:32:54 INFO - 'tscrollx', 05:32:54 INFO - 'tsvg_static']}, 05:32:54 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 05:32:54 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 05:32:54 INFO - 'talos_options': ['--disable-stylo'], 05:32:54 INFO - 'tests': ['tp5o']}, 05:32:54 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 05:32:54 INFO - 'talos_options': ['--mitmproxy', 05:32:54 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 05:32:54 INFO - '--firstNonBlankPaint'], 05:32:54 INFO - 'tests': ['tp6_google', 05:32:54 INFO - 'tp6_youtube', 05:32:54 INFO - 'tp6_amazon', 05:32:54 INFO - 'tp6_facebook']}, 05:32:54 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 05:32:54 INFO - 'talos_options': ['--disable-stylo', 05:32:54 INFO - '--mitmproxy', 05:32:54 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 05:32:54 INFO - '--firstNonBlankPaint'], 05:32:54 INFO - 'tests': ['tp6_google', 05:32:54 INFO - 'tp6_youtube', 05:32:54 INFO - 'tp6_amazon', 05:32:54 INFO - 'tp6_facebook']}, 05:32:54 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 05:32:54 INFO - 'talos_options': ['--stylo-threads=1', 05:32:54 INFO - '--mitmproxy', 05:32:54 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 05:32:54 INFO - '--firstNonBlankPaint'], 05:32:54 INFO - 'tests': ['tp6_google', 05:32:54 INFO - 'tp6_youtube', 05:32:54 INFO - 'tp6_amazon', 05:32:54 INFO - 'tp6_facebook']}, 05:32:54 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 05:32:54 INFO - 'talos_options': ['--xperf_path', 05:32:54 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:32:54 INFO - 'tests': ['tp5n']}, 05:32:54 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 05:32:54 INFO - 'talos_options': ['--disable-stylo', 05:32:54 INFO - '--xperf_path', 05:32:54 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:32:54 INFO - 'tests': ['tp5n']}}} 05:32:54 INFO - Running post-action listener: _resource_record_post_action 05:32:54 INFO - [mozharness: 2017-09-07 12:32:54.211089Z] Finished populate-webroot step (success) 05:32:54 INFO - [mozharness: 2017-09-07 12:32:54.211178Z] Running create-virtualenv step. 05:32:54 INFO - Running pre-action listener: _resource_record_pre_action 05:32:54 INFO - Running main action method: create_virtualenv 05:32:54 INFO - Creating virtualenv /builds/slave/test/build/venv 05:32:54 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 05:32:54 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 05:32:54 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 05:32:54 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:32:54 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:32:55 INFO - Installing distribute.............................................................................................................................................................................................done. 05:32:55 INFO - Installing pip...............done. 05:32:55 INFO - Return code: 0 05:32:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 05:32:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 05:32:55 INFO - Reading from file tmpfile_stdout 05:32:55 INFO - Output received: 05:32:55 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 05:32:55 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:32:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:32:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:32:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:32:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:32:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24b31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x267ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x281fa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2823090>, '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 0x2823d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x281df90>, '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': '4300f881736b422476174874000001bc-1504787535.681553-1137939653', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:32:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 05:32:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 05:32:55 INFO - Using env: {'DISPLAY': ':0', 05:32:55 INFO - 'HOME': '/home/cltbld', 05:32:55 INFO - 'LANG': 'en_US.UTF-8', 05:32:55 INFO - 'LANGUAGE': 'en_US:en', 05:32:55 INFO - 'LOGNAME': 'cltbld', 05:32:55 INFO - 'MAIL': '/var/mail/cltbld', 05:32:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:32:55 INFO - 'MOZ_NO_REMOTE': '1', 05:32:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:32:55 INFO - 'NO_EM_RESTART': '1', 05:32:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:32:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:32:55 INFO - 'PWD': '/builds/slave/test', 05:32:55 INFO - 'SHELL': '/bin/bash', 05:32:55 INFO - 'SHLVL': '1', 05:32:55 INFO - 'TERM': 'linux', 05:32:55 INFO - 'TMOUT': '86400', 05:32:55 INFO - 'USER': 'cltbld', 05:32:55 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1504787535.681553-1137939653', 05:32:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:32:55 INFO - '_': '/tools/buildbot/bin/python'} 05:32:56 INFO - Ignoring indexes: http://pypi.python.org/simple/ 05:32:56 INFO - Downloading/unpacking pip>=1.5 05:32:56 INFO - Running setup.py egg_info for package pip 05:32:56 INFO - warning: no files found matching 'pip/cacert.pem' 05:32:56 INFO - warning: no files found matching '*.html' under directory 'docs' 05:32:56 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 05:32:56 INFO - no previously-included directories found matching 'docs/_build/_sources' 05:32:56 INFO - Installing collected packages: pip 05:32:56 INFO - Found existing installation: pip 1.1 05:32:56 INFO - Uninstalling pip: 05:32:56 INFO - Successfully uninstalled pip 05:32:56 INFO - Running setup.py install for pip 05:32:56 INFO - warning: no files found matching 'pip/cacert.pem' 05:32:56 INFO - warning: no files found matching '*.html' under directory 'docs' 05:32:56 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 05:32:56 INFO - no previously-included directories found matching 'docs/_build/_sources' 05:32:56 INFO - Installing pip script to /builds/slave/test/build/venv/bin 05:32:56 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 05:32:56 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 05:32:56 INFO - Successfully installed pip 05:32:56 INFO - Cleaning up... 05:32:56 INFO - Return code: 0 05:32:56 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:32: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')]} 05:32:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:32: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')]} 05:32:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:32:56 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 0x24b31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x267ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x281fa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2823090>, '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 0x2823d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x281df90>, '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': '4300f881736b422476174874000001bc-1504787535.681553-1137939653', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:32:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 05:32:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 05:32:56 INFO - Using env: {'DISPLAY': ':0', 05:32:56 INFO - 'HOME': '/home/cltbld', 05:32:56 INFO - 'LANG': 'en_US.UTF-8', 05:32:56 INFO - 'LANGUAGE': 'en_US:en', 05:32:56 INFO - 'LOGNAME': 'cltbld', 05:32:56 INFO - 'MAIL': '/var/mail/cltbld', 05:32:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:32:56 INFO - 'MOZ_NO_REMOTE': '1', 05:32:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:32:56 INFO - 'NO_EM_RESTART': '1', 05:32:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:32:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:32:56 INFO - 'PWD': '/builds/slave/test', 05:32:56 INFO - 'SHELL': '/bin/bash', 05:32:56 INFO - 'SHLVL': '1', 05:32:56 INFO - 'TERM': 'linux', 05:32:56 INFO - 'TMOUT': '86400', 05:32:56 INFO - 'USER': 'cltbld', 05:32:56 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1504787535.681553-1137939653', 05:32:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:32:56 INFO - '_': '/tools/buildbot/bin/python'} 05:32:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:32:56 INFO - Downloading/unpacking psutil>=3.1.1 05:32:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:32:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:32:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:32:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:32:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:32:58 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:32:58 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:32:58 INFO - Installing collected packages: psutil 05:32:58 INFO - Running setup.py install for psutil 05:32:58 INFO - building 'psutil._psutil_linux' extension 05:32:58 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 05:32:59 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 05:32:59 INFO - building 'psutil._psutil_posix' extension 05:32:59 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 05:32:59 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 05:32:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:32:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:32:59 INFO - Successfully installed psutil 05:32:59 INFO - Cleaning up... 05:32:59 INFO - Return code: 0 05:32:59 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:32:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:32:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:32:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:32:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:32:59 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 0x24b31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x267ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x281fa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2823090>, '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 0x2823d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x281df90>, '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': '4300f881736b422476174874000001bc-1504787535.681553-1137939653', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:32:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 05:32:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 05:32:59 INFO - Using env: {'DISPLAY': ':0', 05:32:59 INFO - 'HOME': '/home/cltbld', 05:32:59 INFO - 'LANG': 'en_US.UTF-8', 05:32:59 INFO - 'LANGUAGE': 'en_US:en', 05:32:59 INFO - 'LOGNAME': 'cltbld', 05:32:59 INFO - 'MAIL': '/var/mail/cltbld', 05:32:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:32:59 INFO - 'MOZ_NO_REMOTE': '1', 05:32:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:32:59 INFO - 'NO_EM_RESTART': '1', 05:32:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:32:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:32:59 INFO - 'PWD': '/builds/slave/test', 05:32:59 INFO - 'SHELL': '/bin/bash', 05:32:59 INFO - 'SHLVL': '1', 05:32:59 INFO - 'TERM': 'linux', 05:32:59 INFO - 'TMOUT': '86400', 05:32:59 INFO - 'USER': 'cltbld', 05:32:59 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1504787535.681553-1137939653', 05:32:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:32:59 INFO - '_': '/tools/buildbot/bin/python'} 05:32:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:32:59 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:32:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:32:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:32:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:32:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:33:00 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:33:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:33:00 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:33:00 INFO - Installing collected packages: mozsystemmonitor 05:33:00 INFO - Running setup.py install for mozsystemmonitor 05:33:00 INFO - Successfully installed mozsystemmonitor 05:33:00 INFO - Cleaning up... 05:33:00 INFO - Return code: 0 05:33:00 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:33:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:33:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:33:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:33:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:33:00 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 0x24b31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x267ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x281fa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2823090>, '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 0x2823d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x281df90>, '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': '4300f881736b422476174874000001bc-1504787535.681553-1137939653', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:33:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 05:33:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 05:33:00 INFO - Using env: {'DISPLAY': ':0', 05:33:00 INFO - 'HOME': '/home/cltbld', 05:33:00 INFO - 'LANG': 'en_US.UTF-8', 05:33:00 INFO - 'LANGUAGE': 'en_US:en', 05:33:00 INFO - 'LOGNAME': 'cltbld', 05:33:00 INFO - 'MAIL': '/var/mail/cltbld', 05:33:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:33:00 INFO - 'MOZ_NO_REMOTE': '1', 05:33:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:33:00 INFO - 'NO_EM_RESTART': '1', 05:33:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:33:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:33:00 INFO - 'PWD': '/builds/slave/test', 05:33:00 INFO - 'SHELL': '/bin/bash', 05:33:00 INFO - 'SHLVL': '1', 05:33:00 INFO - 'TERM': 'linux', 05:33:00 INFO - 'TMOUT': '86400', 05:33:00 INFO - 'USER': 'cltbld', 05:33:00 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1504787535.681553-1137939653', 05:33:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:33:00 INFO - '_': '/tools/buildbot/bin/python'} 05:33:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:33:01 INFO - Downloading/unpacking jsonschema==2.5.1 05:33:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:33:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:33:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:33:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:33:02 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:33:02 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:33:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:33:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:33:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:33:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:33:02 INFO - Downloading functools32-3.2.3-2.tar.gz 05:33:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:33:02 INFO - warning: no files found matching '*.txt' 05:33:02 INFO - no previously-included directories found matching 'build' 05:33:02 INFO - no previously-included directories found matching 'dist' 05:33:02 INFO - no previously-included directories found matching '.git*' 05:33:02 INFO - Installing collected packages: jsonschema, functools32 05:33:02 INFO - Running setup.py install for functools32 05:33:02 INFO - warning: no files found matching '*.txt' 05:33:02 INFO - no previously-included directories found matching 'build' 05:33:02 INFO - no previously-included directories found matching 'dist' 05:33:02 INFO - no previously-included directories found matching '.git*' 05:33:02 INFO - Successfully installed jsonschema functools32 05:33:02 INFO - Cleaning up... 05:33:02 INFO - Return code: 0 05:33:02 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:33:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:33:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:33:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:33:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:33:02 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 0x24b31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x267ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x281fa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2823090>, '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 0x2823d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x281df90>, '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': '4300f881736b422476174874000001bc-1504787535.681553-1137939653', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:33:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 05:33:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 05:33:02 INFO - Using env: {'DISPLAY': ':0', 05:33:02 INFO - 'HOME': '/home/cltbld', 05:33:02 INFO - 'LANG': 'en_US.UTF-8', 05:33:02 INFO - 'LANGUAGE': 'en_US:en', 05:33:02 INFO - 'LOGNAME': 'cltbld', 05:33:02 INFO - 'MAIL': '/var/mail/cltbld', 05:33:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:33:02 INFO - 'MOZ_NO_REMOTE': '1', 05:33:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:33:02 INFO - 'NO_EM_RESTART': '1', 05:33:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:33:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:33:02 INFO - 'PWD': '/builds/slave/test', 05:33:02 INFO - 'SHELL': '/bin/bash', 05:33:02 INFO - 'SHLVL': '1', 05:33:02 INFO - 'TERM': 'linux', 05:33:02 INFO - 'TMOUT': '86400', 05:33:02 INFO - 'USER': 'cltbld', 05:33:02 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1504787535.681553-1137939653', 05:33:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:33:02 INFO - '_': '/tools/buildbot/bin/python'} 05:33:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:33:02 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:33:02 INFO - Cleaning up... 05:33:02 INFO - Return code: 0 05:33:02 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:33:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:33:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:33:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:33:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:33:02 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 0x24b31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x267ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x281fa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2823090>, '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 0x2823d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x281df90>, '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': '4300f881736b422476174874000001bc-1504787535.681553-1137939653', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:33:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 05:33:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 05:33:02 INFO - Using env: {'DISPLAY': ':0', 05:33:02 INFO - 'HOME': '/home/cltbld', 05:33:02 INFO - 'LANG': 'en_US.UTF-8', 05:33:02 INFO - 'LANGUAGE': 'en_US:en', 05:33:02 INFO - 'LOGNAME': 'cltbld', 05:33:02 INFO - 'MAIL': '/var/mail/cltbld', 05:33:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:33:02 INFO - 'MOZ_NO_REMOTE': '1', 05:33:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:33:02 INFO - 'NO_EM_RESTART': '1', 05:33:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:33:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:33:02 INFO - 'PWD': '/builds/slave/test', 05:33:02 INFO - 'SHELL': '/bin/bash', 05:33:02 INFO - 'SHLVL': '1', 05:33:02 INFO - 'TERM': 'linux', 05:33:02 INFO - 'TMOUT': '86400', 05:33:02 INFO - 'USER': 'cltbld', 05:33:02 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1504787535.681553-1137939653', 05:33:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:33:02 INFO - '_': '/tools/buildbot/bin/python'} 05:33:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:33:03 INFO - Downloading/unpacking blobuploader==1.2.4 05:33:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:33:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:33:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:33:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:33:07 INFO - Downloading blobuploader-1.2.4.tar.gz 05:33:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:33:07 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:33:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:33:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:33:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:33:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:33:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:33:07 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:33:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:33:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:33:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:33:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:33:07 INFO - Downloading docopt-0.6.1.tar.gz 05:33:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:33:07 INFO - Installing collected packages: blobuploader, requests, docopt 05:33:07 INFO - Running setup.py install for blobuploader 05:33:07 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:33:07 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:33:07 INFO - Running setup.py install for requests 05:33:08 INFO - Running setup.py install for docopt 05:33:08 INFO - Successfully installed blobuploader requests docopt 05:33:08 INFO - Cleaning up... 05:33:08 INFO - Return code: 0 05:33:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:33:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:33:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:33:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:33:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:33:08 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 0x24b31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x267ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x281fa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2823090>, '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 0x2823d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x281df90>, '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': '4300f881736b422476174874000001bc-1504787535.681553-1137939653', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:33:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:33:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:33:08 INFO - Using env: {'DISPLAY': ':0', 05:33:08 INFO - 'HOME': '/home/cltbld', 05:33:08 INFO - 'LANG': 'en_US.UTF-8', 05:33:08 INFO - 'LANGUAGE': 'en_US:en', 05:33:08 INFO - 'LOGNAME': 'cltbld', 05:33:08 INFO - 'MAIL': '/var/mail/cltbld', 05:33:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:33:08 INFO - 'MOZ_NO_REMOTE': '1', 05:33:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:33:08 INFO - 'NO_EM_RESTART': '1', 05:33:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:33:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:33:08 INFO - 'PWD': '/builds/slave/test', 05:33:08 INFO - 'SHELL': '/bin/bash', 05:33:08 INFO - 'SHLVL': '1', 05:33:08 INFO - 'TERM': 'linux', 05:33:08 INFO - 'TMOUT': '86400', 05:33:08 INFO - 'USER': 'cltbld', 05:33:08 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1504787535.681553-1137939653', 05:33:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:33:08 INFO - '_': '/tools/buildbot/bin/python'} 05:33:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:33:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:33:08 INFO - Running setup.py (path:/tmp/pip-pJRR1D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:33:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:33:08 INFO - Running setup.py (path:/tmp/pip-IfkYsl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:33:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:33:08 INFO - Running setup.py (path:/tmp/pip-3r4xXW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:33:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:33:08 INFO - Running setup.py (path:/tmp/pip-Ws_Myq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:33:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:33:08 INFO - Running setup.py (path:/tmp/pip-ttUSii-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:33:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:33:08 INFO - Running setup.py (path:/tmp/pip-qDZT9o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:33:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:33:08 INFO - Running setup.py (path:/tmp/pip-oNQ7U3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:33:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:33:09 INFO - Running setup.py (path:/tmp/pip-G0_WzM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:33:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:33:09 INFO - Running setup.py (path:/tmp/pip-hYUAQR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:33:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:33:09 INFO - Running setup.py (path:/tmp/pip-bRNLfo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:33:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:33:09 INFO - Running setup.py (path:/tmp/pip-SghWVo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:33:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:33:09 INFO - Running setup.py (path:/tmp/pip-Z4dbGf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:33:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:33:09 INFO - Running setup.py (path:/tmp/pip-rq0Bwr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:33:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:33:09 INFO - Running setup.py (path:/tmp/pip-QSl_td-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:33:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:33:09 INFO - Running setup.py (path:/tmp/pip-grOIx8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:33:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:33:09 INFO - Running setup.py (path:/tmp/pip-GRNByP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:33:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:33:09 INFO - Running setup.py (path:/tmp/pip-NEipwK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:33:09 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:33:09 INFO - Running setup.py install for manifestparser 05:33:09 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:33:09 INFO - Running setup.py install for mozcrash 05:33:09 INFO - Running setup.py install for mozdebug 05:33:10 INFO - Running setup.py install for mozdevice 05:33:10 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:33:10 INFO - Running setup.py install for mozfile 05:33:10 INFO - Running setup.py install for mozhttpd 05:33:10 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:33:10 INFO - Running setup.py install for mozinfo 05:33:10 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:33:10 INFO - Running setup.py install for mozInstall 05:33:10 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:33:10 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:33:10 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:33:10 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:33:10 INFO - Running setup.py install for mozleak 05:33:10 INFO - Running setup.py install for mozlog 05:33:10 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:33:10 INFO - Running setup.py install for moznetwork 05:33:10 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:33:10 INFO - Running setup.py install for mozprocess 05:33:10 INFO - Running setup.py install for mozprofile 05:33:11 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:33:11 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:33:11 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:33:11 INFO - Running setup.py install for mozrunner 05:33:11 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:33:11 INFO - Running setup.py install for mozscreenshot 05:33:11 INFO - Running setup.py install for moztest 05:33:11 INFO - Running setup.py install for mozversion 05:33:11 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:33:11 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:33:11 INFO - Cleaning up... 05:33:11 INFO - Return code: 0 05:33:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:33:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:33:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:33:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:33:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:33:11 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 0x24b31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x267ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x281fa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2823090>, '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 0x2823d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x281df90>, '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': '4300f881736b422476174874000001bc-1504787535.681553-1137939653', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:33:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:33:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:33:11 INFO - Using env: {'DISPLAY': ':0', 05:33:11 INFO - 'HOME': '/home/cltbld', 05:33:11 INFO - 'LANG': 'en_US.UTF-8', 05:33:11 INFO - 'LANGUAGE': 'en_US:en', 05:33:11 INFO - 'LOGNAME': 'cltbld', 05:33:11 INFO - 'MAIL': '/var/mail/cltbld', 05:33:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:33:11 INFO - 'MOZ_NO_REMOTE': '1', 05:33:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:33:11 INFO - 'NO_EM_RESTART': '1', 05:33:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:33:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:33:11 INFO - 'PWD': '/builds/slave/test', 05:33:11 INFO - 'SHELL': '/bin/bash', 05:33:11 INFO - 'SHLVL': '1', 05:33:11 INFO - 'TERM': 'linux', 05:33:11 INFO - 'TMOUT': '86400', 05:33:11 INFO - 'USER': 'cltbld', 05:33:11 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1504787535.681553-1137939653', 05:33:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:33:11 INFO - '_': '/tools/buildbot/bin/python'} 05:33:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:33:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:33:11 INFO - Running setup.py (path:/tmp/pip-UZDbYl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:33:11 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 05:33:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:33:11 INFO - Running setup.py (path:/tmp/pip-wEatS9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:33:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:33:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:33:11 INFO - Running setup.py (path:/tmp/pip-FbZ_Eu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:33:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:33:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:33:11 INFO - Running setup.py (path:/tmp/pip-DIxaPc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:33:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:33:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:33:12 INFO - Running setup.py (path:/tmp/pip-AbVwu3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:33:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 05:33:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:33:12 INFO - Running setup.py (path:/tmp/pip-Hx34Vv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:33:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 05:33:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:33:12 INFO - Running setup.py (path:/tmp/pip-nqPk24-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:33:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 05:33:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:33:12 INFO - Running setup.py (path:/tmp/pip-507EYh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:33:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.13 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 05:33:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:33:12 INFO - Running setup.py (path:/tmp/pip-WikHqr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:33:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 05:33:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:33:12 INFO - Running setup.py (path:/tmp/pip-xDxJxz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:33:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.5 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:33:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:33:12 INFO - Running setup.py (path:/tmp/pip-OH3Xg5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:33:12 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 05:33:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:33:12 INFO - Running setup.py (path:/tmp/pip-odXXXd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:33:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 05:33:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:33:12 INFO - Running setup.py (path:/tmp/pip-a2jSmx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:33:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 05:33:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:33:12 INFO - Running setup.py (path:/tmp/pip-TrTN8o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:33:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 05:33:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:33:12 INFO - Running setup.py (path:/tmp/pip-MLuqOc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:33:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 05:33:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:33:12 INFO - Running setup.py (path:/tmp/pip-hleIki-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:33:12 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 05:33:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:33:12 INFO - Running setup.py (path:/tmp/pip-xmd2C3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:33:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 05:33:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:33:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:33:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:33:12 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:33:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:33:12 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.13->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 05:33:12 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:33:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:33:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:33:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:33:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:33:14 INFO - Downloading blessings-1.6.tar.gz 05:33:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:33:14 INFO - Installing collected packages: blessings 05:33:14 INFO - Running setup.py install for blessings 05:33:14 INFO - Successfully installed blessings 05:33:14 INFO - Cleaning up... 05:33:14 INFO - Return code: 0 05:33:14 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:33:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:33:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:33:14 INFO - Reading from file tmpfile_stdout 05:33:14 INFO - Current package versions: 05:33:14 INFO - argparse == 1.2.1 05:33:14 INFO - blessings == 1.6 05:33:14 INFO - blobuploader == 1.2.4 05:33:14 INFO - docopt == 0.6.1 05:33:14 INFO - functools32 == 3.2.3-2 05:33:14 INFO - jsonschema == 2.5.1 05:33:14 INFO - manifestparser == 1.1 05:33:14 INFO - mozInstall == 1.13 05:33:14 INFO - mozcrash == 1.0 05:33:14 INFO - mozdebug == 0.1 05:33:14 INFO - mozdevice == 0.51 05:33:14 INFO - mozfile == 1.2 05:33:14 INFO - mozhttpd == 0.7 05:33:14 INFO - mozinfo == 0.10 05:33:14 INFO - mozleak == 0.1 05:33:14 INFO - mozlog == 3.5 05:33:14 INFO - moznetwork == 0.27 05:33:14 INFO - mozprocess == 0.25 05:33:14 INFO - mozprofile == 0.28 05:33:14 INFO - mozrunner == 6.13 05:33:14 INFO - mozscreenshot == 0.1 05:33:14 INFO - mozsystemmonitor == 0.3 05:33:14 INFO - moztest == 0.8 05:33:14 INFO - mozversion == 1.4 05:33:14 INFO - psutil == 3.1.1 05:33:14 INFO - requests == 1.2.3 05:33:14 INFO - wsgiref == 0.1.2 05:33:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:33:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:33:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:33:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:33:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:33:14 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 0x24b31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x267ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x281fa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2823090>, '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 0x2823d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x281df90>, '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': '4300f881736b422476174874000001bc-1504787535.681553-1137939653', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:33:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 05:33:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:33:14 INFO - Using env: {'DISPLAY': ':0', 05:33:14 INFO - 'HOME': '/home/cltbld', 05:33:14 INFO - 'LANG': 'en_US.UTF-8', 05:33:14 INFO - 'LANGUAGE': 'en_US:en', 05:33:14 INFO - 'LOGNAME': 'cltbld', 05:33:14 INFO - 'MAIL': '/var/mail/cltbld', 05:33:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:33:14 INFO - 'MOZ_NO_REMOTE': '1', 05:33:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:33:14 INFO - 'NO_EM_RESTART': '1', 05:33:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:33:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:33:14 INFO - 'PWD': '/builds/slave/test', 05:33:14 INFO - 'SHELL': '/bin/bash', 05:33:14 INFO - 'SHLVL': '1', 05:33:14 INFO - 'TERM': 'linux', 05:33:14 INFO - 'TMOUT': '86400', 05:33:14 INFO - 'USER': 'cltbld', 05:33:14 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1504787535.681553-1137939653', 05:33:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:33:14 INFO - '_': '/tools/buildbot/bin/python'} 05:33:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:33:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:33:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 05:33:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 05:33:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:33:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 05:33:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 05:33:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 05:33:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 05:33:14 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 05:33:14 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 05:33:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:33:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:33:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:33:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:33:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 05:33:16 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:33:16 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:33:16 INFO - Installing collected packages: simplejson 05:33:16 INFO - Running setup.py install for simplejson 05:33:16 INFO - building 'simplejson._speedups' extension 05:33:16 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c simplejson/_speedups.c -o build/temp.linux-x86_64-2.7/simplejson/_speedups.o 05:33:16 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/simplejson/_speedups.o -o build/lib.linux-x86_64-2.7/simplejson/_speedups.so 05:33:17 INFO - Successfully installed simplejson 05:33:17 INFO - Cleaning up... 05:33:17 INFO - Return code: 0 05:33:17 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:33:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:33:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:33:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:33:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:33:17 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 0x24b31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x267ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x281fa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2823090>, '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 0x2823d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x281df90>, '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': '4300f881736b422476174874000001bc-1504787535.681553-1137939653', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:33:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 05:33:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 05:33:17 INFO - Using env: {'DISPLAY': ':0', 05:33:17 INFO - 'HOME': '/home/cltbld', 05:33:17 INFO - 'LANG': 'en_US.UTF-8', 05:33:17 INFO - 'LANGUAGE': 'en_US:en', 05:33:17 INFO - 'LOGNAME': 'cltbld', 05:33:17 INFO - 'MAIL': '/var/mail/cltbld', 05:33:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:33:17 INFO - 'MOZ_NO_REMOTE': '1', 05:33:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:33:17 INFO - 'NO_EM_RESTART': '1', 05:33:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:33:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:33:17 INFO - 'PWD': '/builds/slave/test', 05:33:17 INFO - 'SHELL': '/bin/bash', 05:33:17 INFO - 'SHLVL': '1', 05:33:17 INFO - 'TERM': 'linux', 05:33:17 INFO - 'TMOUT': '86400', 05:33:17 INFO - 'USER': 'cltbld', 05:33:17 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1504787535.681553-1137939653', 05:33:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:33:17 INFO - '_': '/tools/buildbot/bin/python'} 05:33:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:33:17 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:33:17 INFO - Cleaning up... 05:33:17 INFO - Return code: 0 05:33:17 INFO - Running post-action listener: _resource_record_post_action 05:33:17 INFO - Running post-action listener: _start_resource_monitoring 05:33:17 INFO - Starting resource monitoring. 05:33:17 INFO - [mozharness: 2017-09-07 12:33:17.372418Z] Finished create-virtualenv step (success) 05:33:17 INFO - [mozharness: 2017-09-07 12:33:17.373535Z] Running install step. 05:33:17 INFO - Running pre-action listener: _resource_record_pre_action 05:33:17 INFO - Running main action method: install 05:33:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:33:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:33:17 INFO - Reading from file tmpfile_stdout 05:33:17 INFO - Detecting whether we're running mozinstall >=1.0... 05:33:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:33:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:33:17 INFO - Reading from file tmpfile_stdout 05:33:17 INFO - Output received: 05:33:17 INFO - Usage: mozinstall [options] installer 05:33:17 INFO - Options: 05:33:17 INFO - -h, --help show this help message and exit 05:33:17 INFO - -d DEST, --destination=DEST 05:33:17 INFO - Directory to install application into. [default: 05:33:17 INFO - "/builds/slave/test"] 05:33:17 INFO - --app=APP Application being installed. [default: firefox] 05:33:17 INFO - mkdir: /builds/slave/test/build/application 05:33:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 05:33:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 05:33:34 INFO - Reading from file tmpfile_stdout 05:33:34 INFO - Output received: 05:33:34 INFO - /builds/slave/test/build/application/firefox/firefox 05:33:34 INFO - Running post-action listener: _resource_record_post_action 05:33:34 INFO - [mozharness: 2017-09-07 12:33:34.208679Z] Finished install step (success) 05:33:34 INFO - [mozharness: 2017-09-07 12:33:34.208930Z] Running setup-mitmproxy step. 05:33:34 INFO - Running pre-action listener: _resource_record_pre_action 05:33:34 INFO - Running main action method: setup_mitmproxy 05:33:34 INFO - Skipping: mitmproxy is not required 05:33:34 INFO - Running post-action listener: _resource_record_post_action 05:33:34 INFO - [mozharness: 2017-09-07 12:33:34.210164Z] Finished setup-mitmproxy step (success) 05:33:34 INFO - [mozharness: 2017-09-07 12:33:34.210382Z] Running run-tests step. 05:33:34 INFO - Running pre-action listener: _resource_record_pre_action 05:33:34 INFO - Running pre-action listener: _set_gcov_prefix 05:33:34 INFO - Running main action method: run_tests 05:33:34 WARNING - Try message not found. 05:33:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:33:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:33:34 INFO - Python 2.7.3 05:33:34 INFO - Return code: 0 05:33:34 INFO - grabbing minidump binary from tooltool 05:33: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')]} 05:33:34 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 0x2823090>, '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 0x2823d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x281df90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:33:34 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 05:33:34 INFO - Copy/paste: /tools/tooltool.py --url https://tooltool.mozilla-releng.net/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 05:33:34 INFO - Calling ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 05:33:34 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:33:34 INFO - Return code: 0 05:33:34 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 05:33:34 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:33:34 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:33:34 INFO - ENV: RUST_BACKTRACE is now full 05:33:34 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 05:33:34 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:33:34 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/O4Y3kdYKTk6AYNU8oh2jiA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-045', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 05:33:34 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/O4Y3kdYKTk6AYNU8oh2jiA/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-045 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 05:33:34 INFO - Using env: {'DISPLAY': ':0', 05:33:34 INFO - 'HOME': '/home/cltbld', 05:33:34 INFO - 'LANG': 'en_US.UTF-8', 05:33:34 INFO - 'LANGUAGE': 'en_US:en', 05:33:34 INFO - 'LOGNAME': 'cltbld', 05:33:34 INFO - 'MAIL': '/var/mail/cltbld', 05:33:34 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:33:34 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 05:33:34 INFO - 'MOZ_ACCELERATED': '1', 05:33:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:33:34 INFO - 'MOZ_NO_REMOTE': '1', 05:33:34 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:33:34 INFO - 'MOZ_WEBRENDER': '1', 05:33:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:33:34 INFO - 'NO_EM_RESTART': '1', 05:33:34 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:33:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:33:34 INFO - 'PWD': '/builds/slave/test', 05:33:34 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:33:34 INFO - 'RUST_BACKTRACE': 'full', 05:33:34 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 05:33:34 INFO - 'SHELL': '/bin/bash', 05:33:34 INFO - 'SHLVL': '1', 05:33:34 INFO - 'TERM': 'linux', 05:33:34 INFO - 'TMOUT': '86400', 05:33:34 INFO - 'USER': 'cltbld', 05:33:34 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1504787535.681553-1137939653', 05:33:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:33:34 INFO - '_': '/tools/buildbot/bin/python'} 05:33:34 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/O4Y3kdYKTk6AYNU8oh2jiA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-045', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 05:33:34 INFO - mozversion application_buildid: 20170907103944 05:33:34 INFO - mozversion application_changeset: 37b95547f0d27565452136d16b2df2857be840f6 05:33:34 INFO - mozversion application_display_name: Nightly 05:33:34 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:33:34 INFO - mozversion application_name: Firefox 05:33:34 INFO - mozversion application_remotingname: firefox 05:33:34 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:33:34 INFO - mozversion application_vendor: Mozilla 05:33:34 INFO - mozversion application_version: 57.0a1 05:33:34 INFO - mozversion platform_buildid: 20170907103944 05:33:34 INFO - mozversion platform_changeset: 37b95547f0d27565452136d16b2df2857be840f6 05:33:34 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:33:34 INFO - mozversion platform_version: 57.0a1 05:33:34 INFO - using testdate: 1504787614 05:33:34 INFO - actual date: 1504787614 05:33:34 INFO - starting webserver on 'localhost:60741' 05:33:34 INFO - SUITE-START | Running 2 tests 05:33:34 INFO - TEST-START | dromaeo_css 05:33:34 INFO - Initialising browser for dromaeo_css test... 05:33:34 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:60741/getInfo.html -profile /tmp/tmpWk7TUX/profile 05:33:34 INFO - TEST-INFO | started process 3578 (/builds/slave/test/build/application/firefox/firefox http://localhost:60741/getInfo.html) 05:33:45 INFO - TEST-INFO | 3578: exit 0 05:33:45 INFO - Browser initialized. 05:33:45 INFO - Running cycle 1/1 for dromaeo_css test... 05:33:45 INFO - Using env: {'DISPLAY': ':0', 05:33:45 INFO - 'HOME': '/home/cltbld', 05:33:45 INFO - 'JSGC_DISABLE_POISONING': '1', 05:33:45 INFO - 'LANG': 'en_US.UTF-8', 05:33:45 INFO - 'LANGUAGE': 'en_US:en', 05:33:45 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 05:33:45 INFO - 'LOGNAME': 'cltbld', 05:33:45 INFO - 'MAIL': '/var/mail/cltbld', 05:33:45 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:33:45 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 05:33:45 INFO - 'MOZ_ACCELERATED': '1', 05:33:45 INFO - 'MOZ_CRASHREPORTER': '1', 05:33:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:33:45 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 05:33:45 INFO - 'MOZ_NO_REMOTE': '1', 05:33:45 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:33:45 INFO - 'MOZ_WEBRENDER': '1', 05:33:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:33:45 INFO - 'NO_EM_RESTART': '1', 05:33:45 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:33:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:33:45 INFO - 'PWD': '/builds/slave/test', 05:33:45 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:33:45 INFO - 'RUST_BACKTRACE': 'full', 05:33:45 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 05:33:45 INFO - 'SHELL': '/bin/bash', 05:33:45 INFO - 'SHLVL': '1', 05:33:45 INFO - 'TERM': 'linux', 05:33:45 INFO - 'TMOUT': '86400', 05:33:45 INFO - 'USER': 'cltbld', 05:33:45 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1504787535.681553-1137939653', 05:33:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:33:45 INFO - '_': '/tools/buildbot/bin/python'} 05:33:45 INFO - TEST-INFO | started process 3785 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpWk7TUX/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 05:33:46 INFO - PID 3785 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 05:33:46 INFO - PID 3785 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 05:33:46 INFO - PID 3785 | 05:33:46 INFO - PID 3785 | (/builds/slave/test/build/application/firefox/firefox:3846): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 05:33:46 INFO - PID 3785 | 05:33:57 INFO - PID 3785 | RSS: Main: 210268160 05:33:57 INFO - PID 3785 | 05:36:55 INFO - PID 3785 | Cycle 1(1): loaded http://localhost:60741/tests/dromaeo/cssquery-dojo.html (next: http://localhost:60741/tests/dromaeo/cssquery-ext.html) 05:36:56 INFO - PID 3785 | RSS: Main: 219385856 05:36:56 INFO - PID 3785 | 05:39:58 INFO - PID 3785 | Cycle 1(1): loaded http://localhost:60741/tests/dromaeo/cssquery-ext.html (next: http://localhost:60741/tests/dromaeo/cssquery-jquery.html) 05:39:59 INFO - PID 3785 | RSS: Main: 216256512 05:39:59 INFO - PID 3785 | 05:43:01 INFO - PID 3785 | Cycle 1(1): loaded http://localhost:60741/tests/dromaeo/cssquery-jquery.html (next: http://localhost:60741/tests/dromaeo/cssquery-mootools.html) 05:43:01 INFO - PID 3785 | RSS: Main: 219033600 05:43:01 INFO - PID 3785 | 05:46:03 INFO - PID 3785 | Cycle 1(1): loaded http://localhost:60741/tests/dromaeo/cssquery-mootools.html (next: http://localhost:60741/tests/dromaeo/cssquery-prototype.html) 05:46:04 INFO - PID 3785 | RSS: Main: 214716416 05:46:04 INFO - PID 3785 | 05:49:08 INFO - PID 3785 | Cycle 1(1): loaded http://localhost:60741/tests/dromaeo/cssquery-prototype.html (next: http://localhost:60741/tests/dromaeo/cssquery-yui.html) 05:49:08 INFO - PID 3785 | RSS: Main: 219205632 05:49:08 INFO - PID 3785 | 05:52:10 INFO - PID 3785 | Cycle 1(1): loaded http://localhost:60741/tests/dromaeo/cssquery-yui.html (next: http://localhost:60741/tests/dromaeo/cssquery-dojo.html) 05:52:11 INFO - PID 3785 | RSS: Main: 224587776 05:52:11 INFO - PID 3785 | 05:52:11 INFO - PID 3785 | __start_tp_report 05:52:11 INFO - PID 3785 | _x_x_mozilla_page_load 05:52:11 INFO - PID 3785 | _x_x_mozilla_page_load_details 05:52:11 INFO - PID 3785 | |i|pagename|runs| 05:52:11 INFO - PID 3785 | |0;dojo.html;3281.524178994046;3289.0954987378477;3316.2041110133573;3319.784214026088;3329.916752081196;6275.4038366355235;6422.967885160573;6495.8051258462265;6496.935030649698;6503.609783412999;6331.651759153245;6373.203349581291;6389.680515974194;6391.0413437984325;6407.935920640793;5782.999999999989;5788.392218817026;5811.721421287331;5842.269716285465;5891.204687367193;5874.206982057432;5896.469317761401;5901.026330655458;5908.202392676976;5939.88120237595;5817.883642327172;5848.561357898154;5851.561132915027;5869.295684517864;5941;5949.315828679714;5954.434328738763;5958.97020514899;5976.581639285252;5997.070454079581;5849.005669036274;5889.057750759858;5965.642061476326;5974.40255974399;5998.430149135826;6181.907271390933;6370.044493325991;6372.426481616631;6454.741810327581;6552.180977377828;6674.766383176614;6730.495212859055;6743.59538427691;6777.322267773233;6992.965035174842;6951.617661028641;6957.408620267283;7042.683079261395;7045.471589630798;7056.329648683367;6139.969300153471;6402.103705481236;6430.260520040161;6490.2536208335705;6512.837179070561;6864.450843932474;6970.721171153198;7003.824904377329;7055.118110236221;7078.433725301964;6703.899441508382;6714.52998290115;6716.6977486012265;6717.093192419059;6725.159355080615;6725.2602213756445;6868.141482314711;6879.346462086094;6881.827954301082;6881.8967715484305;6785.1518560179975;6808.285130061371;6829.112215411965;6879;6879.6560171991205;6820.829479262959;6834.384905358442;6897.310268973062;6909.170899491993;6910.136232970879;5309.2301616266195;5370.489803468664;5373;5400.135978243462;5412.675239485643;4754.405699287589;4781.545753153445;4795.520447955177;4814.15752243356;4848.272759086165;5951.672658003765;6017.187679663277;6031.638101713911;6039.396060394013;6040.728167232485;6881.552699074544;6891.620960847101;6982.860342793116;6983.8603227935155;6996.8950465743055;5810.389909059572;5865.589408741433;5887.35239123696;5891.49922256613;5979.910301345483;5850.473457388855;5852.239208902815;5857.4435428634215;5868.647881127146;5873.765049397976;6623.370779775913;6650.268470468244;6708.094407255056;6709.597424154566;6730.46156307504;6331.449264723838;6681.766138185335;6735.898961515483;6755.560888542229;6878.002689609979;6477.708503117466;6487.18910136233;6507.609543427409;6516.609003459618;6552.6068435894;6636.13730215069;6815.488838337045;6841.17905851301;6852.794416167523;6892.483063770337;6927.960805879158;6991.265917078634;6998.965005174941;6999.965000174967;7014.158301003708;6595.967020164869;6624.4700423965005;6627;6713.765018224339;6714.731410743516;6420.839479013062;6533.444657204191;6545;6592.538522303393;6736.191657001191;6076.848078798065;6466.644334561644;6469.5794773339585;6477.902831457626;6519.934800651933;6754.358335958076;6777.051212830306;6795.93204067953;6849.24658287578;6872.518923675295;6272.717727702173;6482.837929051811;6499.911054888919;6591.6374599397495;6598.934010659832 05:52:11 INFO - PID 3785 | |1;ext.html;846.3736834742285;860.4407135362014;879.961645258595;889.9822003559933;891.104440037762;40066.799666001665;41193.58806411935;41415.792921035354;41426.17147657045;41479;37890;41020;41857;41936.58063419365;41951.58048419515;7747.41894357924;7870.921290787076;7872.488288261274;7875.724349647763;7887;7659.157492675801;7732.922670773277;7807.297343239107;7813.296803287703;7829;7777.261160189773;7812.765617031499;7855.764327070197;7856.1751016143335;7858.174891636381;7722.227777222289;7762.262585054439;7776.883346749803;7822.37421006321;7824.5696486693205;7733.110692270348;7800.375969922393;7804.219578042207;7809.414293927921;7812.06255249368;38433.039174020596;38699.41950870739;38720.806395968124;38722.41916371257;38732.612673873184;3488.5290485784603;3580.051286409104;3580.5524309461316;3581.94627080594;3589.8743543976097;31254;31308;31360.843195784102;31422.842885785423;31428.52857207144;5134.820281290137;5168.948310516885;5194.11700010999;5208.427073021964;5282.735863206863;189969;190446.9999999986;190772;190835;190957;46842.5315746845;47405;47568.2864757022;47573.28640070402;47727.28409073866;49238.261426078636;49345;49425.75287123541;49443.258351124765;52194.47805521972;16002.199890005453;16141.273642686107;16229;16259.674806504041;16263.59341016484;13167.473301068041;13348.73302533944;13365.732685346238;13373.39819708096;13386.19682819034;14043.78934315986;14112.71774564503;14218.431262749582;14360;14624.19566923808;5610.607257492018;5680.6591604503865;5736.139579063174;5769;5770.480656740913;970.315927271272;1009;1009.8788145422596;1018.2414101494286;1041.1358572384902;23241.883790580938;23595.41011474727;23646.408839779142;23794.7620523796;23899.52200955971;1103.61925135828;1120.3334016260312;1120.4957769003981;1132.8357388178833;1139.5669645534645;12113.33376664292;12118.819007442418;12206.450709718085;12431.94328482089;12450.564230251897;238629;238780;239506;240200;240431;44856;45158;51899.74050129725;52264;52497.2125418119;1424.3020919749456;1446.4286606790258;1447.6163816588612;1449;1477.8004969329004;26765.46469070569;27322.590161147997;27739.306517337227;27770.722292776813;27817.860910695315;48535.271970921174;49076.26385604147;49130;49341.013179736925;49382.50617493779;212395;213490;214068;214447;215107;33805.32389352249;33850;33923;36052.45921311235;37249.81375093107;44892;45379;45802.77098614485;45895;46290.30564541467;33130.50304245487;33611.327773444886;34214.48678269778;34299.82850085734;35950.64049359473;34944.475832863034;35324.82337588296;35808.820955896095;35969.820150899075;36366.27267454689;38250;40284.194316112924;40327;40340.394894076024;40540.797296013334;40458.595414045485;41142;41605;41918;42098.158036839704;25018.37454063663;25150.11974580881;25540.509616246196;25721.871390642926;25886.870565647052 05:52:11 INFO - PID 3785 | |2;jquery.html;554.2905081495687;572.9942700572993;578.9363170051291;583.9854003649909;592.8636413624865;21813;22195.334139975806;22395.888020559916;22647.660285095717;22703.20538781143;24775.256742297715;25101.49797004059;25144.62283065751;26118;26402.6039609406;6738.9326106739045;6871.03805467235;6906.482013848957;6907.447404207652;6913.170419549661;6845.212800527928;6879.1057162568795;6885.552439091468;6886.104806375164;6889.931100689004;6377.457916077139;6572.704228309737;6663.466922646201;6677.599344039373;6684.097646817666;6566.441852442525;6594.241662208811;6608.4052435281055;6628.43658289046;6660.067590537328;6072.423119803611;6148.200733904564;6148.446639802394;6150.477209437203;6164.630122192638;28236.435271294664;29075;29448;29630.111096667133;29635;1981.8513611479198;2038.4088614301834;2041.2651445479617;2045.0183911722308;2045.8261047810954;19745.901270493698;20083.096260668306;20089.397318080333;20149.194032238694;20177.495562610904;2348.225085721725;2382.487765130505;2436.683231179953;2442.792362649177;2448.6939132608422;122242.3887880555;131805;134018;139234;139565.30217348848;14669.401893695569;14674.926625367018;14782.48261310849;15012;15082.472113475975;23169.189078382176;23327.18354857572;23369.88315058448;23390.18134365289;23464.178753743538;10378.740531486774;10410.895891040993;10497.790044199073;10519.894801052045;10523.894761052445;9635.614575417045;9899.60401583943;9934.45360505179;9941;9965.701028969142;9061.864072038925;9117.863232051524;9278.11857873501;9360;9424.811503769886;2772.487089888337;2786.749192572678;2819.16834533813;2838.176928690698;2918.54762511811;926.7127190570944;940.7883226274171;954.1365064616533;990.1880458024351;1004.1264100232844;19409.32067377635;19724.211031558865;19755.703664445045;23908;23972.160974365812;1008.3546530220665;1057.2493529594074;1057.5611121384693;1067.962621308266;1084.0460394852637;10403.907589702972;11261.718457038476;11384.37385943781;11413.942930285295;11694.590689325832;529239;531218;532798;534058;539920;117691;119166.4041679786;124117;126986;127251.36374318069;1228.6252692928742;1256.2650849253107;1289.2410242081953;1292.3651580750359;1297.1698113207556;18554;18555;18611.627767444304;18735.90632046831;18878.811211887707;59526.10710839427;60827.39172608217;61111.38888611057;61178.388216117266;61855.76288474295;75051;75378;75447.62276188584;75677.24322756923;76351;20228.292009779587;20328.186872524933;20558.38324850257;20614.58770824545;20643.896780516003;22713.432164196027;22914.083436662346;23193;23207.535849283257;23617.881910591026;18603;18759.718604220674;18804.811951880307;18837.717434238224;19017.524561886064;15966.760498592297;16007.199640018185;16143.273552690393;16263.26815293291;16328.510144695678;17943.282268709107;18529.81470185281;18788.812111878706;18912.62174756525;18958.336458223897;19691.70462443036;19848.60302793965;20006.19975201034;20064.197432103138;20134.892580907606;18157.54606134857;18292.085395729893;18503;18512.16695248744;18709.15808788635 05:52:11 INFO - PID 3785 | |3;mootools.html;1309.0836414509843;1345.3206130903893;1348.8313960754906;1359.4766015084201;1362.0397619678129;14427.855721442782;15137.31882065307;15148.772768408455;15149.545513634595;15158.317875695606;14838.925805370985;15030.624234394145;15085.094894306349;15164.620884477894;15201.771973420407;2661.7870570354326;2803.257136858734;2808.326001759573;2808.7191280871953;2811.0301945828655;2705.9864700676467;2737.86310684465;2747.5741260104614;2750.051232324845;2752.9862350688218;2669.599560065986;2782.9721702783017;2801.5937689035077;2805.6492938382703;2805.9298517537122;2605.7915366770612;2643.458091091322;2668.8932442702267;2675.3044208505735;2679.0757188769844;2485.155047283932;2498.7126480454617;2641.300055485298;2701.74333438325;2706.336947447868;14709.632259193499;14741.410343586243;14885.92557037208;14917.701645967129;14940.551783446404;7598.126215485179;7912.960435197845;7915.287624113857;7915.525068495908;7917.129115797257;5321.547668448186;5424.349078110613;5498.477644623755;5501.669899806025;5584.748686309126;1904.6002037432934;1943.6307101650782;1957.1975490049012;1966.9311574094982;1986.4040787763759;25322.620160697603;25468.36329091769;25684.48631027388;25715.61426578603;25772.355691107685;6452.903206451907;6638.933610663928;6688.86622267552;6754.594724316458;6939.861202775916;16808.411705590246;16847.326106955585;16864.662706745796;16878.409255676237;16884.577885552713;7414.6292685365515;7516.285952834472;7549.962250188714;7585.089789225328;7590.430717696195;6396.136521569529;7008.404285635676;7065.717371305193;7065.823354416078;7070.434365250768;5449.427810079964;5692.516136128393;5692.57305702074;5711.3146422428745;5721.685307308055;1782.9821701782816;1807.2951548895942;1826.8538516918616;1837.4212123181155;1839.383806424836;902.2601466797163;925.1812146250483;931.4737173496952;936.5317341329335;944.1974321826392;7460.664270107858;7518;7589.7343592975085;7608.087029556488;7732.342750866127;694.1254019934919;710.9751158709421;717.2325611595543;720.9243029481934;724.6376811594203;4435.711678740871;4997.525235102659;5243.554297884646;5280.102382594968;5402.891942161214;20109.59780804405;20643;21034.158633654482;21183.470413239793;21725.456863578536;20932.790672093084;21016.579668406852;21085.68371474429;21231.256906008217;21377.893110534347;10672.199585031154;11208.551657933755;11514.309141451373;11606.071514279007;11612.419379031184;8445.239928406472;8563.871541927001;8842;8976;8977.416467929565;18037.09814509243;18225.726614100535;18488.075596219867;18495.630087398447;18586.535336616693;18882.622347552697;18891.43325700231;18955.810441895406;19000.809991899903;19466.707999379738;12040.819387709367;12134.939325303318;12170.14808963364;12193.51225950952;12275.324857132944;13422.194668319931;13689.178649281075;13715.314234288046;13747.106438081493;13792.241426721626;11005.394703291397;11096.500657470271;11120.388378639253;11346.886531134913;11458.713532161762;10951.726206844893;11190.552377904793;11242.494087765906;11313.20807543464;11545.82681259765;9946.602135914482;10111.191104711752;10131.7467063324;10145.08694217524;10168;7425.888611670937;7434.256574342522;7511.849763004819;7583.848323033619;7677;7522.134954480305;7565.773026809205;7594.31651151399;7658.119316278477;7726.459147859596 05:52:11 INFO - PID 3785 | |4;prototype.html;513.9640225184236;519.3145048535932;532.2202972645074;536.364408176311;540.6945076032042;6137.938620613792;6169.537284703642;6212.9068063979075;6266.342034086418;6280.497560195185;6257.62454252744;6260.591366942435;6316.494680425566;6329;6368.713407896645;5205.895882082356;5231.712255825928;5240.423553409122;5276.182191760264;5321.787128514865;4866.391701037371;4947.219001159579;5217.530422261995;5252.579793616521;5305.177697456881;4958.925616115761;4960.280759289901;5036.294918711384;5037.622178336621;5048.29323894655;4999.675086102167;5011.724355160465;5033.421156567005;5046.19012645454;5050.595952323823;4457.309117086825;4458.3535387368975;4466.441694788152;4501.23479008566;4509.692189265109;6139.969300153515;6195.097088834006;6207.348228435994;6239.251289845202;6258.435391152212;5704.4865962063195;5722.226109905942;5734.627249228787;5745.6552606843725;5777.104548794943;6537.869242615121;6542.705578248942;6564.573302735354;6569.50728695345;6672.232693240291;4764.118638051971;4823.324734537167;4839.419269687624;4845.927311090337;4875.34182885313;6905.413039891666;6940.6529673516125;6967.442604591621;6985.755498557526;6998;4984.37695288089;5045.344105266292;5049.015441988777;5074.944647417802;5085.237214417867;4978;4995.500449954975;5042.621803364762;5051.494850514919;5084.3898732152375;5716.885662286814;5736.340320863071;5743.138529220567;5750.137479378043;5775.364709881909;6541.771038013647;6655.401013908675;6656.500762442836;6693.564918280296;6697.263301036882;1633.3629884345114;1641.1958140511063;1676.9916150419415;1683.335082642374;1771.5393997560727;1210.8062709966364;1226.693326668333;1245.9907474945323;1258.6098309524077;1263.7914744067125;5630.5214056805635;5760.567957403212;5770.451807078321;5784.71076446176;5821.243238378984;6516.543841931114;6553.772816533309;6668.499862510331;6739.797806065826;6743.831404214836;5796.913046304308;5835.912461313083;5848.122781582797;5852.209951656557;5884.440978107073;3573.874914377984;3598.712103031751;3609.4946707460977;3611.1763559402334;3623.873164439232;6450.290468048511;6490.350964903472;6498.155239818793;6579;6611.966940165268;6691.966540167268;6911.375826680726;6930.272321406281;6953.89569156463;6964;6530.575512591667;6534.941493429555;6567.704453299709;6590.736370545124;6650.700718467584;6680.6993685283305;6743.9325606743305;6747.730090796314;6749.0888730020815;6783.864322713617;6646.244871647504;6679.398854103154;6688.030235615711;6729.461643068641;6766.560173588701;6570.967145164243;6609;6616.338366163345;6619.371159739817;6626.271110177973;5012.974935125301;5020.6234532410945;5026.572741317029;5047.419546752024;5069.417017043087;6731.663416829237;6752.189737231367;6763.222229443677;6777.423918966943;6793.490488213502;6520.543561950618;6530.967345163243;6539.607623542603;6584;6673.132492775908;6295.653739044214;6495.805125846232;6587.571807832475;6607.57050791697;6647.966760166361;6104.847378815388;6385.393387628168;6421.839454013687;6450.741970321134;6494.149167487243;6462.741490340334;6498;6517.348265173445;6594.868102638016;6605.207375115017;6332.936670633235;6339.397757213057;6372.617642941438;6374.649394283359;6386.297507274103 05:52:11 INFO - PID 3785 | |5;yui.html;898.1961144775427;901.0538934119176;913.3058875254804;922.6862866625346;922.709346555835;18719.3448229312;19323.420297391065;19364.51588710283;19381.418557443263;19435.222591096357;17603.11984400778;17766.46700598981;17998.100094995232;18057.909710451462;18109;935.5228833295017;954.413035982869;957.6743907071594;964.7973925475659;966.8549717542389;986.7237173591406;987.669130841169;989.0159291504963;990.6681261777313;991.0386924683046;973.4791886340809;1011.2112552209252;1011.2617789014024;1014.7361685961666;1020.9642662506814;980.5146452506019;992.687303499399;993.741627176932;994.0258546624349;995.4525011243787;745.5154149802618;749.8612756639984;750.0324581290129;751.0761763031502;753.600591686407;19464.70802937957;20174.19303227869;20330;20362.898185509126;20407.591848162952;21425.71445713262;21887.12451501938;21948.670769938464;22141.66787498189;22339.55320893573;16469.58826029359;16676.83323166765;16821.57946051346;17035;17197.226124824414;3770.5286839145106;3779.848806047755;3783.5459744830523;3797.2405518896053;3804.733668643196;103523.48238258761;142282;146912.2654386732;149343;150838.24580877135;17322;17407.825921740874;17591.824081759274;17683.20425580852;17726.113694315234;31356.529652055237;31988.520172197437;32015.679843201735;32098.83950580232;32130.678693213235;8475.152484751476;8721.345899057596;8864.64541418336;8883.911160888309;8943.821123577622;4146.315857883415;4223.239816832938;4224.683148763855;4226.5773422657485;4231.4710661167355;5358.517733404013;5368.087425137736;5436.864078398072;5445.237666726662;5450.8909821803345;1048.5072016152396;1065.0574241795912;1089.5859573362072;1090.9727256818644;1092.6558134187705;683.2040672616446;695.401954319285;704.5772536478174;705.6436499567789;714.6819665248914;12377.257364558156;12456.688582785502;12525.56160534377;12811.551595694105;12853.807192892114;695.151914664109;703.5989485993038;710.1974768511549;711.975080872167;713.9857202856018;5645.991346144201;5662.405447428043;5710.914336284959;5742.253507044057;5748.310202775694;125995;126337;129841;130476;135013;81252;108689;110971.44514277538;112084;114539.42730286295;99345;101055.4947225274;101988.49005754924;102414;105899;12681.682957926125;12971.41628626733;13067.803982940453;13069.607911762663;13150.21098734079;42551.3617295747;42718.14563708646;43117.13765724605;43842.342364863915;45836;57949.420505794405;59599.70200148971;61194.694026529585;79012.60493697495;83757;19388.515287117934;19402.805971940663;19575.119119639367;20112.597748044664;20148.59702805965;20767.8961605191;21009.684854726886;21911.780882190975;22001.66997495007;22369.105235790386;18785.436436906915;19075.713864292324;19117.61764764669;20781.792182077985;21099.472513187295;18208.544786379916;18459.892406455656;18673.626527469103;19506.414807555797;19512.609747805247;16569.42007029748;16815.831841681425;16843.578910527336;16952.745708814626;19612.60774784468;12720.173188742701;13069.738605228033;13164.539241126515;13167.802482962954;13297;15163.938524303192;15270.236488175324;15768.918871857872;15814.525564233092;15826.208689565245 05:52:11 INFO - PID 3785 | __end_tp_report 05:52:11 INFO - PID 3785 | __start_cc_report 05:52:11 INFO - PID 3785 | _x_x_mozilla_cycle_collect,355 05:52:11 INFO - PID 3785 | __end_cc_report 05:52:11 INFO - PID 3785 | __startTimestamp1504788731113__endTimestamp 05:52:11 INFO - PID 3785 | 05:52:11 INFO - PID 3785 | ------- Summary: start ------- 05:52:11 INFO - PID 3785 | Number of tests: 6 05:52:11 INFO - PID 3785 | 05:52:11 INFO - PID 3785 | [#0] dojo.html Cycles:165 Average:6300.98 Median:6499.91 stddev:737.73 (11.3%) stddev-sans-first:700.94 05:52:11 INFO - PID 3785 | Values: 3281.5 3289.1 3316.2 3319.8 3329.9 6275.4 6423.0 6495.8 6496.9 6503.6 6331.7 6373.2 6389.7 6391.0 6407.9 5783.0 5788.4 5811.7 5842.3 5891.2 5874.2 5896.5 5901.0 5908.2 5939.9 5817.9 5848.6 5851.6 5869.3 5941.0 5949.3 5954.4 5959.0 5976.6 5997.1 5849.0 5889.1 5965.6 5974.4 5998.4 6181.9 6370.0 6372.4 6454.7 6552.2 6674.8 6730.5 6743.6 6777.3 6993.0 6951.6 6957.4 7042.7 7045.5 7056.3 6140.0 6402.1 6430.3 6490.3 6512.8 6864.5 6970.7 7003.8 7055.1 7078.4 6703.9 6714.5 6716.7 6717.1 6725.2 6725.3 6868.1 6879.3 6881.8 6881.9 6785.2 6808.3 6829.1 6879.0 6879.7 6820.8 6834.4 6897.3 6909.2 6910.1 5309.2 5370.5 5373.0 5400.1 5412.7 4754.4 4781.5 4795.5 4814.2 4848.3 5951.7 6017.2 6031.6 6039.4 6040.7 6881.6 6891.6 6982.9 6983.9 6996.9 5810.4 5865.6 5887.4 5891.5 5979.9 5850.5 5852.2 5857.4 5868.6 5873.8 6623.4 6650.3 6708.1 6709.6 6730.5 6331.4 6681.8 6735.9 6755.6 6878.0 6477.7 6487.2 6507.6 6516.6 6552.6 6636.1 6815.5 6841.2 6852.8 6892.5 6928.0 6991.3 6999.0 7000.0 7014.2 6596.0 6624.5 6627.0 6713.8 6714.7 6420.8 6533.4 6545.0 6592.5 6736.2 6076.8 6466.6 6469.6 6477.9 6519.9 6754.4 6777.1 6795.9 6849.2 6872.5 6272.7 6482.8 6499.9 6591.6 6598.9 05:52:11 INFO - PID 3785 | 05:52:11 INFO - PID 3785 | [#1] ext.html Cycles:180 Average:39288.26 Median:38341.52 stddev:55816.53 (145.6%) stddev-sans-first:55898.47 05:52:11 INFO - PID 3785 | Values: 846.4 860.4 880.0 890.0 891.1 40066.8 41193.6 41415.8 41426.2 41479.0 37890.0 41020.0 41857.0 41936.6 41951.6 7747.4 7870.9 7872.5 7875.7 7887.0 7659.2 7732.9 7807.3 7813.3 7829.0 7777.3 7812.8 7855.8 7856.2 7858.2 7722.2 7762.3 7776.9 7822.4 7824.6 7733.1 7800.4 7804.2 7809.4 7812.1 38433.0 38699.4 38720.8 38722.4 38732.6 3488.5 3580.1 3580.6 3581.9 3589.9 31254.0 31308.0 31360.8 31422.8 31428.5 5134.8 5168.9 5194.1 5208.4 5282.7 189969.0 190447.0 190772.0 190835.0 190957.0 46842.5 47405.0 47568.3 47573.3 47727.3 49238.3 49345.0 49425.8 49443.3 52194.5 16002.2 16141.3 16229.0 16259.7 16263.6 13167.5 13348.7 13365.7 13373.4 13386.2 14043.8 14112.7 14218.4 14360.0 14624.2 5610.6 5680.7 5736.1 5769.0 5770.5 970.3 1009.0 1009.9 1018.2 1041.1 23241.9 23595.4 23646.4 23794.8 23899.5 1103.6 1120.3 1120.5 1132.8 1139.6 12113.3 12118.8 12206.5 12431.9 12450.6 238629.0 238780.0 239506.0 240200.0 240431.0 44856.0 45158.0 51899.7 52264.0 52497.2 1424.3 1446.4 1447.6 1449.0 1477.8 26765.5 27322.6 27739.3 27770.7 27817.9 48535.3 49076.3 49130.0 49341.0 49382.5 212395.0 213490.0 214068.0 214447.0 215107.0 33805.3 33850.0 33923.0 36052.5 37249.8 44892.0 45379.0 45802.8 45895.0 46290.3 33130.5 33611.3 34214.5 34299.8 35950.6 34944.5 35324.8 35808.8 35969.8 36366.3 38250.0 40284.2 40327.0 40340.4 40540.8 40458.6 41142.0 41605.0 41918.0 42098.2 25018.4 25150.1 25540.5 25721.9 25886.9 05:52:11 INFO - PID 3785 | 05:52:11 INFO - PID 3785 | [#2] jquery.html Cycles:180 Average:36729.80 Median:23041.64 stddev:89335.03 (387.7%) stddev-sans-first:89544.35 05:52:11 INFO - PID 3785 | Values: 554.3 573.0 578.9 584.0 592.9 21813.0 22195.3 22395.9 22647.7 22703.2 24775.3 25101.5 25144.6 26118.0 26402.6 6738.9 6871.0 6906.5 6907.4 6913.2 6845.2 6879.1 6885.6 6886.1 6889.9 6377.5 6572.7 6663.5 6677.6 6684.1 6566.4 6594.2 6608.4 6628.4 6660.1 6072.4 6148.2 6148.4 6150.5 6164.6 28236.4 29075.0 29448.0 29630.1 29635.0 1981.9 2038.4 2041.3 2045.0 2045.8 19745.9 20083.1 20089.4 20149.2 20177.5 2348.2 2382.5 2436.7 2442.8 2448.7 122242.4 131805.0 134018.0 139234.0 139565.3 14669.4 14674.9 14782.5 15012.0 15082.5 23169.2 23327.2 23369.9 23390.2 23464.2 10378.7 10410.9 10497.8 10519.9 10523.9 9635.6 9899.6 9934.5 9941.0 9965.7 9061.9 9117.9 9278.1 9360.0 9424.8 2772.5 2786.7 2819.2 2838.2 2918.5 926.7 940.8 954.1 990.2 1004.1 19409.3 19724.2 19755.7 23908.0 23972.2 1008.4 1057.2 1057.6 1068.0 1084.0 10403.9 11261.7 11384.4 11413.9 11694.6 529239.0 531218.0 532798.0 534058.0 539920.0 117691.0 119166.4 124117.0 126986.0 127251.4 1228.6 1256.3 1289.2 1292.4 1297.2 18554.0 18555.0 18611.6 18735.9 18878.8 59526.1 60827.4 61111.4 61178.4 61855.8 75051.0 75378.0 75447.6 75677.2 76351.0 20228.3 20328.2 20558.4 20614.6 20643.9 22713.4 22914.1 23193.0 23207.5 23617.9 18603.0 18759.7 18804.8 18837.7 19017.5 15966.8 16007.2 16143.3 16263.3 16328.5 17943.3 18529.8 18788.8 18912.6 18958.3 19691.7 19848.6 20006.2 20064.2 20134.9 18157.5 18292.1 18503.0 18512.2 18709.2 05:52:11 INFO - PID 3785 | 05:52:11 INFO - PID 3785 | [#3] mootools.html Cycles:180 Average:9264.65 Median:14189.07 stddev:6513.80 (45.9%) stddev-sans-first:6504.64 05:52:11 INFO - PID 3785 | Values: 1309.1 1345.3 1348.8 1359.5 1362.0 14427.9 15137.3 15148.8 15149.5 15158.3 14838.9 15030.6 15085.1 15164.6 15201.8 2661.8 2803.3 2808.3 2808.7 2811.0 2706.0 2737.9 2747.6 2750.1 2753.0 2669.6 2783.0 2801.6 2805.6 2805.9 2605.8 2643.5 2668.9 2675.3 2679.1 2485.2 2498.7 2641.3 2701.7 2706.3 14709.6 14741.4 14885.9 14917.7 14940.6 7598.1 7913.0 7915.3 7915.5 7917.1 5321.5 5424.3 5498.5 5501.7 5584.7 1904.6 1943.6 1957.2 1966.9 1986.4 25322.6 25468.4 25684.5 25715.6 25772.4 6452.9 6638.9 6688.9 6754.6 6939.9 16808.4 16847.3 16864.7 16878.4 16884.6 7414.6 7516.3 7550.0 7585.1 7590.4 6396.1 7008.4 7065.7 7065.8 7070.4 5449.4 5692.5 5692.6 5711.3 5721.7 1783.0 1807.3 1826.9 1837.4 1839.4 902.3 925.2 931.5 936.5 944.2 7460.7 7518.0 7589.7 7608.1 7732.3 694.1 711.0 717.2 720.9 724.6 4435.7 4997.5 5243.6 5280.1 5402.9 20109.6 20643.0 21034.2 21183.5 21725.5 20932.8 21016.6 21085.7 21231.3 21377.9 10672.2 11208.6 11514.3 11606.1 11612.4 8445.2 8563.9 8842.0 8976.0 8977.4 18037.1 18225.7 18488.1 18495.6 18586.5 18882.6 18891.4 18955.8 19000.8 19466.7 12040.8 12134.9 12170.1 12193.5 12275.3 13422.2 13689.2 13715.3 13747.1 13792.2 11005.4 11096.5 11120.4 11346.9 11458.7 10951.7 11190.6 11242.5 11313.2 11545.8 9946.6 10111.2 10131.7 10145.1 10168.0 7425.9 7434.3 7511.8 7583.8 7677.0 7522.1 7565.8 7594.3 7658.1 7726.5 05:52:11 INFO - PID 3785 | 05:52:11 INFO - PID 3785 | [#4] prototype.html Cycles:180 Average:5547.03 Median:6210.13 stddev:1554.34 (25.0%) stddev-sans-first:1512.09 05:52:11 INFO - PID 3785 | Values: 514.0 519.3 532.2 536.4 540.7 6137.9 6169.5 6212.9 6266.3 6280.5 6257.6 6260.6 6316.5 6329.0 6368.7 5205.9 5231.7 5240.4 5276.2 5321.8 4866.4 4947.2 5217.5 5252.6 5305.2 4958.9 4960.3 5036.3 5037.6 5048.3 4999.7 5011.7 5033.4 5046.2 5050.6 4457.3 4458.4 4466.4 4501.2 4509.7 6140.0 6195.1 6207.3 6239.3 6258.4 5704.5 5722.2 5734.6 5745.7 5777.1 6537.9 6542.7 6564.6 6569.5 6672.2 4764.1 4823.3 4839.4 4845.9 4875.3 6905.4 6940.7 6967.4 6985.8 6998.0 4984.4 5045.3 5049.0 5074.9 5085.2 4978.0 4995.5 5042.6 5051.5 5084.4 5716.9 5736.3 5743.1 5750.1 5775.4 6541.8 6655.4 6656.5 6693.6 6697.3 1633.4 1641.2 1677.0 1683.3 1771.5 1210.8 1226.7 1246.0 1258.6 1263.8 5630.5 5760.6 5770.5 5784.7 5821.2 6516.5 6553.8 6668.5 6739.8 6743.8 5796.9 5835.9 5848.1 5852.2 5884.4 3573.9 3598.7 3609.5 3611.2 3623.9 6450.3 6490.4 6498.2 6579.0 6612.0 6692.0 6911.4 6930.3 6953.9 6964.0 6530.6 6534.9 6567.7 6590.7 6650.7 6680.7 6743.9 6747.7 6749.1 6783.9 6646.2 6679.4 6688.0 6729.5 6766.6 6571.0 6609.0 6616.3 6619.4 6626.3 5013.0 5020.6 5026.6 5047.4 5069.4 6731.7 6752.2 6763.2 6777.4 6793.5 6520.5 6531.0 6539.6 6584.0 6673.1 6295.7 6495.8 6587.6 6607.6 6648.0 6104.8 6385.4 6421.8 6450.7 6494.1 6462.7 6498.0 6517.3 6594.9 6605.2 6332.9 6339.4 6372.6 6374.6 6386.3 05:52:11 INFO - PID 3785 | 05:52:11 INFO - PID 3785 | [#5] yui.html Cycles:180 Average:25577.57 Median:20774.84 stddev:36302.72 (174.7%) stddev-sans-first:36357.26 05:52:11 INFO - PID 3785 | Values: 898.2 901.1 913.3 922.7 922.7 18719.3 19323.4 19364.5 19381.4 19435.2 17603.1 17766.5 17998.1 18057.9 18109.0 935.5 954.4 957.7 964.8 966.9 986.7 987.7 989.0 990.7 991.0 973.5 1011.2 1011.3 1014.7 1021.0 980.5 992.7 993.7 994.0 995.5 745.5 749.9 750.0 751.1 753.6 19464.7 20174.2 20330.0 20362.9 20407.6 21425.7 21887.1 21948.7 22141.7 22339.6 16469.6 16676.8 16821.6 17035.0 17197.2 3770.5 3779.8 3783.5 3797.2 3804.7 103523.5 142282.0 146912.3 149343.0 150838.2 17322.0 17407.8 17591.8 17683.2 17726.1 31356.5 31988.5 32015.7 32098.8 32130.7 8475.2 8721.3 8864.6 8883.9 8943.8 4146.3 4223.2 4224.7 4226.6 4231.5 5358.5 5368.1 5436.9 5445.2 5450.9 1048.5 1065.1 1089.6 1091.0 1092.7 683.2 695.4 704.6 705.6 714.7 12377.3 12456.7 12525.6 12811.6 12853.8 695.2 703.6 710.2 712.0 714.0 5646.0 5662.4 5710.9 5742.3 5748.3 125995.0 126337.0 129841.0 130476.0 135013.0 81252.0 108689.0 110971.4 112084.0 114539.4 99345.0 101055.5 101988.5 102414.0 105899.0 12681.7 12971.4 13067.8 13069.6 13150.2 42551.4 42718.1 43117.1 43842.3 45836.0 57949.4 59599.7 61194.7 79012.6 83757.0 19388.5 19402.8 19575.1 20112.6 20148.6 20767.9 21009.7 21911.8 22001.7 22369.1 18785.4 19075.7 19117.6 20781.8 21099.5 18208.5 18459.9 18673.6 19506.4 19512.6 16569.4 16815.8 16843.6 16952.7 19612.6 12720.2 13069.7 13164.5 13167.8 13297.0 15163.9 15270.2 15768.9 15814.5 15826.2 05:52:11 INFO - PID 3785 | -------- Summary: end -------- 05:52:11 INFO - PID 3785 | 05:52:12 INFO - TEST-INFO | 3785: exit 0 05:52:12 INFO - TEST-OK | dromaeo_css | took 1117411ms 05:52:12 INFO - TEST-START | kraken 05:52:12 INFO - Initialising browser for kraken test... 05:52:12 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:60741/getInfo.html -profile /tmp/tmpZ1O8Vp/profile 05:52:12 INFO - TEST-INFO | started process 4182 (/builds/slave/test/build/application/firefox/firefox http://localhost:60741/getInfo.html) 05:52:18 INFO - TEST-INFO | 4182: exit 0 05:52:18 INFO - Browser initialized. 05:52:18 INFO - Running cycle 1/1 for kraken test... 05:52:18 INFO - Using env: {'DISPLAY': ':0', 05:52:18 INFO - 'HOME': '/home/cltbld', 05:52:18 INFO - 'JSGC_DISABLE_POISONING': '1', 05:52:18 INFO - 'LANG': 'en_US.UTF-8', 05:52:18 INFO - 'LANGUAGE': 'en_US:en', 05:52:18 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 05:52:18 INFO - 'LOGNAME': 'cltbld', 05:52:18 INFO - 'MAIL': '/var/mail/cltbld', 05:52:18 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:52:18 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 05:52:18 INFO - 'MOZ_ACCELERATED': '1', 05:52:18 INFO - 'MOZ_CRASHREPORTER': '1', 05:52:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:52:18 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 05:52:18 INFO - 'MOZ_NO_REMOTE': '1', 05:52:18 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:52:18 INFO - 'MOZ_WEBRENDER': '1', 05:52:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:52:18 INFO - 'NO_EM_RESTART': '1', 05:52:18 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:52:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:52:18 INFO - 'PWD': '/builds/slave/test', 05:52:18 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:52:18 INFO - 'RUST_BACKTRACE': 'full', 05:52:18 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 05:52:18 INFO - 'SHELL': '/bin/bash', 05:52:18 INFO - 'SHLVL': '1', 05:52:18 INFO - 'TERM': 'linux', 05:52:18 INFO - 'TMOUT': '86400', 05:52:18 INFO - 'USER': 'cltbld', 05:52:18 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1504787535.681553-1137939653', 05:52:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:52:18 INFO - '_': '/tools/buildbot/bin/python'} 05:52:18 INFO - TEST-INFO | started process 4394 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpZ1O8Vp/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 05:52:19 INFO - PID 4394 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 05:52:19 INFO - PID 4394 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 05:52:19 INFO - PID 4394 | 05:52:19 INFO - PID 4394 | (/builds/slave/test/build/application/firefox/firefox:4455): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 05:52:19 INFO - PID 4394 | 05:52:30 INFO - PID 4394 | RSS: Main: 209756160 05:52:30 INFO - PID 4394 | 05:52:32 INFO - PID 4394 | Cycle 1(1): loaded http://localhost:60741/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:60741/tests/kraken/driver.html?testName=audio-beat-detection) 05:52:33 INFO - PID 4394 | RSS: Main: 215588864 05:52:33 INFO - PID 4394 | 05:52:36 INFO - PID 4394 | Cycle 1(1): loaded http://localhost:60741/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:60741/tests/kraken/driver.html?testName=audio-dft) 05:52:37 INFO - PID 4394 | RSS: Main: 218202112 05:52:37 INFO - PID 4394 | 05:52:40 INFO - PID 4394 | Cycle 1(1): loaded http://localhost:60741/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:60741/tests/kraken/driver.html?testName=audio-fft) 05:52:40 INFO - PID 4394 | RSS: Main: 212713472 05:52:40 INFO - PID 4394 | 05:52:42 INFO - PID 4394 | Cycle 1(1): loaded http://localhost:60741/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:60741/tests/kraken/driver.html?testName=audio-oscillator) 05:52:43 INFO - PID 4394 | RSS: Main: 212860928 05:52:43 INFO - PID 4394 | 05:52:45 INFO - PID 4394 | Cycle 1(1): loaded http://localhost:60741/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:60741/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:52:46 INFO - PID 4394 | RSS: Main: 213225472 05:52:46 INFO - PID 4394 | 05:52:49 INFO - PID 4394 | Cycle 1(1): loaded http://localhost:60741/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:60741/tests/kraken/driver.html?testName=imaging-darkroom) 05:52:50 INFO - PID 4394 | RSS: Main: 215339008 05:52:50 INFO - PID 4394 | 05:52:53 INFO - PID 4394 | Cycle 1(1): loaded http://localhost:60741/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:60741/tests/kraken/driver.html?testName=imaging-desaturate) 05:52:54 INFO - PID 4394 | RSS: Main: 214597632 05:52:54 INFO - PID 4394 | 05:52:57 INFO - PID 4394 | Cycle 1(1): loaded http://localhost:60741/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:60741/tests/kraken/driver.html?testName=json-parse-financial) 05:52:57 INFO - PID 4394 | RSS: Main: 216956928 05:52:57 INFO - PID 4394 | 05:52:59 INFO - PID 4394 | Cycle 1(1): loaded http://localhost:60741/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:60741/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:53:00 INFO - PID 4394 | RSS: Main: 216248320 05:53:00 INFO - PID 4394 | 05:53:01 INFO - PID 4394 | Cycle 1(1): loaded http://localhost:60741/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:60741/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:53:02 INFO - PID 4394 | RSS: Main: 212770816 05:53:02 INFO - PID 4394 | 05:53:04 INFO - PID 4394 | Cycle 1(1): loaded http://localhost:60741/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:60741/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:53:05 INFO - PID 4394 | RSS: Main: 213422080 05:53:05 INFO - PID 4394 | 05:53:08 INFO - PID 4394 | Cycle 1(1): loaded http://localhost:60741/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:60741/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:53:08 INFO - PID 4394 | RSS: Main: 213573632 05:53:08 INFO - PID 4394 | 05:53:11 INFO - PID 4394 | Cycle 1(1): loaded http://localhost:60741/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:60741/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:53:12 INFO - PID 4394 | RSS: Main: 213868544 05:53:12 INFO - PID 4394 | 05:53:14 INFO - PID 4394 | Cycle 1(1): loaded http://localhost:60741/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:60741/tests/kraken/driver.html?testName=ai-astar) 05:53:14 INFO - PID 4394 | RSS: Main: 215867392 05:53:14 INFO - PID 4394 | 05:53:14 INFO - PID 4394 | __start_tp_report 05:53:14 INFO - PID 4394 | _x_x_mozilla_page_load 05:53:14 INFO - PID 4394 | _x_x_mozilla_page_load_details 05:53:14 INFO - PID 4394 | |i|pagename|runs| 05:53:14 INFO - PID 4394 | |0;ai-astar;114;118;119;119;112;109;111;106;113;110 05:53:14 INFO - PID 4394 | |1;audio-beat-detection;121;127;117;117;117;123;120;118;146;120 05:53:14 INFO - PID 4394 | |2;audio-dft;162;163;162;167;164;166;169;173;166;168 05:53:14 INFO - PID 4394 | |3;audio-fft;75;77;78;78;73;87;85;81;87;77 05:53:14 INFO - PID 4394 | |4;audio-oscillator;80;80;79;80;79;89;81;86;80;89 05:53:14 INFO - PID 4394 | |5;imaging-gaussian-blur;115;115;113;120;115;116;115;117;115;117 05:53:14 INFO - PID 4394 | |6;imaging-darkroom;149;149;149;147;150;150;157;149;150;152 05:53:14 INFO - PID 4394 | |7;imaging-desaturate;97;94;96;94;98;95;94;94;102;96 05:53:14 INFO - PID 4394 | |8;json-parse-financial;52;51;49;58;57;53;52;54;52;51 05:53:14 INFO - PID 4394 | |9;json-stringify-tinderbox;46;40;39;45;39;41;38;46;41;46 05:53:14 INFO - PID 4394 | |10;stanford-crypto-aes;78;83;83;74;84;78;75;76;76;81 05:53:14 INFO - PID 4394 | |11;stanford-crypto-ccm;134;122;128;120;121;126;134;121;121;124 05:53:14 INFO - PID 4394 | |12;stanford-crypto-pbkdf2;174;170;174;172;172;176;166;174;169;170 05:53:14 INFO - PID 4394 | |13;stanford-crypto-sha256-iterative;75;63;62;62;64;66;63;64;67;69 05:53:14 INFO - PID 4394 | __end_tp_report 05:53:14 INFO - PID 4394 | __start_cc_report 05:53:14 INFO - PID 4394 | _x_x_mozilla_cycle_collect,936 05:53:14 INFO - PID 4394 | __end_cc_report 05:53:14 INFO - PID 4394 | __startTimestamp1504788794603__endTimestamp 05:53:14 INFO - PID 4394 | 05:53:14 INFO - PID 4394 | ------- Summary: start ------- 05:53:14 INFO - PID 4394 | Number of tests: 14 05:53:14 INFO - PID 4394 | 05:53:14 INFO - PID 4394 | [#0] ai-astar Cycles:10 Average:113.10 Median:113.50 stddev:4.43 (3.9%) stddev-sans-first:4.69 05:53:14 INFO - PID 4394 | Values: 114.0 118.0 119.0 119.0 112.0 109.0 111.0 106.0 113.0 110.0 05:53:14 INFO - PID 4394 | 05:53:14 INFO - PID 4394 | [#1] audio-beat-detection Cycles:10 Average:122.60 Median:120.50 stddev:8.81 (7.3%) stddev-sans-first:9.32 05:53:14 INFO - PID 4394 | Values: 121.0 127.0 117.0 117.0 117.0 123.0 120.0 118.0 146.0 120.0 05:53:14 INFO - PID 4394 | 05:53:14 INFO - PID 4394 | [#2] audio-dft Cycles:10 Average:166.00 Median:166.50 stddev:3.46 (2.1%) stddev-sans-first:3.36 05:53:14 INFO - PID 4394 | Values: 162.0 163.0 162.0 167.0 164.0 166.0 169.0 173.0 166.0 168.0 05:53:14 INFO - PID 4394 | 05:53:14 INFO - PID 4394 | [#3] audio-fft Cycles:10 Average:79.80 Median:79.50 stddev:4.98 (6.3%) stddev-sans-first:4.97 05:53:14 INFO - PID 4394 | Values: 75.0 77.0 78.0 78.0 73.0 87.0 85.0 81.0 87.0 77.0 05:53:14 INFO - PID 4394 | 05:53:14 INFO - PID 4394 | [#4] audio-oscillator Cycles:10 Average:82.30 Median:80.50 stddev:4.06 (5.0%) stddev-sans-first:4.22 05:53:14 INFO - PID 4394 | Values: 80.0 80.0 79.0 80.0 79.0 89.0 81.0 86.0 80.0 89.0 05:53:14 INFO - PID 4394 | 05:53:14 INFO - PID 4394 | [#5] imaging-gaussian-blur Cycles:10 Average:115.80 Median:115.50 stddev:1.87 (1.6%) stddev-sans-first:1.96 05:53:14 INFO - PID 4394 | Values: 115.0 115.0 113.0 120.0 115.0 116.0 115.0 117.0 115.0 117.0 05:53:14 INFO - PID 4394 | 05:53:14 INFO - PID 4394 | [#6] imaging-darkroom Cycles:10 Average:150.20 Median:150.00 stddev:2.70 (1.8%) stddev-sans-first:2.83 05:53:14 INFO - PID 4394 | Values: 149.0 149.0 149.0 147.0 150.0 150.0 157.0 149.0 150.0 152.0 05:53:14 INFO - PID 4394 | 05:53:14 INFO - PID 4394 | [#7] imaging-desaturate Cycles:10 Average:96.00 Median:95.50 stddev:2.54 (2.7%) stddev-sans-first:2.67 05:53:14 INFO - PID 4394 | Values: 97.0 94.0 96.0 94.0 98.0 95.0 94.0 94.0 102.0 96.0 05:53:14 INFO - PID 4394 | 05:53:14 INFO - PID 4394 | [#8] json-parse-financial Cycles:10 Average:52.90 Median:52.50 stddev:2.77 (5.3%) stddev-sans-first:2.92 05:53:14 INFO - PID 4394 | Values: 52.0 51.0 49.0 58.0 57.0 53.0 52.0 54.0 52.0 51.0 05:53:14 INFO - PID 4394 | 05:53:14 INFO - PID 4394 | [#9] json-stringify-tinderbox Cycles:10 Average:42.10 Median:43.00 stddev:3.28 (7.6%) stddev-sans-first:3.16 05:53:14 INFO - PID 4394 | Values: 46.0 40.0 39.0 45.0 39.0 41.0 38.0 46.0 41.0 46.0 05:53:14 INFO - PID 4394 | 05:53:14 INFO - PID 4394 | [#10] stanford-crypto-aes Cycles:10 Average:78.80 Median:79.50 stddev:3.68 (4.6%) stddev-sans-first:3.89 05:53:14 INFO - PID 4394 | Values: 78.0 83.0 83.0 74.0 84.0 78.0 75.0 76.0 76.0 81.0 05:53:14 INFO - PID 4394 | 05:53:14 INFO - PID 4394 | [#11] stanford-crypto-ccm Cycles:10 Average:125.10 Median:125.00 stddev:5.32 (4.3%) stddev-sans-first:4.57 05:53:14 INFO - PID 4394 | Values: 134.0 122.0 128.0 120.0 121.0 126.0 134.0 121.0 121.0 124.0 05:53:14 INFO - PID 4394 | 05:53:14 INFO - PID 4394 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:171.70 Median:173.00 stddev:2.98 (1.7%) stddev-sans-first:3.05 05:53:14 INFO - PID 4394 | Values: 174.0 170.0 174.0 172.0 172.0 176.0 166.0 174.0 169.0 170.0 05:53:14 INFO - PID 4394 | 05:53:14 INFO - PID 4394 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.50 Median:65.00 stddev:4.03 (6.2%) stddev-sans-first:2.40 05:53:14 INFO - PID 4394 | Values: 75.0 63.0 62.0 62.0 64.0 66.0 63.0 64.0 67.0 69.0 05:53:14 INFO - PID 4394 | -------- Summary: end -------- 05:53:14 INFO - PID 4394 | 05:53:15 INFO - PID 4394 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 05:53:15 INFO - TEST-INFO | 4394: exit 0 05:53:15 INFO - TEST-OK | kraken | took 63690ms 05:53:15 INFO - SUITE-END | took 1181s 05:53:15 INFO - Completed test suite (00:19:41) 05:53:15 INFO - javascript benchmark 05:53:15 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8721.83066714372, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3281.524178994046, 3289.0954987378477, 3316.2041110133573, 3319.784214026088, 3329.916752081196, 6275.4038366355235, 6422.967885160573, 6495.8051258462265, 6496.935030649698, 6503.609783412999, 6331.651759153245, 6373.203349581291, 6389.680515974194, 6391.0413437984325, 6407.935920640793, 5782.999999999989, 5788.392218817026, 5811.721421287331, 5842.269716285465, 5891.204687367193, 5874.206982057432, 5896.469317761401, 5901.026330655458, 5908.202392676976, 5939.88120237595, 5817.883642327172, 5848.561357898154, 5851.561132915027, 5869.295684517864, 5941.0, 5949.315828679714, 5954.434328738763, 5958.97020514899, 5976.581639285252, 5997.070454079581, 5849.005669036274, 5889.057750759858, 5965.642061476326, 5974.40255974399, 5998.430149135826, 6181.907271390933, 6370.044493325991, 6372.426481616631, 6454.741810327581, 6552.180977377828, 6674.766383176614, 6730.495212859055, 6743.59538427691, 6777.322267773233, 6992.965035174842, 6951.617661028641, 6957.408620267283, 7042.683079261395, 7045.471589630798, 7056.329648683367, 6139.969300153471, 6402.103705481236, 6430.260520040161, 6490.2536208335705, 6512.837179070561, 6864.450843932474, 6970.721171153198, 7003.824904377329, 7055.118110236221, 7078.433725301964, 6703.899441508382, 6714.52998290115, 6716.6977486012265, 6717.093192419059, 6725.159355080615, 6725.2602213756445, 6868.141482314711, 6879.346462086094, 6881.827954301082, 6881.8967715484305, 6785.1518560179975, 6808.285130061371, 6829.112215411965, 6879.0, 6879.6560171991205, 6820.829479262959, 6834.384905358442, 6897.310268973062, 6909.170899491993, 6910.136232970879, 5309.2301616266195, 5370.489803468664, 5373.0, 5400.135978243462, 5412.675239485643, 4754.405699287589, 4781.545753153445, 4795.520447955177, 4814.15752243356, 4848.272759086165, 5951.672658003765, 6017.187679663277, 6031.638101713911, 6039.396060394013, 6040.728167232485, 6881.552699074544, 6891.620960847101, 6982.860342793116, 6983.8603227935155, 6996.8950465743055, 5810.389909059572, 5865.589408741433, 5887.35239123696, 5891.49922256613, 5979.910301345483, 5850.473457388855, 5852.239208902815, 5857.4435428634215, 5868.647881127146, 5873.765049397976, 6623.370779775913, 6650.268470468244, 6708.094407255056, 6709.597424154566, 6730.46156307504, 6331.449264723838, 6681.766138185335, 6735.898961515483, 6755.560888542229, 6878.002689609979, 6477.708503117466, 6487.18910136233, 6507.609543427409, 6516.609003459618, 6552.6068435894, 6636.13730215069, 6815.488838337045, 6841.17905851301, 6852.794416167523, 6892.483063770337, 6927.960805879158, 6991.265917078634, 6998.965005174941, 6999.965000174967, 7014.158301003708, 6595.967020164869, 6624.4700423965005, 6627.0, 6713.765018224339, 6714.731410743516, 6420.839479013062, 6533.444657204191, 6545.0, 6592.538522303393, 6736.191657001191, 6076.848078798065, 6466.644334561644, 6469.5794773339585, 6477.902831457626, 6519.934800651933, 6754.358335958076, 6777.051212830306, 6795.93204067953, 6849.24658287578, 6872.518923675295, 6272.717727702173, 6482.837929051811, 6499.911054888919, 6591.6374599397495, 6598.934010659832], "value": 6247.092786898008, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [846.3736834742285, 860.4407135362014, 879.961645258595, 889.9822003559933, 891.104440037762, 40066.799666001665, 41193.58806411935, 41415.792921035354, 41426.17147657045, 41479.0, 37890.0, 41020.0, 41857.0, 41936.58063419365, 41951.58048419515, 7747.41894357924, 7870.921290787076, 7872.488288261274, 7875.724349647763, 7887.0, 7659.157492675801, 7732.922670773277, 7807.297343239107, 7813.296803287703, 7829.0, 7777.261160189773, 7812.765617031499, 7855.764327070197, 7856.1751016143335, 7858.174891636381, 7722.227777222289, 7762.262585054439, 7776.883346749803, 7822.37421006321, 7824.5696486693205, 7733.110692270348, 7800.375969922393, 7804.219578042207, 7809.414293927921, 7812.06255249368, 38433.039174020596, 38699.41950870739, 38720.806395968124, 38722.41916371257, 38732.612673873184, 3488.5290485784603, 3580.051286409104, 3580.5524309461316, 3581.94627080594, 3589.8743543976097, 31254.0, 31308.0, 31360.843195784102, 31422.842885785423, 31428.52857207144, 5134.820281290137, 5168.948310516885, 5194.11700010999, 5208.427073021964, 5282.735863206863, 189969.0, 190446.9999999986, 190772.0, 190835.0, 190957.0, 46842.5315746845, 47405.0, 47568.2864757022, 47573.28640070402, 47727.28409073866, 49238.261426078636, 49345.0, 49425.75287123541, 49443.258351124765, 52194.47805521972, 16002.199890005453, 16141.273642686107, 16229.0, 16259.674806504041, 16263.59341016484, 13167.473301068041, 13348.73302533944, 13365.732685346238, 13373.39819708096, 13386.19682819034, 14043.78934315986, 14112.71774564503, 14218.431262749582, 14360.0, 14624.19566923808, 5610.607257492018, 5680.6591604503865, 5736.139579063174, 5769.0, 5770.480656740913, 970.315927271272, 1009.0, 1009.8788145422596, 1018.2414101494286, 1041.1358572384902, 23241.883790580938, 23595.41011474727, 23646.408839779142, 23794.7620523796, 23899.52200955971, 1103.61925135828, 1120.3334016260312, 1120.4957769003981, 1132.8357388178833, 1139.5669645534645, 12113.33376664292, 12118.819007442418, 12206.450709718085, 12431.94328482089, 12450.564230251897, 238629.0, 238780.0, 239506.0, 240200.0, 240431.0, 44856.0, 45158.0, 51899.74050129725, 52264.0, 52497.2125418119, 1424.3020919749456, 1446.4286606790258, 1447.6163816588612, 1449.0, 1477.8004969329004, 26765.46469070569, 27322.590161147997, 27739.306517337227, 27770.722292776813, 27817.860910695315, 48535.271970921174, 49076.26385604147, 49130.0, 49341.013179736925, 49382.50617493779, 212395.0, 213490.0, 214068.0, 214447.0, 215107.0, 33805.32389352249, 33850.0, 33923.0, 36052.45921311235, 37249.81375093107, 44892.0, 45379.0, 45802.77098614485, 45895.0, 46290.30564541467, 33130.50304245487, 33611.327773444886, 34214.48678269778, 34299.82850085734, 35950.64049359473, 34944.475832863034, 35324.82337588296, 35808.820955896095, 35969.820150899075, 36366.27267454689, 38250.0, 40284.194316112924, 40327.0, 40340.394894076024, 40540.797296013334, 40458.595414045485, 41142.0, 41605.0, 41918.0, 42098.158036839704, 25018.37454063663, 25150.11974580881, 25540.509616246196, 25721.871390642926, 25886.870565647052], "value": 17791.50113631048, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [554.2905081495687, 572.9942700572993, 578.9363170051291, 583.9854003649909, 592.8636413624865, 21813.0, 22195.334139975806, 22395.888020559916, 22647.660285095717, 22703.20538781143, 24775.256742297715, 25101.49797004059, 25144.62283065751, 26118.0, 26402.6039609406, 6738.9326106739045, 6871.03805467235, 6906.482013848957, 6907.447404207652, 6913.170419549661, 6845.212800527928, 6879.1057162568795, 6885.552439091468, 6886.104806375164, 6889.931100689004, 6377.457916077139, 6572.704228309737, 6663.466922646201, 6677.599344039373, 6684.097646817666, 6566.441852442525, 6594.241662208811, 6608.4052435281055, 6628.43658289046, 6660.067590537328, 6072.423119803611, 6148.200733904564, 6148.446639802394, 6150.477209437203, 6164.630122192638, 28236.435271294664, 29075.0, 29448.0, 29630.111096667133, 29635.0, 1981.8513611479198, 2038.4088614301834, 2041.2651445479617, 2045.0183911722308, 2045.8261047810954, 19745.901270493698, 20083.096260668306, 20089.397318080333, 20149.194032238694, 20177.495562610904, 2348.225085721725, 2382.487765130505, 2436.683231179953, 2442.792362649177, 2448.6939132608422, 122242.3887880555, 131805.0, 134018.0, 139234.0, 139565.30217348848, 14669.401893695569, 14674.926625367018, 14782.48261310849, 15012.0, 15082.472113475975, 23169.189078382176, 23327.18354857572, 23369.88315058448, 23390.18134365289, 23464.178753743538, 10378.740531486774, 10410.895891040993, 10497.790044199073, 10519.894801052045, 10523.894761052445, 9635.614575417045, 9899.60401583943, 9934.45360505179, 9941.0, 9965.701028969142, 9061.864072038925, 9117.863232051524, 9278.11857873501, 9360.0, 9424.811503769886, 2772.487089888337, 2786.749192572678, 2819.16834533813, 2838.176928690698, 2918.54762511811, 926.7127190570944, 940.7883226274171, 954.1365064616533, 990.1880458024351, 1004.1264100232844, 19409.32067377635, 19724.211031558865, 19755.703664445045, 23908.0, 23972.160974365812, 1008.3546530220665, 1057.2493529594074, 1057.5611121384693, 1067.962621308266, 1084.0460394852637, 10403.907589702972, 11261.718457038476, 11384.37385943781, 11413.942930285295, 11694.590689325832, 529239.0, 531218.0, 532798.0, 534058.0, 539920.0, 117691.0, 119166.4041679786, 124117.0, 126986.0, 127251.36374318069, 1228.6252692928742, 1256.2650849253107, 1289.2410242081953, 1292.3651580750359, 1297.1698113207556, 18554.0, 18555.0, 18611.627767444304, 18735.90632046831, 18878.811211887707, 59526.10710839427, 60827.39172608217, 61111.38888611057, 61178.388216117266, 61855.76288474295, 75051.0, 75378.0, 75447.62276188584, 75677.24322756923, 76351.0, 20228.292009779587, 20328.186872524933, 20558.38324850257, 20614.58770824545, 20643.896780516003, 22713.432164196027, 22914.083436662346, 23193.0, 23207.535849283257, 23617.881910591026, 18603.0, 18759.718604220674, 18804.811951880307, 18837.717434238224, 19017.524561886064, 15966.760498592297, 16007.199640018185, 16143.273552690393, 16263.26815293291, 16328.510144695678, 17943.282268709107, 18529.81470185281, 18788.812111878706, 18912.62174756525, 18958.336458223897, 19691.70462443036, 19848.60302793965, 20006.19975201034, 20064.197432103138, 20134.892580907606, 18157.54606134857, 18292.085395729893, 18503.0, 18512.16695248744, 18709.15808788635], "value": 12437.848670079895, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1309.0836414509843, 1345.3206130903893, 1348.8313960754906, 1359.4766015084201, 1362.0397619678129, 14427.855721442782, 15137.31882065307, 15148.772768408455, 15149.545513634595, 15158.317875695606, 14838.925805370985, 15030.624234394145, 15085.094894306349, 15164.620884477894, 15201.771973420407, 2661.7870570354326, 2803.257136858734, 2808.326001759573, 2808.7191280871953, 2811.0301945828655, 2705.9864700676467, 2737.86310684465, 2747.5741260104614, 2750.051232324845, 2752.9862350688218, 2669.599560065986, 2782.9721702783017, 2801.5937689035077, 2805.6492938382703, 2805.9298517537122, 2605.7915366770612, 2643.458091091322, 2668.8932442702267, 2675.3044208505735, 2679.0757188769844, 2485.155047283932, 2498.7126480454617, 2641.300055485298, 2701.74333438325, 2706.336947447868, 14709.632259193499, 14741.410343586243, 14885.92557037208, 14917.701645967129, 14940.551783446404, 7598.126215485179, 7912.960435197845, 7915.287624113857, 7915.525068495908, 7917.129115797257, 5321.547668448186, 5424.349078110613, 5498.477644623755, 5501.669899806025, 5584.748686309126, 1904.6002037432934, 1943.6307101650782, 1957.1975490049012, 1966.9311574094982, 1986.4040787763759, 25322.620160697603, 25468.36329091769, 25684.48631027388, 25715.61426578603, 25772.355691107685, 6452.903206451907, 6638.933610663928, 6688.86622267552, 6754.594724316458, 6939.861202775916, 16808.411705590246, 16847.326106955585, 16864.662706745796, 16878.409255676237, 16884.577885552713, 7414.6292685365515, 7516.285952834472, 7549.962250188714, 7585.089789225328, 7590.430717696195, 6396.136521569529, 7008.404285635676, 7065.717371305193, 7065.823354416078, 7070.434365250768, 5449.427810079964, 5692.516136128393, 5692.57305702074, 5711.3146422428745, 5721.685307308055, 1782.9821701782816, 1807.2951548895942, 1826.8538516918616, 1837.4212123181155, 1839.383806424836, 902.2601466797163, 925.1812146250483, 931.4737173496952, 936.5317341329335, 944.1974321826392, 7460.664270107858, 7518.0, 7589.7343592975085, 7608.087029556488, 7732.342750866127, 694.1254019934919, 710.9751158709421, 717.2325611595543, 720.9243029481934, 724.6376811594203, 4435.711678740871, 4997.525235102659, 5243.554297884646, 5280.102382594968, 5402.891942161214, 20109.59780804405, 20643.0, 21034.158633654482, 21183.470413239793, 21725.456863578536, 20932.790672093084, 21016.579668406852, 21085.68371474429, 21231.256906008217, 21377.893110534347, 10672.199585031154, 11208.551657933755, 11514.309141451373, 11606.071514279007, 11612.419379031184, 8445.239928406472, 8563.871541927001, 8842.0, 8976.0, 8977.416467929565, 18037.09814509243, 18225.726614100535, 18488.075596219867, 18495.630087398447, 18586.535336616693, 18882.622347552697, 18891.43325700231, 18955.810441895406, 19000.809991899903, 19466.707999379738, 12040.819387709367, 12134.939325303318, 12170.14808963364, 12193.51225950952, 12275.324857132944, 13422.194668319931, 13689.178649281075, 13715.314234288046, 13747.106438081493, 13792.241426721626, 11005.394703291397, 11096.500657470271, 11120.388378639253, 11346.886531134913, 11458.713532161762, 10951.726206844893, 11190.552377904793, 11242.494087765906, 11313.20807543464, 11545.82681259765, 9946.602135914482, 10111.191104711752, 10131.7467063324, 10145.08694217524, 10168.0, 7425.888611670937, 7434.256574342522, 7511.849763004819, 7583.848323033619, 7677.0, 7522.134954480305, 7565.773026809205, 7594.31651151399, 7658.119316278477, 7726.459147859596], "value": 6644.624501363253, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [513.9640225184236, 519.3145048535932, 532.2202972645074, 536.364408176311, 540.6945076032042, 6137.938620613792, 6169.537284703642, 6212.9068063979075, 6266.342034086418, 6280.497560195185, 6257.62454252744, 6260.591366942435, 6316.494680425566, 6329.0, 6368.713407896645, 5205.895882082356, 5231.712255825928, 5240.423553409122, 5276.182191760264, 5321.787128514865, 4866.391701037371, 4947.219001159579, 5217.530422261995, 5252.579793616521, 5305.177697456881, 4958.925616115761, 4960.280759289901, 5036.294918711384, 5037.622178336621, 5048.29323894655, 4999.675086102167, 5011.724355160465, 5033.421156567005, 5046.19012645454, 5050.595952323823, 4457.309117086825, 4458.3535387368975, 4466.441694788152, 4501.23479008566, 4509.692189265109, 6139.969300153515, 6195.097088834006, 6207.348228435994, 6239.251289845202, 6258.435391152212, 5704.4865962063195, 5722.226109905942, 5734.627249228787, 5745.6552606843725, 5777.104548794943, 6537.869242615121, 6542.705578248942, 6564.573302735354, 6569.50728695345, 6672.232693240291, 4764.118638051971, 4823.324734537167, 4839.419269687624, 4845.927311090337, 4875.34182885313, 6905.413039891666, 6940.6529673516125, 6967.442604591621, 6985.755498557526, 6998.0, 4984.37695288089, 5045.344105266292, 5049.015441988777, 5074.944647417802, 5085.237214417867, 4978.0, 4995.500449954975, 5042.621803364762, 5051.494850514919, 5084.3898732152375, 5716.885662286814, 5736.340320863071, 5743.138529220567, 5750.137479378043, 5775.364709881909, 6541.771038013647, 6655.401013908675, 6656.500762442836, 6693.564918280296, 6697.263301036882, 1633.3629884345114, 1641.1958140511063, 1676.9916150419415, 1683.335082642374, 1771.5393997560727, 1210.8062709966364, 1226.693326668333, 1245.9907474945323, 1258.6098309524077, 1263.7914744067125, 5630.5214056805635, 5760.567957403212, 5770.451807078321, 5784.71076446176, 5821.243238378984, 6516.543841931114, 6553.772816533309, 6668.499862510331, 6739.797806065826, 6743.831404214836, 5796.913046304308, 5835.912461313083, 5848.122781582797, 5852.209951656557, 5884.440978107073, 3573.874914377984, 3598.712103031751, 3609.4946707460977, 3611.1763559402334, 3623.873164439232, 6450.290468048511, 6490.350964903472, 6498.155239818793, 6579.0, 6611.966940165268, 6691.966540167268, 6911.375826680726, 6930.272321406281, 6953.89569156463, 6964.0, 6530.575512591667, 6534.941493429555, 6567.704453299709, 6590.736370545124, 6650.700718467584, 6680.6993685283305, 6743.9325606743305, 6747.730090796314, 6749.0888730020815, 6783.864322713617, 6646.244871647504, 6679.398854103154, 6688.030235615711, 6729.461643068641, 6766.560173588701, 6570.967145164243, 6609.0, 6616.338366163345, 6619.371159739817, 6626.271110177973, 5012.974935125301, 5020.6234532410945, 5026.572741317029, 5047.419546752024, 5069.417017043087, 6731.663416829237, 6752.189737231367, 6763.222229443677, 6777.423918966943, 6793.490488213502, 6520.543561950618, 6530.967345163243, 6539.607623542603, 6584.0, 6673.132492775908, 6295.653739044214, 6495.805125846232, 6587.571807832475, 6607.57050791697, 6647.966760166361, 6104.847378815388, 6385.393387628168, 6421.839454013687, 6450.741970321134, 6494.149167487243, 6462.741490340334, 6498.0, 6517.348265173445, 6594.868102638016, 6605.207375115017, 6332.936670633235, 6339.397757213057, 6372.617642941438, 6374.649394283359, 6386.297507274103], "value": 5089.519303287477, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [898.1961144775427, 901.0538934119176, 913.3058875254804, 922.6862866625346, 922.709346555835, 18719.3448229312, 19323.420297391065, 19364.51588710283, 19381.418557443263, 19435.222591096357, 17603.11984400778, 17766.46700598981, 17998.100094995232, 18057.909710451462, 18109.0, 935.5228833295017, 954.413035982869, 957.6743907071594, 964.7973925475659, 966.8549717542389, 986.7237173591406, 987.669130841169, 989.0159291504963, 990.6681261777313, 991.0386924683046, 973.4791886340809, 1011.2112552209252, 1011.2617789014024, 1014.7361685961666, 1020.9642662506814, 980.5146452506019, 992.687303499399, 993.741627176932, 994.0258546624349, 995.4525011243787, 745.5154149802618, 749.8612756639984, 750.0324581290129, 751.0761763031502, 753.600591686407, 19464.70802937957, 20174.19303227869, 20330.0, 20362.898185509126, 20407.591848162952, 21425.71445713262, 21887.12451501938, 21948.670769938464, 22141.66787498189, 22339.55320893573, 16469.58826029359, 16676.83323166765, 16821.57946051346, 17035.0, 17197.226124824414, 3770.5286839145106, 3779.848806047755, 3783.5459744830523, 3797.2405518896053, 3804.733668643196, 103523.48238258761, 142282.0, 146912.2654386732, 149343.0, 150838.24580877135, 17322.0, 17407.825921740874, 17591.824081759274, 17683.20425580852, 17726.113694315234, 31356.529652055237, 31988.520172197437, 32015.679843201735, 32098.83950580232, 32130.678693213235, 8475.152484751476, 8721.345899057596, 8864.64541418336, 8883.911160888309, 8943.821123577622, 4146.315857883415, 4223.239816832938, 4224.683148763855, 4226.5773422657485, 4231.4710661167355, 5358.517733404013, 5368.087425137736, 5436.864078398072, 5445.237666726662, 5450.8909821803345, 1048.5072016152396, 1065.0574241795912, 1089.5859573362072, 1090.9727256818644, 1092.6558134187705, 683.2040672616446, 695.401954319285, 704.5772536478174, 705.6436499567789, 714.6819665248914, 12377.257364558156, 12456.688582785502, 12525.56160534377, 12811.551595694105, 12853.807192892114, 695.151914664109, 703.5989485993038, 710.1974768511549, 711.975080872167, 713.9857202856018, 5645.991346144201, 5662.405447428043, 5710.914336284959, 5742.253507044057, 5748.310202775694, 125995.0, 126337.0, 129841.0, 130476.0, 135013.0, 81252.0, 108689.0, 110971.44514277538, 112084.0, 114539.42730286295, 99345.0, 101055.4947225274, 101988.49005754924, 102414.0, 105899.0, 12681.682957926125, 12971.41628626733, 13067.803982940453, 13069.607911762663, 13150.21098734079, 42551.3617295747, 42718.14563708646, 43117.13765724605, 43842.342364863915, 45836.0, 57949.420505794405, 59599.70200148971, 61194.694026529585, 79012.60493697495, 83757.0, 19388.515287117934, 19402.805971940663, 19575.119119639367, 20112.597748044664, 20148.59702805965, 20767.8961605191, 21009.684854726886, 21911.780882190975, 22001.66997495007, 22369.105235790386, 18785.436436906915, 19075.713864292324, 19117.61764764669, 20781.792182077985, 21099.472513187295, 18208.544786379916, 18459.892406455656, 18673.626527469103, 19506.414807555797, 19512.609747805247, 16569.42007029748, 16815.831841681425, 16843.578910527336, 16952.745708814626, 19612.60774784468, 12720.173188742701, 13069.738605228033, 13164.539241126515, 13167.802482962954, 13297.0, 15163.938524303192, 15270.236488175324, 15768.918871857872, 15814.525564233092, 15826.208689565245], "value": 9415.339460028063, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1461.8999999999999, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 118.0, 119.0, 119.0, 112.0, 109.0, 111.0, 106.0, 113.0, 110.0], "value": 113.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [121.0, 127.0, 117.0, 117.0, 117.0, 123.0, 120.0, 118.0, 146.0, 120.0], "value": 122.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 163.0, 162.0, 167.0, 164.0, 166.0, 169.0, 173.0, 166.0, 168.0], "value": 166.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 77.0, 78.0, 78.0, 73.0, 87.0, 85.0, 81.0, 87.0, 77.0], "value": 79.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 80.0, 79.0, 80.0, 79.0, 89.0, 81.0, 86.0, 80.0, 89.0], "value": 82.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 115.0, 113.0, 120.0, 115.0, 116.0, 115.0, 117.0, 115.0, 117.0], "value": 115.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 149.0, 149.0, 147.0, 150.0, 150.0, 157.0, 149.0, 150.0, 152.0], "value": 150.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 94.0, 96.0, 94.0, 98.0, 95.0, 94.0, 94.0, 102.0, 96.0], "value": 96.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 51.0, 49.0, 58.0, 57.0, 53.0, 52.0, 54.0, 52.0, 51.0], "value": 52.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [46.0, 40.0, 39.0, 45.0, 39.0, 41.0, 38.0, 46.0, 41.0, 46.0], "value": 42.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 83.0, 83.0, 74.0, 84.0, 78.0, 75.0, 76.0, 76.0, 81.0], "value": 78.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [134.0, 122.0, 128.0, 120.0, 121.0, 126.0, 134.0, 121.0, 121.0, 124.0], "value": 125.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [174.0, 170.0, 174.0, 172.0, 172.0, 176.0, 166.0, 174.0, 169.0, 170.0], "value": 171.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 63.0, 62.0, 62.0, 64.0, 66.0, 63.0, 64.0, 67.0, 69.0], "value": 65.5, "unit": "score"}]}]} 05:53:15 INFO - Return code: 0 05:53:15 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:53:15 INFO - # TBPL SUCCESS # 05:53:15 INFO - Running post-action listener: _package_coverage_data 05:53:15 INFO - Running post-action listener: _resource_record_post_action 05:53:15 INFO - [mozharness: 2017-09-07 12:53:15.899013Z] Finished run-tests step (success) 05:53:15 INFO - Running post-run listener: _resource_record_post_run 05:53:15 INFO - Total resource usage - Wall time: 1198s; CPU: 13.0%; Read bytes: 14299136; Write bytes: 402636800; Read time: 5260; Write time: 411436 05:53:15 INFO - TinderboxPrint: CPU usage
13.3% 05:53:15 INFO - TinderboxPrint: I/O read bytes / time
14,299,136 / 5,260 05:53:15 INFO - TinderboxPrint: I/O write bytes / time
402,636,800 / 411,436 05:53:15 INFO - TinderboxPrint: CPU idle
8,275.8 (86.7%) 05:53:15 INFO - TinderboxPrint: CPU user
1,202.8 (12.6%) 05:53:15 INFO - TinderboxPrint: Swap in / out
0 / 0 05:53:15 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 119365632; Read time: 0; Write time: 104916 05:53:15 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 05:53:15 INFO - run-tests - Wall time: 1182s; CPU: 13.0%; Read bytes: 12554240; Write bytes: 281919488; Read time: 5232; Write time: 306460 05:53:16 INFO - Running post-run listener: _upload_blobber_files 05:53:16 INFO - Blob upload gear active. 05:53:16 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:53:16 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 05:53:16 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'] 05:53:16 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 05:53:16 INFO - (blobuploader) - INFO - Open directory for files ... 05:53:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 05:53:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:53:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:53:18 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 05:53:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:53:18 INFO - (blobuploader) - INFO - Done attempting. 05:53:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:53:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:53:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:53:24 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:53:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:53:24 INFO - (blobuploader) - INFO - Done attempting. 05:53:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 05:53:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:53:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:53:25 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 05:53:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:53:25 INFO - (blobuploader) - INFO - Done attempting. 05:53:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 05:53:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:53:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:53:26 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 05:53:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:53:26 INFO - (blobuploader) - INFO - Done attempting. 05:53:26 INFO - (blobuploader) - INFO - Iteration through files over. 05:53:26 INFO - Return code: 0 05:53:26 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:53:26 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:53:26 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b6af4f9226bb86fd177d1cbb963794ae28c02acc47184367ac6de3bf2087c9e002d373723c3419545e45f46995aebdde345741d1cccd8a9fb128d8693d64eac1", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d985044a29cea5582edb42672b5234ed079dc58aa08143bed06a63537bd5714606d98d80dc1e9483620168de0138bdbb78abe939aaff825201ea098c4628d5e5", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ab14afffde41b9e18cb9ae1874269109b3e97de642319965993a29765d2fb15d107fd1c3984e2f840610d8e36f910694ae2e88c76afddd0abdfeb823b413dbc3", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d4298809fb1b82582a003065cc87b5e4bee0079afc5dfcd0ec7c156dc6dc2bc5b25a196c473ca72817922a90511da818346b766d9f8980c12898cd0164745a12"} 05:53:26 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:53:26 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:53:26 INFO - Contents: 05:53:26 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b6af4f9226bb86fd177d1cbb963794ae28c02acc47184367ac6de3bf2087c9e002d373723c3419545e45f46995aebdde345741d1cccd8a9fb128d8693d64eac1", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d985044a29cea5582edb42672b5234ed079dc58aa08143bed06a63537bd5714606d98d80dc1e9483620168de0138bdbb78abe939aaff825201ea098c4628d5e5", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ab14afffde41b9e18cb9ae1874269109b3e97de642319965993a29765d2fb15d107fd1c3984e2f840610d8e36f910694ae2e88c76afddd0abdfeb823b413dbc3", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d4298809fb1b82582a003065cc87b5e4bee0079afc5dfcd0ec7c156dc6dc2bc5b25a196c473ca72817922a90511da818346b766d9f8980c12898cd0164745a12"} 05:53:26 INFO - Running post-run listener: copy_logs_to_upload_dir 05:53:26 INFO - Copying logs to upload dir... 05:53:26 INFO - mkdir: /builds/slave/test/build/upload/logs 05:53:26 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1267.419100 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2017-09-07 05:53:26.960446) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-07 05:53:26.963798) ========= 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=4300f881736b422476174874000001bc-1504787535.681553-1137939653 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/b6af4f9226bb86fd177d1cbb963794ae28c02acc47184367ac6de3bf2087c9e002d373723c3419545e45f46995aebdde345741d1cccd8a9fb128d8693d64eac1", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d985044a29cea5582edb42672b5234ed079dc58aa08143bed06a63537bd5714606d98d80dc1e9483620168de0138bdbb78abe939aaff825201ea098c4628d5e5", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ab14afffde41b9e18cb9ae1874269109b3e97de642319965993a29765d2fb15d107fd1c3984e2f840610d8e36f910694ae2e88c76afddd0abdfeb823b413dbc3", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d4298809fb1b82582a003065cc87b5e4bee0079afc5dfcd0ec7c156dc6dc2bc5b25a196c473ca72817922a90511da818346b766d9f8980c12898cd0164745a12"} build_url:https://queue.taskcluster.net/v1/task/O4Y3kdYKTk6AYNU8oh2jiA/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011176 build_url: 'https://queue.taskcluster.net/v1/task/O4Y3kdYKTk6AYNU8oh2jiA/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b6af4f9226bb86fd177d1cbb963794ae28c02acc47184367ac6de3bf2087c9e002d373723c3419545e45f46995aebdde345741d1cccd8a9fb128d8693d64eac1", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d985044a29cea5582edb42672b5234ed079dc58aa08143bed06a63537bd5714606d98d80dc1e9483620168de0138bdbb78abe939aaff825201ea098c4628d5e5", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ab14afffde41b9e18cb9ae1874269109b3e97de642319965993a29765d2fb15d107fd1c3984e2f840610d8e36f910694ae2e88c76afddd0abdfeb823b413dbc3", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d4298809fb1b82582a003065cc87b5e4bee0079afc5dfcd0ec7c156dc6dc2bc5b25a196c473ca72817922a90511da818346b766d9f8980c12898cd0164745a12"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-07 05:53:26.991779) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-07 05:53:26.992135) ========= 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=4300f881736b422476174874000001bc-1504787535.681553-1137939653 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004381 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-07 05:53:27.076743) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-07 05:53:27.077122) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-07 05:53:27.077507) ========= ========= Total master_lag: 0.38 =========