builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-037 starttime: 1504086924.92 results: success (0) revision: ab2d700fda2b4934d24227216972dce9fac19b74 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-08-30 02:55:24.919157) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-08-30 02:55:24.919697) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-30 02:55:24.920007) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-30 02:55:25.083212) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-30 02:55:25.083540) ========= 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=56c518bb7cbc29fb3c9463e2000001c4-1504084713.229460-263559356 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-08-30 02:55:25-- 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% 17.7M=0.001s 2017-08-30 02:55:25 (17.7 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.127206 ========= master_lag: 0.05 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-30 02:55:25.263022) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-30 02:55:25.263444) ========= 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=56c518bb7cbc29fb3c9463e2000001c4-1504084713.229460-263559356 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018720 ========= master_lag: 0.07 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-30 02:55:25.357060) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-08-30 02:55:25.357448) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev ab2d700fda2b4934d24227216972dce9fac19b74 --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 ab2d700fda2b4934d24227216972dce9fac19b74 --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=56c518bb7cbc29fb3c9463e2000001c4-1504084713.229460-263559356 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-08-30 02:55:25,415 truncating revision to first 12 chars 2017-08-30 02:55:25,415 Setting DEBUG logging. 2017-08-30 02:55:25,415 attempt 1/10 2017-08-30 02:55:25,415 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/ab2d700fda2b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-08-30 02:55:26,588 unpacking tar archive at: mozilla-central-ab2d700fda2b/testing/mozharness/ program finished with exit code 0 elapsedTime=1.488744 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-08-30 02:55:26.878556) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-30 02:55:26.882098) ========= script_repo_revision: ab2d700fda2b4934d24227216972dce9fac19b74 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-30 02:55:26.882576) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-30 02:55:26.882892) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-30 02:55:26.915505) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 50 secs) (at 2017-08-30 02:55:26.915941) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 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-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] 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=56c518bb7cbc29fb3c9463e2000001c4-1504084713.229460-263559356 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 02:55:27 INFO - MultiFileLogger online at 20170830 02:55:27 in /builds/slave/test 02:55:27 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 02:55:27 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:55:27 INFO - {'append_to_log': False, 02:55:27 INFO - 'base_work_dir': '/builds/slave/test', 02:55:27 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 02:55:27 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:55:27 INFO - 'branch': 'Firefox-Non-PGO', 02:55:27 INFO - 'buildbot_json_path': 'buildprops.json', 02:55:27 INFO - 'code_coverage': False, 02:55:27 INFO - 'config_files': ('talos/linux_config.py',), 02:55:27 INFO - 'default_actions': ('clobber', 02:55:27 INFO - 'read-buildbot-config', 02:55:27 INFO - 'download-and-extract', 02:55:27 INFO - 'populate-webroot', 02:55:27 INFO - 'create-virtualenv', 02:55:27 INFO - 'install', 02:55:27 INFO - 'setup-mitmproxy', 02:55:27 INFO - 'run-tests'), 02:55:27 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:55:27 INFO - 'disable_ccov_upload': False, 02:55:27 INFO - 'disable_stylo': False, 02:55:27 INFO - 'download_minidump_stackwalk': True, 02:55:27 INFO - 'download_symbols': 'ondemand', 02:55:27 INFO - 'enable_stylo': False, 02:55:27 INFO - 'enable_webrender': False, 02:55:27 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 02:55:27 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:55:27 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:55:27 INFO - 'gecko_profile': False, 02:55:27 INFO - 'gecko_profile_interval': 0, 02:55:27 INFO - 'installer_path': 'installer.exe', 02:55:27 INFO - 'log_level': 'info', 02:55:27 INFO - 'log_name': 'talos', 02:55:27 INFO - 'log_to_console': True, 02:55:27 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 02:55:27 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 02:55:27 INFO - 'opt_config_files': (), 02:55:27 INFO - 'pip_index': False, 02:55:27 INFO - 'suite': 'dromaeojs-e10s', 02:55:27 INFO - 'system_bits': '32', 02:55:27 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:55:27 INFO - 'title': 'talos-linux64-ix-037', 02:55:27 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:55:27 INFO - 'use_talos_json': True, 02:55:27 INFO - 'verify': 'False', 02:55:27 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:55:27 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:55:27 INFO - 'work_dir': 'build'} 02:55:27 INFO - [mozharness: 2017-08-30 09:55:27.102581Z] Running clobber step. 02:55:27 INFO - Running pre-action listener: _resource_record_pre_action 02:55:27 INFO - Running main action method: clobber 02:55:27 INFO - rmtree: /builds/slave/test/build 02:55:27 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:55:28 INFO - Running post-action listener: _resource_record_post_action 02:55:28 INFO - [mozharness: 2017-08-30 09:55:28.128346Z] Finished clobber step (success) 02:55:28 INFO - [mozharness: 2017-08-30 09:55:28.128443Z] Running read-buildbot-config step. 02:55:28 INFO - Running pre-action listener: _resource_record_pre_action 02:55:28 INFO - Running main action method: read_buildbot_config 02:55:28 INFO - Using buildbot properties: 02:55:28 INFO - { 02:55:28 INFO - "project": "", 02:55:28 INFO - "product": "firefox", 02:55:28 INFO - "who": "archaeopteryx@coole-files.de", 02:55:28 INFO - "installer_path": "public/build/target.tar.bz2", 02:55:28 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 02:55:28 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 02:55:28 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 02:55:28 INFO - "stage_platform": "linux64", 02:55:28 INFO - "basedir": "/builds/slave/test", 02:55:28 INFO - "buildnumber": 800, 02:55:28 INFO - "platform": "ubuntu64_hw", 02:55:28 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 02:55:28 INFO - "slavebuilddir": "test", 02:55:28 INFO - "taskId": "XpkcS1T2Sh-oD1pw2lqyUQ", 02:55:28 INFO - "branch": "mozilla-central", 02:55:28 INFO - "script_repo_revision": "production", 02:55:28 INFO - "revision": "ab2d700fda2b4934d24227216972dce9fac19b74", 02:55:28 INFO - "slavename": "talos-linux64-ix-037", 02:55:28 INFO - "repo_path": "mozilla-central" 02:55:28 INFO - } 02:55:28 INFO - Finding installer, test and symbols from parent task. 02:55:28 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XpkcS1T2Sh-oD1pw2lqyUQ'}, attempt #1 02:55:28 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XpkcS1T2Sh-oD1pw2lqyUQ'}, attempt #1 02:55:29 INFO - Task dependencies: eSP-W7eQTHm081zyISUr-A 02:55:29 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/eSP-W7eQTHm081zyISUr-A'}, attempt #1 02:55:29 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/eSP-W7eQTHm081zyISUr-A/artifacts/public/build/target.tar.bz2 02:55:29 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/eSP-W7eQTHm081zyISUr-A/artifacts/public/build/target.test_packages.json 02:55:29 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/eSP-W7eQTHm081zyISUr-A/artifacts/public/build/target.crashreporter-symbols.zip 02:55:29 INFO - Running post-action listener: _resource_record_post_action 02:55:29 INFO - [mozharness: 2017-08-30 09:55:29.455478Z] Finished read-buildbot-config step (success) 02:55:29 INFO - [mozharness: 2017-08-30 09:55:29.455698Z] Running download-and-extract step. 02:55:29 INFO - Running pre-action listener: _resource_record_pre_action 02:55:29 INFO - Running main action method: download_and_extract 02:55:29 INFO - mkdir: /builds/slave/test/build/tests 02:55:29 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')]} 02:55:29 INFO - trying https://queue.taskcluster.net/v1/task/eSP-W7eQTHm081zyISUr-A/artifacts/public/build/target.test_packages.json 02:55:29 INFO - Downloading https://queue.taskcluster.net/v1/task/eSP-W7eQTHm081zyISUr-A/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 02:55:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/eSP-W7eQTHm081zyISUr-A/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 02:55:33 INFO - Downloaded 989 bytes. 02:55:33 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 02:55:33 INFO - Using the following test package requirements: 02:55:33 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 02:55:33 INFO - u'common': [u'target.common.tests.zip'], 02:55:33 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 02:55:33 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 02:55:33 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 02:55:33 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 02:55:33 INFO - u'mozbase': [u'target.common.tests.zip'], 02:55:33 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 02:55:33 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 02:55:33 INFO - u'web-platform': [u'target.common.tests.zip', 02:55:33 INFO - u'target.web-platform.tests.tar.gz'], 02:55:33 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 02:55:33 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 02:55:33 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/eSP-W7eQTHm081zyISUr-A/artifacts/public/build/target.common.tests.zip 02:55:33 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/eSP-W7eQTHm081zyISUr-A/artifacts/public/build/target.common.tests.zip'}, attempt #1 02:55:33 INFO - Fetch https://queue.taskcluster.net/v1/task/eSP-W7eQTHm081zyISUr-A/artifacts/public/build/target.common.tests.zip into memory 02:55:34 INFO - Content-Length response header: 54962578 02:55:34 INFO - Bytes received: 54962578 02:55:44 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 02:55:44 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/eSP-W7eQTHm081zyISUr-A/artifacts/public/build/target.common.tests.zip 02:55:44 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/eSP-W7eQTHm081zyISUr-A/artifacts/public/build/target.common.tests.zip'}, attempt #1 02:55:44 INFO - Fetch https://queue.taskcluster.net/v1/task/eSP-W7eQTHm081zyISUr-A/artifacts/public/build/target.common.tests.zip into memory 02:55:46 INFO - Content-Length response header: 54962578 02:55:46 INFO - Bytes received: 54962578 02:55:58 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/eSP-W7eQTHm081zyISUr-A/artifacts/public/build/target.talos.tests.zip 02:55:58 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/eSP-W7eQTHm081zyISUr-A/artifacts/public/build/target.talos.tests.zip'}, attempt #1 02:55:58 INFO - Fetch https://queue.taskcluster.net/v1/task/eSP-W7eQTHm081zyISUr-A/artifacts/public/build/target.talos.tests.zip into memory 02:55:58 INFO - Content-Length response header: 14496650 02:55:58 INFO - Bytes received: 14496650 02:55: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')]} 02:55:59 INFO - trying https://queue.taskcluster.net/v1/task/eSP-W7eQTHm081zyISUr-A/artifacts/public/build/target.tar.bz2 02:55:59 INFO - Downloading https://queue.taskcluster.net/v1/task/eSP-W7eQTHm081zyISUr-A/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 02:55:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/eSP-W7eQTHm081zyISUr-A/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 02:56:00 INFO - Downloaded 62596709 bytes. 02:56:00 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/eSP-W7eQTHm081zyISUr-A/artifacts/public/build/target.tar.bz2 02:56:00 INFO - mkdir: /builds/slave/test/properties 02:56:00 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:56:00 INFO - Writing to file /builds/slave/test/properties/build_url 02:56:00 INFO - Contents: 02:56:00 INFO - build_url:https://queue.taskcluster.net/v1/task/eSP-W7eQTHm081zyISUr-A/artifacts/public/build/target.tar.bz2 02:56:00 INFO - Running post-action listener: _resource_record_post_action 02:56:00 INFO - Running post-action listener: find_tests_for_verification 02:56:00 INFO - Running post-action listener: set_extra_try_arguments 02:56:00 INFO - [mozharness: 2017-08-30 09:56:00.155329Z] Finished download-and-extract step (success) 02:56:00 INFO - [mozharness: 2017-08-30 09:56:00.155428Z] Running populate-webroot step. 02:56:00 INFO - Running pre-action listener: _resource_record_pre_action 02:56:00 INFO - Running main action method: populate_webroot 02:56:00 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:56:00 INFO - 'chromez-stylo-e10s': {'talos_options': ['--enable-stylo'], 02:56:00 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:56:00 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:56:00 INFO - 'dromaeojs-stylo-e10s': {'talos_options': ['--enable-stylo'], 02:56:00 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:56:00 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 02:56:00 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:56:00 INFO - 'g1-stylo-e10s': {'pagesets_name': 'tp5n.zip', 02:56:00 INFO - 'talos_options': ['--enable-stylo'], 02:56:00 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:56:00 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 02:56:00 INFO - 'tests': ['damp', 'tps']}, 02:56:00 INFO - 'g2-stylo-e10s': {'pagesets_name': 'tp5n.zip', 02:56:00 INFO - 'talos_options': ['--enable-stylo'], 02:56:00 INFO - 'tests': ['damp', 'tps']}, 02:56:00 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:56:00 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 02:56:00 INFO - 'g4-stylo-e10s': {'talos_options': ['--enable-stylo'], 02:56:00 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 02:56:00 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 02:56:00 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 02:56:00 INFO - 'g5-stylo-e10s': {'pagesets_name': 'tp5n.zip', 02:56:00 INFO - 'talos_options': ['--enable-stylo'], 02:56:00 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 02:56:00 INFO - 'other-e10s': {'tests': ['a11yr', 02:56:00 INFO - 'ts_paint', 02:56:00 INFO - 'tpaint', 02:56:00 INFO - 'sessionrestore', 02:56:00 INFO - 'sessionrestore_many_windows', 02:56:00 INFO - 'sessionrestore_no_auto_restore', 02:56:00 INFO - 'tabpaint']}, 02:56:00 INFO - 'other-stylo-e10s': {'talos_options': ['--enable-stylo'], 02:56:00 INFO - 'tests': ['a11yr', 02:56:00 INFO - 'ts_paint', 02:56:00 INFO - 'tpaint', 02:56:00 INFO - 'sessionrestore', 02:56:00 INFO - 'sessionrestore_many_windows', 02:56:00 INFO - 'sessionrestore_no_auto_restore', 02:56:00 INFO - 'tabpaint']}, 02:56:00 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 02:56:00 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 02:56:00 INFO - 'perf-reftest-singletons-stylo-e10s': {'talos_options': ['--enable-stylo'], 02:56:00 INFO - 'tests': ['bloom_basic_singleton']}, 02:56:00 INFO - 'perf-reftest-stylo-e10s': {'talos_options': ['--enable-stylo'], 02:56:00 INFO - 'tests': ['bloom_basic']}, 02:56:00 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:56:00 INFO - 'tsvgr_opacity', 02:56:00 INFO - 'tart', 02:56:00 INFO - 'tscrollx', 02:56:00 INFO - 'tsvg_static']}, 02:56:00 INFO - 'svgr-stylo-e10s': {'talos_options': ['--enable-stylo'], 02:56:00 INFO - 'tests': ['tsvgx', 02:56:00 INFO - 'tsvgr_opacity', 02:56:00 INFO - 'tart', 02:56:00 INFO - 'tscrollx', 02:56:00 INFO - 'tsvg_static']}, 02:56:00 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 02:56:00 INFO - 'tp5o-stylo-e10s': {'pagesets_name': 'tp5n.zip', 02:56:00 INFO - 'talos_options': ['--enable-stylo'], 02:56:00 INFO - 'tests': ['tp5o']}, 02:56:00 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 02:56:00 INFO - 'talos_options': ['--mitmproxy', 02:56:00 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 02:56:00 INFO - '--firstNonBlankPaint'], 02:56:00 INFO - 'tests': ['tp6_google', 02:56:00 INFO - 'tp6_youtube', 02:56:00 INFO - 'tp6_amazon', 02:56:00 INFO - 'tp6_facebook']}, 02:56:00 INFO - 'tp6-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 02:56:00 INFO - 'talos_options': ['--enable-stylo', 02:56:00 INFO - '--mitmproxy', 02:56:00 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 02:56:00 INFO - '--firstNonBlankPaint'], 02:56:00 INFO - 'tests': ['tp6_google', 02:56:00 INFO - 'tp6_youtube', 02:56:00 INFO - 'tp6_amazon', 02:56:00 INFO - 'tp6_facebook']}, 02:56:00 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 02:56:00 INFO - 'talos_options': ['--enable-stylo', 02:56:00 INFO - '--stylo-threads=1', 02:56:00 INFO - '--mitmproxy', 02:56:00 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 02:56:00 INFO - '--firstNonBlankPaint'], 02:56:00 INFO - 'tests': ['tp6_google', 02:56:00 INFO - 'tp6_youtube', 02:56:00 INFO - 'tp6_amazon', 02:56:00 INFO - 'tp6_facebook']}, 02:56:00 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 02:56:00 INFO - 'talos_options': ['--xperf_path', 02:56:00 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:56:00 INFO - 'tests': ['tp5n']}, 02:56:00 INFO - 'xperf-stylo-e10s': {'pagesets_name': 'tp5n.zip', 02:56:00 INFO - 'talos_options': ['--enable-stylo', 02:56:00 INFO - '--xperf_path', 02:56:00 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:56:00 INFO - 'tests': ['tp5n']}}} 02:56:00 INFO - Running post-action listener: _resource_record_post_action 02:56:00 INFO - [mozharness: 2017-08-30 09:56:00.169928Z] Finished populate-webroot step (success) 02:56:00 INFO - [mozharness: 2017-08-30 09:56:00.170019Z] Running create-virtualenv step. 02:56:00 INFO - Running pre-action listener: _resource_record_pre_action 02:56:00 INFO - Running main action method: create_virtualenv 02:56:00 INFO - Creating virtualenv /builds/slave/test/build/venv 02:56:00 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 02:56:00 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 02:56:00 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 02:56:00 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:56:00 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:56:00 INFO - Installing distribute.............................................................................................................................................................................................done. 02:56:01 INFO - Installing pip...............done. 02:56:01 INFO - Return code: 0 02:56:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 02:56:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 02:56:01 INFO - Reading from file tmpfile_stdout 02:56:01 INFO - Output received: 02:56:01 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 02:56:01 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:56:01 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')]} 02:56:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:56:01 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')]} 02:56:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:56:01 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 0xc411f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe09a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfb0b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfad580>, '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 0xfae480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfae910>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1504084713.229460-263559356', '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 02:56:01 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 02:56:01 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 02:56:01 INFO - Using env: {'DISPLAY': ':0', 02:56:01 INFO - 'HOME': '/home/cltbld', 02:56:01 INFO - 'LANG': 'en_US.UTF-8', 02:56:01 INFO - 'LANGUAGE': 'en_US:en', 02:56:01 INFO - 'LOGNAME': 'cltbld', 02:56:01 INFO - 'MAIL': '/var/mail/cltbld', 02:56:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:56:01 INFO - 'MOZ_NO_REMOTE': '1', 02:56:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:56:01 INFO - 'NO_EM_RESTART': '1', 02:56:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:56:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:56:01 INFO - 'PWD': '/builds/slave/test', 02:56:01 INFO - 'SHELL': '/bin/bash', 02:56:01 INFO - 'SHLVL': '1', 02:56:01 INFO - 'TERM': 'linux', 02:56:01 INFO - 'TMOUT': '86400', 02:56:01 INFO - 'USER': 'cltbld', 02:56:01 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1504084713.229460-263559356', 02:56:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:56:01 INFO - '_': '/tools/buildbot/bin/python'} 02:56:02 INFO - Ignoring indexes: http://pypi.python.org/simple/ 02:56:02 INFO - Downloading/unpacking pip>=1.5 02:56:02 INFO - Running setup.py egg_info for package pip 02:56:02 INFO - warning: no files found matching 'pip/cacert.pem' 02:56:02 INFO - warning: no files found matching '*.html' under directory 'docs' 02:56:02 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 02:56:02 INFO - no previously-included directories found matching 'docs/_build/_sources' 02:56:02 INFO - Installing collected packages: pip 02:56:02 INFO - Found existing installation: pip 1.1 02:56:02 INFO - Uninstalling pip: 02:56:02 INFO - Successfully uninstalled pip 02:56:02 INFO - Running setup.py install for pip 02:56:02 INFO - warning: no files found matching 'pip/cacert.pem' 02:56:02 INFO - warning: no files found matching '*.html' under directory 'docs' 02:56:02 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 02:56:02 INFO - no previously-included directories found matching 'docs/_build/_sources' 02:56:02 INFO - Installing pip script to /builds/slave/test/build/venv/bin 02:56:02 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 02:56:02 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 02:56:02 INFO - Successfully installed pip 02:56:02 INFO - Cleaning up... 02:56:02 INFO - Return code: 0 02:56:02 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 02:56: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')]} 02:56:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:56: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')]} 02:56:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:56: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', '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 0xc411f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe09a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfb0b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfad580>, '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 0xfae480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfae910>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1504084713.229460-263559356', '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 02:56: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', 'psutil>=3.1.1'] in /builds/slave/test/build 02:56: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 psutil>=3.1.1 02:56:02 INFO - Using env: {'DISPLAY': ':0', 02:56:02 INFO - 'HOME': '/home/cltbld', 02:56:02 INFO - 'LANG': 'en_US.UTF-8', 02:56:02 INFO - 'LANGUAGE': 'en_US:en', 02:56:02 INFO - 'LOGNAME': 'cltbld', 02:56:02 INFO - 'MAIL': '/var/mail/cltbld', 02:56:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:56:02 INFO - 'MOZ_NO_REMOTE': '1', 02:56:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:56:02 INFO - 'NO_EM_RESTART': '1', 02:56:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:56:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:56:02 INFO - 'PWD': '/builds/slave/test', 02:56:02 INFO - 'SHELL': '/bin/bash', 02:56:02 INFO - 'SHLVL': '1', 02:56:02 INFO - 'TERM': 'linux', 02:56:02 INFO - 'TMOUT': '86400', 02:56:02 INFO - 'USER': 'cltbld', 02:56:02 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1504084713.229460-263559356', 02:56:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:56:02 INFO - '_': '/tools/buildbot/bin/python'} 02:56:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:56:02 INFO - Downloading/unpacking psutil>=3.1.1 02:56: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 02:56: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 02:56: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 02:56: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 02:56:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:56:03 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:56:03 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:56:03 INFO - Installing collected packages: psutil 02:56:03 INFO - Running setup.py install for psutil 02:56:03 INFO - building 'psutil._psutil_linux' extension 02:56:03 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 02:56:03 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 02:56:04 INFO - building 'psutil._psutil_posix' extension 02:56:04 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 02:56:04 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 02:56:04 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:56:04 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:56:04 INFO - Successfully installed psutil 02:56:04 INFO - Cleaning up... 02:56:04 INFO - Return code: 0 02:56:04 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 02:56:04 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')]} 02:56:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:56:04 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')]} 02:56:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:56:04 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 0xc411f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe09a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfb0b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfad580>, '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 0xfae480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfae910>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1504084713.229460-263559356', '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 02:56:04 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 02:56:04 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 02:56:04 INFO - Using env: {'DISPLAY': ':0', 02:56:04 INFO - 'HOME': '/home/cltbld', 02:56:04 INFO - 'LANG': 'en_US.UTF-8', 02:56:04 INFO - 'LANGUAGE': 'en_US:en', 02:56:04 INFO - 'LOGNAME': 'cltbld', 02:56:04 INFO - 'MAIL': '/var/mail/cltbld', 02:56:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:56:04 INFO - 'MOZ_NO_REMOTE': '1', 02:56:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:56:04 INFO - 'NO_EM_RESTART': '1', 02:56:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:56:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:56:04 INFO - 'PWD': '/builds/slave/test', 02:56:04 INFO - 'SHELL': '/bin/bash', 02:56:04 INFO - 'SHLVL': '1', 02:56:04 INFO - 'TERM': 'linux', 02:56:04 INFO - 'TMOUT': '86400', 02:56:04 INFO - 'USER': 'cltbld', 02:56:04 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1504084713.229460-263559356', 02:56:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:56:04 INFO - '_': '/tools/buildbot/bin/python'} 02:56:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:56:04 INFO - Downloading/unpacking mozsystemmonitor==0.3 02:56:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:56:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:56:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:56:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:56:05 INFO - Downloading mozsystemmonitor-0.3.tar.gz 02:56:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:56:05 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 02:56:05 INFO - Installing collected packages: mozsystemmonitor 02:56:05 INFO - Running setup.py install for mozsystemmonitor 02:56:05 INFO - Successfully installed mozsystemmonitor 02:56:05 INFO - Cleaning up... 02:56:05 INFO - Return code: 0 02:56:05 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 02:56:05 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')]} 02:56:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:56:05 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')]} 02:56:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:56:05 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 0xc411f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe09a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfb0b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfad580>, '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 0xfae480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfae910>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1504084713.229460-263559356', '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 02:56:05 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 02:56:05 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 02:56:05 INFO - Using env: {'DISPLAY': ':0', 02:56:05 INFO - 'HOME': '/home/cltbld', 02:56:05 INFO - 'LANG': 'en_US.UTF-8', 02:56:05 INFO - 'LANGUAGE': 'en_US:en', 02:56:05 INFO - 'LOGNAME': 'cltbld', 02:56:05 INFO - 'MAIL': '/var/mail/cltbld', 02:56:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:56:05 INFO - 'MOZ_NO_REMOTE': '1', 02:56:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:56:05 INFO - 'NO_EM_RESTART': '1', 02:56:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:56:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:56:05 INFO - 'PWD': '/builds/slave/test', 02:56:05 INFO - 'SHELL': '/bin/bash', 02:56:05 INFO - 'SHLVL': '1', 02:56:05 INFO - 'TERM': 'linux', 02:56:05 INFO - 'TMOUT': '86400', 02:56:05 INFO - 'USER': 'cltbld', 02:56:05 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1504084713.229460-263559356', 02:56:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:56:05 INFO - '_': '/tools/buildbot/bin/python'} 02:56:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:56:06 INFO - Downloading/unpacking jsonschema==2.5.1 02:56:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:56:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:56:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:56:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:56:07 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:56:07 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 02:56: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 02:56: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 02:56: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 02:56: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 02:56:07 INFO - Downloading functools32-3.2.3-2.tar.gz 02:56:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:56:07 INFO - warning: no files found matching '*.txt' 02:56:07 INFO - no previously-included directories found matching 'build' 02:56:07 INFO - no previously-included directories found matching 'dist' 02:56:07 INFO - no previously-included directories found matching '.git*' 02:56:07 INFO - Installing collected packages: jsonschema, functools32 02:56:07 INFO - Running setup.py install for functools32 02:56:07 INFO - warning: no files found matching '*.txt' 02:56:07 INFO - no previously-included directories found matching 'build' 02:56:07 INFO - no previously-included directories found matching 'dist' 02:56:07 INFO - no previously-included directories found matching '.git*' 02:56:07 INFO - Successfully installed jsonschema functools32 02:56:07 INFO - Cleaning up... 02:56:07 INFO - Return code: 0 02:56:07 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 02:56:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:56:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:56:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:56:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:56:07 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 0xc411f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe09a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfb0b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfad580>, '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 0xfae480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfae910>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1504084713.229460-263559356', '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 02:56:07 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 02:56:07 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 02:56:07 INFO - Using env: {'DISPLAY': ':0', 02:56:07 INFO - 'HOME': '/home/cltbld', 02:56:07 INFO - 'LANG': 'en_US.UTF-8', 02:56:07 INFO - 'LANGUAGE': 'en_US:en', 02:56:07 INFO - 'LOGNAME': 'cltbld', 02:56:07 INFO - 'MAIL': '/var/mail/cltbld', 02:56:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:56:07 INFO - 'MOZ_NO_REMOTE': '1', 02:56:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:56:07 INFO - 'NO_EM_RESTART': '1', 02:56:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:56:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:56:07 INFO - 'PWD': '/builds/slave/test', 02:56:07 INFO - 'SHELL': '/bin/bash', 02:56:07 INFO - 'SHLVL': '1', 02:56:07 INFO - 'TERM': 'linux', 02:56:07 INFO - 'TMOUT': '86400', 02:56:07 INFO - 'USER': 'cltbld', 02:56:07 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1504084713.229460-263559356', 02:56:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:56:07 INFO - '_': '/tools/buildbot/bin/python'} 02:56:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:56:07 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 02:56:07 INFO - Cleaning up... 02:56:07 INFO - Return code: 0 02:56:07 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:56:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:56:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:56:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:56:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:56:07 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 0xc411f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe09a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfb0b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfad580>, '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 0xfae480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfae910>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1504084713.229460-263559356', '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 02:56:07 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 02:56:07 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 02:56:07 INFO - Using env: {'DISPLAY': ':0', 02:56:07 INFO - 'HOME': '/home/cltbld', 02:56:07 INFO - 'LANG': 'en_US.UTF-8', 02:56:07 INFO - 'LANGUAGE': 'en_US:en', 02:56:07 INFO - 'LOGNAME': 'cltbld', 02:56:07 INFO - 'MAIL': '/var/mail/cltbld', 02:56:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:56:07 INFO - 'MOZ_NO_REMOTE': '1', 02:56:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:56:07 INFO - 'NO_EM_RESTART': '1', 02:56:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:56:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:56:07 INFO - 'PWD': '/builds/slave/test', 02:56:07 INFO - 'SHELL': '/bin/bash', 02:56:07 INFO - 'SHLVL': '1', 02:56:07 INFO - 'TERM': 'linux', 02:56:07 INFO - 'TMOUT': '86400', 02:56:07 INFO - 'USER': 'cltbld', 02:56:07 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1504084713.229460-263559356', 02:56:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:56:07 INFO - '_': '/tools/buildbot/bin/python'} 02:56:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:56:07 INFO - Downloading/unpacking blobuploader==1.2.4 02:56: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 02:56: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 02:56: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 02:56: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 02:56:08 INFO - Downloading blobuploader-1.2.4.tar.gz 02:56:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:56:09 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:56:09 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 02:56:09 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 02:56:09 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 02:56:09 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 02:56:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:56:09 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:56:09 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 02:56:09 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 02:56:09 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 02:56:09 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 02:56:09 INFO - Downloading docopt-0.6.1.tar.gz 02:56:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:56:09 INFO - Installing collected packages: blobuploader, requests, docopt 02:56:09 INFO - Running setup.py install for blobuploader 02:56:09 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:56:09 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:56:09 INFO - Running setup.py install for requests 02:56:10 INFO - Running setup.py install for docopt 02:56:10 INFO - Successfully installed blobuploader requests docopt 02:56:10 INFO - Cleaning up... 02:56:10 INFO - Return code: 0 02:56:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:56:10 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')]} 02:56:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:56:10 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')]} 02:56:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:56:10 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 0xc411f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe09a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfb0b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfad580>, '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 0xfae480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfae910>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1504084713.229460-263559356', '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 02:56:10 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 02:56:10 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 02:56:10 INFO - Using env: {'DISPLAY': ':0', 02:56:10 INFO - 'HOME': '/home/cltbld', 02:56:10 INFO - 'LANG': 'en_US.UTF-8', 02:56:10 INFO - 'LANGUAGE': 'en_US:en', 02:56:10 INFO - 'LOGNAME': 'cltbld', 02:56:10 INFO - 'MAIL': '/var/mail/cltbld', 02:56:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:56:10 INFO - 'MOZ_NO_REMOTE': '1', 02:56:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:56:10 INFO - 'NO_EM_RESTART': '1', 02:56:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:56:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:56:10 INFO - 'PWD': '/builds/slave/test', 02:56:10 INFO - 'SHELL': '/bin/bash', 02:56:10 INFO - 'SHLVL': '1', 02:56:10 INFO - 'TERM': 'linux', 02:56:10 INFO - 'TMOUT': '86400', 02:56:10 INFO - 'USER': 'cltbld', 02:56:10 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1504084713.229460-263559356', 02:56:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:56:10 INFO - '_': '/tools/buildbot/bin/python'} 02:56:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:56:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:56:10 INFO - Running setup.py (path:/tmp/pip-GYAq5A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:56:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:56:10 INFO - Running setup.py (path:/tmp/pip-k8k_xO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:56:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:56:10 INFO - Running setup.py (path:/tmp/pip-zkvMG5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:56:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:56:10 INFO - Running setup.py (path:/tmp/pip-0PcD8L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:56:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:56:10 INFO - Running setup.py (path:/tmp/pip-hYzyWv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:56:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:56:10 INFO - Running setup.py (path:/tmp/pip-SQdOqL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:56:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:56:10 INFO - Running setup.py (path:/tmp/pip-ncSf5_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:56:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:56:10 INFO - Running setup.py (path:/tmp/pip-e3Gmt_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:56:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:56:10 INFO - Running setup.py (path:/tmp/pip-D8uQpV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:56:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:56:11 INFO - Running setup.py (path:/tmp/pip-_Sz8q4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:56:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:56:11 INFO - Running setup.py (path:/tmp/pip-TSWPYs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:56:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:56:11 INFO - Running setup.py (path:/tmp/pip-NtKbdA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:56:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:56:11 INFO - Running setup.py (path:/tmp/pip-5Q7QVr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:56:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:56:11 INFO - Running setup.py (path:/tmp/pip-1zQWDa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:56:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:56:11 INFO - Running setup.py (path:/tmp/pip-oh3fdW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:56:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:56:11 INFO - Running setup.py (path:/tmp/pip-TO8yxt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:56:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:56:11 INFO - Running setup.py (path:/tmp/pip-pbENNL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:56:11 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:56:11 INFO - Running setup.py install for manifestparser 02:56:11 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:56:11 INFO - Running setup.py install for mozcrash 02:56:11 INFO - Running setup.py install for mozdebug 02:56:11 INFO - Running setup.py install for mozdevice 02:56:11 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:56:11 INFO - Running setup.py install for mozfile 02:56:12 INFO - Running setup.py install for mozhttpd 02:56:12 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:56:12 INFO - Running setup.py install for mozinfo 02:56:12 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:56:12 INFO - Running setup.py install for mozInstall 02:56:12 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:56:12 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:56:12 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:56:12 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:56:12 INFO - Running setup.py install for mozleak 02:56:12 INFO - Running setup.py install for mozlog 02:56:12 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:56:12 INFO - Running setup.py install for moznetwork 02:56:12 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:56:12 INFO - Running setup.py install for mozprocess 02:56:12 INFO - Running setup.py install for mozprofile 02:56:12 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:56:12 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:56:12 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:56:12 INFO - Running setup.py install for mozrunner 02:56:13 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:56:13 INFO - Running setup.py install for mozscreenshot 02:56:13 INFO - Running setup.py install for moztest 02:56:13 INFO - Running setup.py install for mozversion 02:56:13 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:56:13 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:56:13 INFO - Cleaning up... 02:56:13 INFO - Return code: 0 02:56:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:56:13 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')]} 02:56:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:56:13 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')]} 02:56:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:56:13 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 0xc411f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe09a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfb0b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfad580>, '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 0xfae480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfae910>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1504084713.229460-263559356', '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 02:56:13 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 02:56:13 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 02:56:13 INFO - Using env: {'DISPLAY': ':0', 02:56:13 INFO - 'HOME': '/home/cltbld', 02:56:13 INFO - 'LANG': 'en_US.UTF-8', 02:56:13 INFO - 'LANGUAGE': 'en_US:en', 02:56:13 INFO - 'LOGNAME': 'cltbld', 02:56:13 INFO - 'MAIL': '/var/mail/cltbld', 02:56:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:56:13 INFO - 'MOZ_NO_REMOTE': '1', 02:56:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:56:13 INFO - 'NO_EM_RESTART': '1', 02:56:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:56:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:56:13 INFO - 'PWD': '/builds/slave/test', 02:56:13 INFO - 'SHELL': '/bin/bash', 02:56:13 INFO - 'SHLVL': '1', 02:56:13 INFO - 'TERM': 'linux', 02:56:13 INFO - 'TMOUT': '86400', 02:56:13 INFO - 'USER': 'cltbld', 02:56:13 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1504084713.229460-263559356', 02:56:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:56:13 INFO - '_': '/tools/buildbot/bin/python'} 02:56:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:56:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:56:13 INFO - Running setup.py (path:/tmp/pip-5bRoj0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:56:13 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)) 02:56:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:56:13 INFO - Running setup.py (path:/tmp/pip-JbjRW3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:56:13 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)) 02:56:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:56:13 INFO - Running setup.py (path:/tmp/pip-MbSMhA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:56:13 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)) 02:56:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:56:13 INFO - Running setup.py (path:/tmp/pip-2YuvxB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:56:13 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)) 02:56:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:56:13 INFO - Running setup.py (path:/tmp/pip-VCQuqr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:56:13 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)) 02:56:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:56:13 INFO - Running setup.py (path:/tmp/pip-ZfXllL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:56:13 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)) 02:56:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:56:13 INFO - Running setup.py (path:/tmp/pip-hH0PVu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:56:14 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)) 02:56:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:56:14 INFO - Running setup.py (path:/tmp/pip-PggqPX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:56:14 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)) 02:56:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:56:14 INFO - Running setup.py (path:/tmp/pip-e2trJi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:56:14 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)) 02:56:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:56:14 INFO - Running setup.py (path:/tmp/pip-ZA3QOz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:56:14 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)) 02:56:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:56:14 INFO - Running setup.py (path:/tmp/pip-b_sxbj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:56:14 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)) 02:56:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:56:14 INFO - Running setup.py (path:/tmp/pip-NqqvEW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:56:14 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)) 02:56:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:56:14 INFO - Running setup.py (path:/tmp/pip-nHk1sy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:56:14 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)) 02:56:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:56:14 INFO - Running setup.py (path:/tmp/pip-1V4iUQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:56:14 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)) 02:56:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:56:14 INFO - Running setup.py (path:/tmp/pip-XtttdO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:56:14 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)) 02:56:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:56:14 INFO - Running setup.py (path:/tmp/pip-gCjlen-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:56:14 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)) 02:56:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:56:14 INFO - Running setup.py (path:/tmp/pip-tY5lWV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:56:14 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)) 02:56:14 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)) 02:56:14 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)) 02:56:14 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)) 02:56:14 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)) 02:56:14 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)) 02:56:14 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)) 02:56:14 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:56: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 02:56: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 02:56: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 02:56: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 02:56:15 INFO - Downloading blessings-1.6.tar.gz 02:56:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:56:15 INFO - Installing collected packages: blessings 02:56:15 INFO - Running setup.py install for blessings 02:56:16 INFO - Successfully installed blessings 02:56:16 INFO - Cleaning up... 02:56:16 INFO - Return code: 0 02:56:16 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:56:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:56:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:56:16 INFO - Reading from file tmpfile_stdout 02:56:16 INFO - Current package versions: 02:56:16 INFO - argparse == 1.2.1 02:56:16 INFO - blessings == 1.6 02:56:16 INFO - blobuploader == 1.2.4 02:56:16 INFO - docopt == 0.6.1 02:56:16 INFO - functools32 == 3.2.3-2 02:56:16 INFO - jsonschema == 2.5.1 02:56:16 INFO - manifestparser == 1.1 02:56:16 INFO - mozInstall == 1.13 02:56:16 INFO - mozcrash == 1.0 02:56:16 INFO - mozdebug == 0.1 02:56:16 INFO - mozdevice == 0.51 02:56:16 INFO - mozfile == 1.2 02:56:16 INFO - mozhttpd == 0.7 02:56:16 INFO - mozinfo == 0.10 02:56:16 INFO - mozleak == 0.1 02:56:16 INFO - mozlog == 3.5 02:56:16 INFO - moznetwork == 0.27 02:56:16 INFO - mozprocess == 0.25 02:56:16 INFO - mozprofile == 0.28 02:56:16 INFO - mozrunner == 6.13 02:56:16 INFO - mozscreenshot == 0.1 02:56:16 INFO - mozsystemmonitor == 0.3 02:56:16 INFO - moztest == 0.8 02:56:16 INFO - mozversion == 1.4 02:56:16 INFO - psutil == 3.1.1 02:56:16 INFO - requests == 1.2.3 02:56:16 INFO - wsgiref == 0.1.2 02:56:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:56:16 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')]} 02:56:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:56:16 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')]} 02:56:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:56:16 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 0xc411f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe09a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfb0b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfad580>, '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 0xfae480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfae910>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1504084713.229460-263559356', '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 02:56:16 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 02:56:16 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 02:56:16 INFO - Using env: {'DISPLAY': ':0', 02:56:16 INFO - 'HOME': '/home/cltbld', 02:56:16 INFO - 'LANG': 'en_US.UTF-8', 02:56:16 INFO - 'LANGUAGE': 'en_US:en', 02:56:16 INFO - 'LOGNAME': 'cltbld', 02:56:16 INFO - 'MAIL': '/var/mail/cltbld', 02:56:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:56:16 INFO - 'MOZ_NO_REMOTE': '1', 02:56:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:56:16 INFO - 'NO_EM_RESTART': '1', 02:56:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:56:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:56:16 INFO - 'PWD': '/builds/slave/test', 02:56:16 INFO - 'SHELL': '/bin/bash', 02:56:16 INFO - 'SHLVL': '1', 02:56:16 INFO - 'TERM': 'linux', 02:56:16 INFO - 'TMOUT': '86400', 02:56:16 INFO - 'USER': 'cltbld', 02:56:16 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1504084713.229460-263559356', 02:56:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:56:16 INFO - '_': '/tools/buildbot/bin/python'} 02:56:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:56:16 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)) 02:56:16 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)) 02:56:16 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)) 02:56:16 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)) 02:56:16 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)) 02:56:16 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)) 02:56:16 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)) 02:56:16 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)) 02:56:16 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)) 02:56:16 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 02:56:16 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 02:56:16 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 02:56:16 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 02:56:16 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 02:56:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 02:56:18 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)) 02:56:18 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)) 02:56:18 INFO - Installing collected packages: simplejson 02:56:18 INFO - Running setup.py install for simplejson 02:56:18 INFO - building 'simplejson._speedups' extension 02:56:18 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 02:56:19 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 02:56:19 INFO - Successfully installed simplejson 02:56:19 INFO - Cleaning up... 02:56:19 INFO - Return code: 0 02:56:19 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:56:19 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')]} 02:56:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:56:19 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')]} 02:56:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:56:19 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 0xc411f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe09a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfb0b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfad580>, '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 0xfae480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfae910>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1504084713.229460-263559356', '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 02:56:19 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 02:56:19 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 02:56:19 INFO - Using env: {'DISPLAY': ':0', 02:56:19 INFO - 'HOME': '/home/cltbld', 02:56:19 INFO - 'LANG': 'en_US.UTF-8', 02:56:19 INFO - 'LANGUAGE': 'en_US:en', 02:56:19 INFO - 'LOGNAME': 'cltbld', 02:56:19 INFO - 'MAIL': '/var/mail/cltbld', 02:56:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:56:19 INFO - 'MOZ_NO_REMOTE': '1', 02:56:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:56:19 INFO - 'NO_EM_RESTART': '1', 02:56:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:56:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:56:19 INFO - 'PWD': '/builds/slave/test', 02:56:19 INFO - 'SHELL': '/bin/bash', 02:56:19 INFO - 'SHLVL': '1', 02:56:19 INFO - 'TERM': 'linux', 02:56:19 INFO - 'TMOUT': '86400', 02:56:19 INFO - 'USER': 'cltbld', 02:56:19 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1504084713.229460-263559356', 02:56:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:56:19 INFO - '_': '/tools/buildbot/bin/python'} 02:56:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:56:19 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 02:56:19 INFO - Cleaning up... 02:56:20 INFO - Return code: 0 02:56:20 INFO - Running post-action listener: _resource_record_post_action 02:56:20 INFO - Running post-action listener: _start_resource_monitoring 02:56:20 INFO - Starting resource monitoring. 02:56:20 INFO - [mozharness: 2017-08-30 09:56:20.043651Z] Finished create-virtualenv step (success) 02:56:20 INFO - [mozharness: 2017-08-30 09:56:20.044160Z] Running install step. 02:56:20 INFO - Running pre-action listener: _resource_record_pre_action 02:56:20 INFO - Running main action method: install 02:56:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:56:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:56:20 INFO - Reading from file tmpfile_stdout 02:56:20 INFO - Detecting whether we're running mozinstall >=1.0... 02:56:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:56:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:56:20 INFO - Reading from file tmpfile_stdout 02:56:20 INFO - Output received: 02:56:20 INFO - Usage: mozinstall [options] installer 02:56:20 INFO - Options: 02:56:20 INFO - -h, --help show this help message and exit 02:56:20 INFO - -d DEST, --destination=DEST 02:56:20 INFO - Directory to install application into. [default: 02:56:20 INFO - "/builds/slave/test"] 02:56:20 INFO - --app=APP Application being installed. [default: firefox] 02:56:20 INFO - mkdir: /builds/slave/test/build/application 02:56:20 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'] 02:56:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 02:56:36 INFO - Reading from file tmpfile_stdout 02:56:36 INFO - Output received: 02:56:36 INFO - /builds/slave/test/build/application/firefox/firefox 02:56:36 INFO - Running post-action listener: _resource_record_post_action 02:56:36 INFO - [mozharness: 2017-08-30 09:56:36.860136Z] Finished install step (success) 02:56:36 INFO - [mozharness: 2017-08-30 09:56:36.860420Z] Running setup-mitmproxy step. 02:56:36 INFO - Running pre-action listener: _resource_record_pre_action 02:56:36 INFO - Running main action method: setup_mitmproxy 02:56:36 INFO - Skipping: mitmproxy is not required 02:56:36 INFO - Running post-action listener: _resource_record_post_action 02:56:36 INFO - [mozharness: 2017-08-30 09:56:36.861559Z] Finished setup-mitmproxy step (success) 02:56:36 INFO - [mozharness: 2017-08-30 09:56:36.861849Z] Running run-tests step. 02:56:36 INFO - Running pre-action listener: _resource_record_pre_action 02:56:36 INFO - Running pre-action listener: _set_gcov_prefix 02:56:36 INFO - Running main action method: run_tests 02:56:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:56:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:56:36 INFO - Python 2.7.3 02:56:36 INFO - Return code: 0 02:56:36 INFO - grabbing minidump binary from tooltool 02:56:36 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')]} 02:56:36 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 0xfad580>, '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 0xfae480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfae910>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:56:36 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 02:56:36 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 02:56:36 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 02:56:36 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:56:36 INFO - Return code: 0 02:56:36 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 02:56:36 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:56:36 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:56:36 INFO - ENV: RUST_BACKTRACE is now full 02:56:36 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 02:56:36 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:56:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/eSP-W7eQTHm081zyISUr-A/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-037', '--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 02:56:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/eSP-W7eQTHm081zyISUr-A/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-037 --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 02:56:36 INFO - Using env: {'DISPLAY': ':0', 02:56:36 INFO - 'HOME': '/home/cltbld', 02:56:36 INFO - 'LANG': 'en_US.UTF-8', 02:56:36 INFO - 'LANGUAGE': 'en_US:en', 02:56:36 INFO - 'LOGNAME': 'cltbld', 02:56:36 INFO - 'MAIL': '/var/mail/cltbld', 02:56:36 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:56:36 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 02:56:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:56:36 INFO - 'MOZ_NO_REMOTE': '1', 02:56:36 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:56:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:56:36 INFO - 'NO_EM_RESTART': '1', 02:56:36 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:56:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:56:36 INFO - 'PWD': '/builds/slave/test', 02:56:36 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:56:36 INFO - 'RUST_BACKTRACE': 'full', 02:56:36 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 02:56:36 INFO - 'SHELL': '/bin/bash', 02:56:36 INFO - 'SHLVL': '1', 02:56:37 INFO - 'TERM': 'linux', 02:56:37 INFO - 'TMOUT': '86400', 02:56:37 INFO - 'USER': 'cltbld', 02:56:37 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1504084713.229460-263559356', 02:56:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:56:37 INFO - '_': '/tools/buildbot/bin/python'} 02:56:37 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/eSP-W7eQTHm081zyISUr-A/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-037', '--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 02:56:37 INFO - mozversion application_buildid: 20170830090533 02:56:37 INFO - mozversion application_changeset: ab2d700fda2b4934d24227216972dce9fac19b74 02:56:37 INFO - mozversion application_display_name: Nightly 02:56:37 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:56:37 INFO - mozversion application_name: Firefox 02:56:37 INFO - mozversion application_remotingname: firefox 02:56:37 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 02:56:37 INFO - mozversion application_vendor: Mozilla 02:56:37 INFO - mozversion application_version: 57.0a1 02:56:37 INFO - mozversion platform_buildid: 20170830090533 02:56:37 INFO - mozversion platform_changeset: ab2d700fda2b4934d24227216972dce9fac19b74 02:56:37 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 02:56:37 INFO - mozversion platform_version: 57.0a1 02:56:37 INFO - using testdate: 1504086997 02:56:37 INFO - actual date: 1504086997 02:56:37 INFO - starting webserver on 'localhost:38787' 02:56:37 INFO - SUITE-START | Running 2 tests 02:56:37 INFO - TEST-START | dromaeo_css 02:56:37 INFO - Initialising browser for dromaeo_css test... 02:56:37 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:38787/getInfo.html -profile /tmp/tmptgWfmm/profile 02:56:37 INFO - TEST-INFO | started process 11019 (/builds/slave/test/build/application/firefox/firefox http://localhost:38787/getInfo.html) 02:56:42 INFO - TEST-INFO | 11019: exit 0 02:56:42 INFO - Browser initialized. 02:56:42 INFO - Running cycle 1/1 for dromaeo_css test... 02:56:42 INFO - Using env: {'DISPLAY': ':0', 02:56:42 INFO - 'HOME': '/home/cltbld', 02:56:42 INFO - 'JSGC_DISABLE_POISONING': '1', 02:56:42 INFO - 'LANG': 'en_US.UTF-8', 02:56:42 INFO - 'LANGUAGE': 'en_US:en', 02:56:42 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 02:56:42 INFO - 'LOGNAME': 'cltbld', 02:56:42 INFO - 'MAIL': '/var/mail/cltbld', 02:56:42 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:56:42 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 02:56:42 INFO - 'MOZ_CRASHREPORTER': '1', 02:56:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:56:42 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 02:56:42 INFO - 'MOZ_NO_REMOTE': '1', 02:56:42 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:56:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:56:42 INFO - 'NO_EM_RESTART': '1', 02:56:42 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:56:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:56:42 INFO - 'PWD': '/builds/slave/test', 02:56:42 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:56:42 INFO - 'RUST_BACKTRACE': 'full', 02:56:42 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 02:56:42 INFO - 'SHELL': '/bin/bash', 02:56:42 INFO - 'SHLVL': '1', 02:56:42 INFO - 'TERM': 'linux', 02:56:42 INFO - 'TMOUT': '86400', 02:56:42 INFO - 'USER': 'cltbld', 02:56:42 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1504084713.229460-263559356', 02:56:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:56:42 INFO - '_': '/tools/buildbot/bin/python'} 02:56:42 INFO - TEST-INFO | started process 11204 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmptgWfmm/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 02:56:43 INFO - PID 11204 | 02:56:43 INFO - PID 11204 | (/builds/slave/test/build/application/firefox/firefox:11256): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 02:56:43 INFO - PID 11204 | 02:56:54 INFO - PID 11204 | RSS: Main: 169889792 02:56:54 INFO - PID 11204 | 02:59:53 INFO - PID 11204 | Cycle 1(1): loaded http://localhost:38787/tests/dromaeo/cssquery-dojo.html (next: http://localhost:38787/tests/dromaeo/cssquery-ext.html) 02:59:54 INFO - PID 11204 | RSS: Main: 172048384 02:59:54 INFO - PID 11204 | 03:02:55 INFO - PID 11204 | Cycle 1(1): loaded http://localhost:38787/tests/dromaeo/cssquery-ext.html (next: http://localhost:38787/tests/dromaeo/cssquery-jquery.html) 03:02:56 INFO - PID 11204 | RSS: Main: 168394752 03:02:56 INFO - PID 11204 | 03:05:58 INFO - PID 11204 | Cycle 1(1): loaded http://localhost:38787/tests/dromaeo/cssquery-jquery.html (next: http://localhost:38787/tests/dromaeo/cssquery-mootools.html) 03:05:58 INFO - PID 11204 | RSS: Main: 168886272 03:05:58 INFO - PID 11204 | 03:09:00 INFO - PID 11204 | Cycle 1(1): loaded http://localhost:38787/tests/dromaeo/cssquery-mootools.html (next: http://localhost:38787/tests/dromaeo/cssquery-prototype.html) 03:09:01 INFO - PID 11204 | RSS: Main: 166629376 03:09:01 INFO - PID 11204 | 03:12:04 INFO - PID 11204 | Cycle 1(1): loaded http://localhost:38787/tests/dromaeo/cssquery-prototype.html (next: http://localhost:38787/tests/dromaeo/cssquery-yui.html) 03:12:05 INFO - PID 11204 | RSS: Main: 170311680 03:12:05 INFO - PID 11204 | 03:15:06 INFO - PID 11204 | Cycle 1(1): loaded http://localhost:38787/tests/dromaeo/cssquery-yui.html (next: http://localhost:38787/tests/dromaeo/cssquery-dojo.html) 03:15:07 INFO - PID 11204 | RSS: Main: 172892160 03:15:07 INFO - PID 11204 | 03:15:07 INFO - PID 11204 | __start_tp_report 03:15:07 INFO - PID 11204 | _x_x_mozilla_page_load 03:15:07 INFO - PID 11204 | _x_x_mozilla_page_load_details 03:15:07 INFO - PID 11204 | |i|pagename|runs| 03:15:07 INFO - PID 11204 | |0;dojo.html;3363.5627368442097;3369.600923778331;3371.4942758586208;3388.7289016878644;3406.1825161961137;6551.181102362205;6570.44151247144;6593.505487088476;6605.702743376548;6634.635095069769;6387.680615969194;6474.482041436686;6486;6518.804435866919;6614.834629134274;5932.822015339547;5970.850728731773;5998.10028495727;6013.278406591205;6030.66831324277;938.5077204883722;5643.830685079454;5851.7074146292725;5871.060630299153;6070.271567411917;5925.259342582177;5935.347111817697;6006.339302676702;6025.668588227668;6026.06595977622;5928.318243402021;5937.52499800015;6015.09773533969;6015.127806468081;6035.78874739382;5925.4370834770625;5936.109583562457;6011.30869949957;6034.517238620943;6044.032954727222;6339.619622822599;6495.6752162391695;6515.4787616990525;6533.705983230719;6578;6841.3842754151865;6897.2757860424435;6908.965455172743;6927.965360173167;6930.237673855871;6961.303869612998;6966.198887127995;6967.303269673042;7011.579305241651;7087.078679771596;6547.148870646833;6561.40947314744;6571.901421478682;6573.474122070223;6591.40677339037;7200.387967022862;7211.314925082109;7283.890741638879;7285.125784905846;7285.744998925013;6849.657517124124;6877.828054298682;6951.200611929693;6959;6979.8604027919155;6829.692663830139;6882.277360877136;6948.235694073648;6971.198312194061;7016.7193312267955;6974.407175390047;6976.372126508537;6989.720411183597;7010.579365238102;7022.789316320519;6722.731090756315;6824.351686589766;6833.6241506716615;7004.789856304319;7046.61243631595;5026.974865125651;5046.293518907356;5050.368703912011;5051.166557517969;5052.1663925451885;4463.598276155097;4528.026474307973;4543.34121552373;4543.386642803245;4545.227311357077;6074.635521868702;6118.938810611837;6192.628442293477;6200.56596038273;6206.968965155145;7026.227115017344;7031.507794454441;7052.400545953651;7142.142942846892;7147.53541019832;6137.754489820447;6154.599951003171;6161.137440758297;6164.907526387108;6165.167702360213;6008.21893153896;6022.277326720821;6033.276006879202;6037.969810151009;6048.032314829607;6789.694463749143;6795.592264464148;6871.244163142051;6880.965595172092;6903.102596662402;6957.965210173917;6991.2309645937885;7002.964985175042;7072.929270707227;7101.289871013064;6739.764108256188;6805.523613347018;6819.181698196248;6834;6837.145356830396;6907.447404207752;6927.965360173167;6963.547369420971;6985.650717464208;6987.09167808202;6959.930400696131;7025.754098606524;7032.578045317297;7103.2186459488385;7112.786616401516;6523.217213934358;6770.32296770319;6792.083068785848;6850.417714494324;6860.3139686031;6691.498137639599;6713.56361836479;6716.294789047275;6717.529772915849;6719.193696756421;6567.671616419256;6635.834104147435;6644.534882558174;6650.168728908887;6652.334766523502;6745.52781305304;6789.796306110825;6850.314968503109;6872.759453419107;6937.583744975318;6465.482761379172;6541.672916354068;6545.410913017851;6577.3422657735755;6676.866462670817 03:15:07 INFO - PID 11204 | |1;ext.html;827.1769589258688;861.1560670542868;877.8068824858531;883.9204471597559;1145.4730823821042;26733.331666708316;27296.72703272967;39490.40764388533;40055.19889602206;40113.39829902554;40758.59241407585;41164.176716465656;41210.38184427236;41274.79362603183;41361;7086.433085353159;7580.924190758077;7663.731769388072;7727.574983375912;7747.2640099190485;7507.549547027196;7581.431392645546;7613.162552119263;7613.657385417642;7619.123800762928;7533.209013053633;7552.69789208431;7556.319931206191;7556.622168891561;7558.546487210758;7508.436867234952;7585.696572137108;7595.772126836204;7599.202083781234;7602.125755538129;7560.168381478033;7607.885881711779;7610.353119984808;7614.390848732088;7614.581198034106;37196.628033719855;37463.06342341436;37560.248795023945;37628.24743505142;37659.62340376589;3466.6013408457848;3468.9479657805155;3469.7397695172963;3490.4415293553166;3494.755367124302;31263;31370.843145784354;31563.684363156302;31573.684263157535;31597.8420107898;5066;5134.922976155361;5168.22476628505;5199.792008319662;5210.505002024802;193219;193353;194168.02915985472;194672;195287;48809;48857.5114248853;48912.75543622259;48917.266241006415;49153.508464915605;50945.23582146271;50945.74527127341;50967.74516127395;50985.49014509882;51006.489935100915;15233;15461.690766184613;15472.303746331441;15473.071615703095;15475.458358957383;11576.594819181288;11778.11664125194;11979.46092425843;12999.415026323839;13133.802992955114;14264.144151350953;14274.072185307921;14315.355808988621;14346.856531434552;14479.420823166955;5615.185798059259;5845;5845.649261044351;5865.654694316559;5901.17383566301;998.1216529454034;1006.2704539209014;1014.9847752283722;1030.4847576211894;1033.2715435617872;22229.333120006424;28275.71724282772;28382.432351352858;28946.84212631471;29342.413151736844;1086.9510872010778;1103.7847619714078;1106.3251416635846;1106.435717783936;1112.866456025282;12096.516139354504;12096.81854772179;12297.754044919051;12320.81518777219;12374.319412432222;243075;244087;244510;245397;245438;50851.237231441555;50881;51008.48991510112;51091;51105.74447127741;1423.629856237365;1424.2522675595396;1441.8990670652954;1442.5311773673388;1472.2785834940803;22805.543889122455;22913.65629515525;22934.197303094978;23049.423764406023;23235.53528929378;37319.81340093282;37567;37574.43638345372;37625.4356184652;37670.81164594269;209531;209574;210450;210822;211853;33121.84073557414;35612;37241.81379093087;37338.06654833651;37342.439863402615;45310;45633.771831140635;45654.31518527158;45709;45847.541524584325;33036.834815825765;33221.50167747534;33397.49903751397;33789.15527111842;33846;32964.835175823966;33316.83341583276;33398;33509;34004;37530.812345938095;37646.058848529006;37778.62221377861;37904;37990.62009379871;32623.673763262064;32713.34573308568;33149.337013259115;33247.16882077871;33305.50041749327;24174;24508.26475205747;24657.383565411008;24764.752352476244;24813.13154039672 03:15:07 INFO - PID 11204 | |2;jquery.html;562.3532937122309;579.2469789273943;580.3529065092423;586.8122200895713;592.9199558059662;21721.805300708456;22078.22726204583;22894.084236630537;22924.083036678534;22961;25220.36949076274;25731.356716082108;25775.355616109606;25780.74219257807;25867.353316167104;6723.462123030145;6878.828029299258;6887.931120688804;6892.551984121016;6892.827679308032;6317.399847014549;6594.571352862073;6624.23821260554;6689.464842812563;6745.4940879434;6587.209534855824;6589.967050164766;6592.637404942726;6594.07682924391;6595.406413422791;6583.539152259344;6586.440152587036;6587.80236592903;6589.901151482708;6596.8020959370815;6114.755409783604;6115.1744514490865;6116.724747386378;6127.632342059491;6128.080787881809;28548.571771423238;29600.70399296001;29986;30001.549976750368;30115.849420752977;2078.8025137612053;2117.93646190613;2125.075592117418;2131.4458240857334;2135.1245989144377;19487.220511179537;19748.802511974984;20633.277835275694;20660.5867882643;20679.069441875152;2371.537550177716;2428.477877256398;2445.76765207305;2463.285647162321;2492.7881130103965;143960;150967;151073.24463377614;151074;151127.2443637786;14815.333310001075;15052.172130532927;15071.354333226744;15239.695206095816;15486.845131548765;23374.7662523376;23386;23426.7657323428;23431.648525272136;23462.530749384918;9958.850617240747;10118.54466549007;10238.846417303746;10265;10355.430451325099;9659.613615455444;9713.22294216461;9762.072603102693;9847.950760246153;9923.057309555725;8885.866711999326;8934.329925255632;8951.41815781972;8956.552172391355;9058.320625952949;2484.7079518650203;2496.6268552296165;2523.785478234334;2652.2573679369807;2666.480036392885;940.2102234123232;940.8588711693301;968.5641461342424;980.3284749946309;1003.8945910679273;19491.707624385646;19694.409167724993;19694.70457943132;19776.60446791085;20075.096620651813;1040.3757745352727;1059.6715018344337;1062.5802807890839;1062.7608788022633;1065.9680209593726;10582.259241853151;10802.783944321069;10918.290311129751;10918.344899306067;10978.835317470244;513092;536045;545493;546178;550967;112783.43608281905;115984.42007789906;118729;121219;121291;1078.250615822012;1087.141158484804;1139.1171841822654;1145.6849366424133;1203.0976767424431;20684.896575517025;21084.367468975954;21838.126474940826;21931.67102493432;21975.890120549295;56966.43033569777;57802;58403;59706.70146649239;60446.395536044074;68828.65585672039;68969.65515172391;69186.30813691799;69329.65335173292;69608.30391696018;14646.707065858836;14779.334929927963;14910.925445372704;14937.178455185069;14973.251337433303;23364.182253621042;23479.765202347757;23752.881235594403;23926.88036559806;24135.637965430884;18784.15471303822;18865.434036978913;19034.14346354445;19144.425667229447;19177.424677259703;17097.914510427367;17518.124093795006;17727.468175954742;18077.186526606598;18342.541436464195;19629.70555441698;19793.60412791765;19987.100580473623;20006.299779507648;20116.195352186332;14636.268186590842;14990.625234368792;14994.325255363317;14999;15014.77477837855;18526.536836579195;18590.44228673142;18599.07004649789;18623.81376186221;18709.532261693566 03:15:07 INFO - PID 11204 | |3;mootools.html;1299.3762993762994;1356.3557310277615;1365.0649305225916;1382.9170249785013;1383.2253937794835;14909.552713418601;15236.695266094672;15469.149196794171;15506.069635821845;15521.22393880307;15476.303566339513;15979;15986.280617372217;15997.120158391284;16013.039217646949;2782.3044238940215;2820.844853533055;2823.308289469083;2823.435312937421;2823.8446885421295;2646.126778163211;2698.433329000912;2750.4499100179946;2765.3224959884856;2768.861556922156;2743.9451210975867;2760;2765.806393552452;2771.3764403009263;2771.4872748541484;2694.3398867277447;2730.3993121513236;2739.7123302053396;2740.986295068532;2746.986265068682;2689.6368990186465;2695.1510274263546;2723.2783312422225;2723.4280801031805;2723.8365698057983;14920.70158596822;15258.160801155933;15267.541973740805;15270.694586108326;15278.083315001024;8141.1451797561585;8445.915540844575;8451.53516556589;8456.06983231844;8459.746207613782;5181.222816577506;5384.461553844584;5553.250311207976;5635.943640563582;5641.887162256732;1867.2530987605066;1885.1045753267088;1926.4413320137144;1934.0909772406947;1943.0964601460248;26961.595576066375;27420.862895685394;27493.48785816779;27533.724662753317;27542.5868611971;6650.73397064112;6738.7304507819135;6804.285550017276;6878.38094571491;7072.398846098139;17209.139543023048;17219.483415497554;17224;17229.91385043092;17234.741478877826;7966.561839098905;8107.635156417975;8156.0620528639965;8156.755297341089;8163.918360816435;7061.2232654409;7445.9255407445235;7509;7514.135874374517;7521.962390188014;5121.28302037715;5152.896942061137;5187.0922588546855;5246.186841039645;5354.732263386815;1233.0998371188903;1296.293520031585;1438.6834896322791;1444.6821699226152;1453.7019910918236;889.8487257166297;904.8418024927971;911.8221946720325;911.8951320598084;925.167349385545;7456.552606843607;7513.699452021858;7544.396448284124;7567.356774674215;7656.846863062708;987.787625660472;988.0662773678805;988.367444835306;995.2088089968348;996.7059717383245;4594.517575654575;5748.913766293509;5772.9711351442975;5794.420557944256;5945.583809133405;21433.249836255658;22400.103995839985;22683.546329073655;22731.659025114637;22746.09015639389;20838.68741968872;21988;21999.560008799734;22203.333899983027;22231.110755569596;11031.83452248217;11094.778104437866;11351.091912646969;11359.716007099889;11495.540178392937;8344.707935222481;8382.49705017701;8398.454100483448;8412.200840920103;8485.406021578678;18405.907970460063;18901.243950241835;19212.423627291202;19247.23011079541;19289.710654339917;19185;19210.711839322143;19222.32721854784;19356.70964935555;19513.317033903746;11791.88208117908;12253.69365765863;12364.258144511357;12378.071644626509;12383.566575169825;13364.933175334061;13457;13457.730845383234;13458.798118028433;13487.190768553919;11160.386178760245;11206.271592346471;11276.3797991108;11277.266977646426;11440.485178166837;10142.746431339276;10176.18590512772;10221.488925553842;10233.13018393415;10423.42671153094;9373.906260937576;9514.809703805746;9518.904810951803;9521.904780952102;9525.095115963972;7347.963260183665;7374.446916481178;7387.519811212254;7421;7422.331990120915;7223.2776722329445;7326.853462930818;7348.412127029718;7349.889751653836;7450.515716478411 03:15:07 INFO - PID 11204 | |4;prototype.html;511.55239165729984;525.419411550237;540.9188621706745;541.4855886907436;542.2544001997254;6480.805575832726;6499.1876015498065;6512.80461586152;6534.738610455576;6550.443212326958;6369.235691717;6382.585131966419;6411.967940160304;6421.229452465711;6422.486201103912;5275.261463395128;5320.76056577453;5344.171653393731;5347.224652425396;5354.705491197982;4970.478099799505;5165.483451654842;5267.894642107156;5297.549708274802;5326.973365133188;5081.974590127044;5106.514881086291;5135.948640513603;5141.5886729061585;5164.251183578379;5071.290019397269;5118.769655365518;5151.484851514837;5154.587632989352;5159.355080614923;4460.353248778942;4527.456705195397;4566.995261042565;4572.9314060289125;4574.839880604163;6273.435390814803;6276.403741644536;6300.716467758962;6320.905186422207;6344.33384494626;5717.685527295997;5801.332846722638;5804.622699524517;5839.386864379264;5858.736356863951;6737.7978660639765;6756.290589488082;6772.322767723188;6778;6785.355391237874;4943.555080042777;4947.406311242638;4981.676191047571;4989.625778066659;4999.4250661174065;6603.306652801435;6818.4886133540185;6919.965400173017;6958.304169583052;6960.512764106464;4983.103041452501;4991;5011.498850114959;5082.923756143661;5091.949080509222;5076.949230507722;5110.182370820651;5135.974320128375;5171.844844654666;5182.663126896739;5699.629524080921;5748.798792042258;5790.218320526675;5798.420157984167;5861.531077513789;6709.630970296681;6716.932830671729;6731.730730770714;6749.932500675029;6808.93191068083;1771.5571107223195;1779.4163194756677;1800.8829426087264;1804.1881153480986;1822.824278340463;1172.4489489939886;1214.8117041858527;1226.2887525235521;1230.0897335971495;1262.267884626914;5922.496587790086;5925.881482370414;5932.516870782304;5951;5994.610350327215;6644.202695676549;6827.863442731117;6840.016395245422;6845.315468453115;6867.278935711778;5822.767089316465;5920.407959204045;5954.4045595440975;5955.40445955401;6006.819795406145;3621.855125794939;3646.507721457622;3656.1225305926387;3664.670179683841;3676.742628016013;6537.869242615121;6590.9670451647435;6660.567063140978;6700.162479690039;6749.932500675029;6669.333066693389;6825.965870170717;6907.481938854506;6976.686049127801;7047.89428158578;6482.384173503509;6563.442107420923;6620.602763834089;6718.966405168041;6727.730890764413;6836.965815170893;6852.383285504328;6939.618320992394;6949.270326615632;6951.478639102189;6702.899456508254;6814.284500127415;6858.622775747382;6859.862802744017;6901.758438454831;6650.900236496554;6665.966670166618;6696.531242812956;6743.898841517479;6788.185417749902;5082.796688132434;5094.592432605456;5103.92344114846;5130.846074617767;5135.845924622267;6570.277269500256;6813.863722725617;6950.165980082423;6991.881298992137;6996.551207077482;6457.806265812033;6559.376859198369;6601.095517972077;6659.267480577036;6661;6541;6602.933970660232;6663.866722665616;6687.832804179934;6711.3288671132495;6540.476761859135;6564.573302735307;6572.244191917799;6578.243501997332;6598.736050557924;6700.832479187864;6730.562513436611;6730.899036514554;6741.2921643226755;6748.7300507979135;6455.225372955276;6459.4174427265625;6500.349965003651;6516.478681705547;6560.573562718408 03:15:07 INFO - PID 11204 | |5;yui.html;875.0636818604092;891.8751374807528;895.9283257339414;899.6716198587518;913.70761356366;18067.36764213253;18985.62028759424;18998.24007039717;19023.143958521865;21309.36071917845;19871;20759.169633214653;20779;20812.271570495035;21047.158113675436;920.1580553793272;936.180841763457;939.8684184214216;940.2994768897181;940.3182692547882;968.8449848024318;992.602958816472;994.328828041073;996.1433167475965;997.6558087459815;1046.748780292732;1055.2718624149313;1055.7255113670424;1056.1867362131154;1057.1437135919891;1044.7074819050679;1045.2892033417272;1046.1526163807264;1046.6232156423682;1046.6650671784994;775.4571799740204;775.6664634207289;775.7401270574364;775.8176828445311;775.8758598624249;19109.23563057476;19222.423327300203;19483.220671173138;19566.21735130593;19899.403017909484;18577.907110464497;18695.345662901735;18852.151653175504;19059.523511912314;19078.713819292723;16816.32734690611;16836;17079.487615371436;17106.48680539586;17112.31550737969;3686.889393318205;3696.796676182809;3707.888763337104;3719.9628003719886;3720.088578298334;127398.36300818529;129624;129744;129854.3507282467;132390;15586.454474093352;16352.100634465218;16418.507444776675;16443;16518;30167.698323016488;30440.54339184914;30655;30984.690153098632;31109.066727998197;7186.8203294917;7433;8498.957505212435;8800.515971621622;8890.19988201052;4252.978735106305;4315.180115777992;4316.093620339701;4316.395704601358;4349.108432771274;5249.396319423239;5291.3385826771655;5309.203619457112;5342.973285133549;5363.758630861621;1063.2769716592636;1070.8340207267886;1078.8273876179774;1080.0387654987069;1080.3247970018738;667.6061123936901;680.5100327764002;686.3822559696313;687.9621620810904;696.435886931587;11693.766124677459;11824.881751182551;11959;12294.193137615746;12679.429425675868;656.1896058367912;662.3740565165969;666.8399584099878;673.8214373191108;684.5105749389111;5372.328458942632;5518;5566.582506312043;5573.665580065209;5701.600887937888;109017;110793;111668.88331116748;113206;117210;97558.04883902424;97875;99740;102054.48972755088;105217.47391263148;87376.563117184;87729;88507.55746221356;90839.54580227057;91598;10506.422146782;10688.198385120992;10758.300710453796;11003.724906877393;11013.118950484102;40422.79788601038;41029;41711;41716.165676686905;41821;51503.154707659305;51881.7405912968;52248.21627675512;55005.72497137489;55219.44780552143;13441.596752097452;15123.697526049198;15649.608759781097;15700.76448853291;15727.606809829846;16622.3351065956;18653.16060777241;18716.25134994585;19296.517587060436;19666.50833729168;16808.159592020107;17129.657406852042;17137.571560711083;17375.304987800348;17556;17288.740668890227;17528.82471175272;17615.823841761932;17711.645767084843;17781.28874844992;14643.12141271527;14730.852691472948;14775.039622424509;14800.407983680963;18585;12488.25070495773;12687;12697.93651031739;13440.663983400493;13698.65753356174;12931.741365172831;13385.598432047054;13420.932895335462;13432.985052242242;14295.428182872569 03:15:07 INFO - PID 11204 | __end_tp_report 03:15:07 INFO - PID 11204 | __start_cc_report 03:15:07 INFO - PID 11204 | _x_x_mozilla_cycle_collect,369 03:15:07 INFO - PID 11204 | __end_cc_report 03:15:07 INFO - PID 11204 | __startTimestamp1504088107086__endTimestamp 03:15:07 INFO - PID 11204 | 03:15:07 INFO - PID 11204 | ------- Summary: start ------- 03:15:07 INFO - PID 11204 | Number of tests: 6 03:15:07 INFO - PID 11204 | 03:15:07 INFO - PID 11204 | [#0] dojo.html Cycles:165 Average:6376.36 Median:6650.17 stddev:902.35 (13.6%) stddev-sans-first:873.61 03:15:07 INFO - PID 11204 | Values: 3363.6 3369.6 3371.5 3388.7 3406.2 6551.2 6570.4 6593.5 6605.7 6634.6 6387.7 6474.5 6486.0 6518.8 6614.8 5932.8 5970.9 5998.1 6013.3 6030.7 938.5 5643.8 5851.7 5871.1 6070.3 5925.3 5935.3 6006.3 6025.7 6026.1 5928.3 5937.5 6015.1 6015.1 6035.8 5925.4 5936.1 6011.3 6034.5 6044.0 6339.6 6495.7 6515.5 6533.7 6578.0 6841.4 6897.3 6909.0 6928.0 6930.2 6961.3 6966.2 6967.3 7011.6 7087.1 6547.1 6561.4 6571.9 6573.5 6591.4 7200.4 7211.3 7283.9 7285.1 7285.7 6849.7 6877.8 6951.2 6959.0 6979.9 6829.7 6882.3 6948.2 6971.2 7016.7 6974.4 6976.4 6989.7 7010.6 7022.8 6722.7 6824.4 6833.6 7004.8 7046.6 5027.0 5046.3 5050.4 5051.2 5052.2 4463.6 4528.0 4543.3 4543.4 4545.2 6074.6 6118.9 6192.6 6200.6 6207.0 7026.2 7031.5 7052.4 7142.1 7147.5 6137.8 6154.6 6161.1 6164.9 6165.2 6008.2 6022.3 6033.3 6038.0 6048.0 6789.7 6795.6 6871.2 6881.0 6903.1 6958.0 6991.2 7003.0 7072.9 7101.3 6739.8 6805.5 6819.2 6834.0 6837.1 6907.4 6928.0 6963.5 6985.7 6987.1 6959.9 7025.8 7032.6 7103.2 7112.8 6523.2 6770.3 6792.1 6850.4 6860.3 6691.5 6713.6 6716.3 6717.5 6719.2 6567.7 6635.8 6644.5 6650.2 6652.3 6745.5 6789.8 6850.3 6872.8 6937.6 6465.5 6541.7 6545.4 6577.3 6676.9 03:15:07 INFO - PID 11204 | 03:15:07 INFO - PID 11204 | [#1] ext.html Cycles:180 Average:38553.73 Median:3480.09 stddev:56364.82 (1619.6%) stddev-sans-first:56451.75 03:15:07 INFO - PID 11204 | Values: 827.2 861.2 877.8 883.9 1145.5 26733.3 27296.7 39490.4 40055.2 40113.4 40758.6 41164.2 41210.4 41274.8 41361.0 7086.4 7580.9 7663.7 7727.6 7747.3 7507.5 7581.4 7613.2 7613.7 7619.1 7533.2 7552.7 7556.3 7556.6 7558.5 7508.4 7585.7 7595.8 7599.2 7602.1 7560.2 7607.9 7610.4 7614.4 7614.6 37196.6 37463.1 37560.2 37628.2 37659.6 3466.6 3468.9 3469.7 3490.4 3494.8 31263.0 31370.8 31563.7 31573.7 31597.8 5066.0 5134.9 5168.2 5199.8 5210.5 193219.0 193353.0 194168.0 194672.0 195287.0 48809.0 48857.5 48912.8 48917.3 49153.5 50945.2 50945.7 50967.7 50985.5 51006.5 15233.0 15461.7 15472.3 15473.1 15475.5 11576.6 11778.1 11979.5 12999.4 13133.8 14264.1 14274.1 14315.4 14346.9 14479.4 5615.2 5845.0 5845.6 5865.7 5901.2 998.1 1006.3 1015.0 1030.5 1033.3 22229.3 28275.7 28382.4 28946.8 29342.4 1087.0 1103.8 1106.3 1106.4 1112.9 12096.5 12096.8 12297.8 12320.8 12374.3 243075.0 244087.0 244510.0 245397.0 245438.0 50851.2 50881.0 51008.5 51091.0 51105.7 1423.6 1424.3 1441.9 1442.5 1472.3 22805.5 22913.7 22934.2 23049.4 23235.5 37319.8 37567.0 37574.4 37625.4 37670.8 209531.0 209574.0 210450.0 210822.0 211853.0 33121.8 35612.0 37241.8 37338.1 37342.4 45310.0 45633.8 45654.3 45709.0 45847.5 33036.8 33221.5 33397.5 33789.2 33846.0 32964.8 33316.8 33398.0 33509.0 34004.0 37530.8 37646.1 37778.6 37904.0 37990.6 32623.7 32713.3 33149.3 33247.2 33305.5 24174.0 24508.3 24657.4 24764.8 24813.1 03:15:07 INFO - PID 11204 | 03:15:07 INFO - PID 11204 | [#2] jquery.html Cycles:180 Average:36774.50 Median:23369.47 stddev:90472.58 (387.1%) stddev-sans-first:90685.53 03:15:07 INFO - PID 11204 | Values: 562.4 579.2 580.4 586.8 592.9 21721.8 22078.2 22894.1 22924.1 22961.0 25220.4 25731.4 25775.4 25780.7 25867.4 6723.5 6878.8 6887.9 6892.6 6892.8 6317.4 6594.6 6624.2 6689.5 6745.5 6587.2 6590.0 6592.6 6594.1 6595.4 6583.5 6586.4 6587.8 6589.9 6596.8 6114.8 6115.2 6116.7 6127.6 6128.1 28548.6 29600.7 29986.0 30001.5 30115.8 2078.8 2117.9 2125.1 2131.4 2135.1 19487.2 19748.8 20633.3 20660.6 20679.1 2371.5 2428.5 2445.8 2463.3 2492.8 143960.0 150967.0 151073.2 151074.0 151127.2 14815.3 15052.2 15071.4 15239.7 15486.8 23374.8 23386.0 23426.8 23431.6 23462.5 9958.9 10118.5 10238.8 10265.0 10355.4 9659.6 9713.2 9762.1 9848.0 9923.1 8885.9 8934.3 8951.4 8956.6 9058.3 2484.7 2496.6 2523.8 2652.3 2666.5 940.2 940.9 968.6 980.3 1003.9 19491.7 19694.4 19694.7 19776.6 20075.1 1040.4 1059.7 1062.6 1062.8 1066.0 10582.3 10802.8 10918.3 10918.3 10978.8 513092.0 536045.0 545493.0 546178.0 550967.0 112783.4 115984.4 118729.0 121219.0 121291.0 1078.3 1087.1 1139.1 1145.7 1203.1 20684.9 21084.4 21838.1 21931.7 21975.9 56966.4 57802.0 58403.0 59706.7 60446.4 68828.7 68969.7 69186.3 69329.7 69608.3 14646.7 14779.3 14910.9 14937.2 14973.3 23364.2 23479.8 23752.9 23926.9 24135.6 18784.2 18865.4 19034.1 19144.4 19177.4 17097.9 17518.1 17727.5 18077.2 18342.5 19629.7 19793.6 19987.1 20006.3 20116.2 14636.3 14990.6 14994.3 14999.0 15014.8 18526.5 18590.4 18599.1 18623.8 18709.5 03:15:07 INFO - PID 11204 | 03:15:07 INFO - PID 11204 | [#3] mootools.html Cycles:180 Average:9430.92 Median:2740.35 stddev:6822.16 (249.0%) stddev-sans-first:6813.95 03:15:07 INFO - PID 11204 | Values: 1299.4 1356.4 1365.1 1382.9 1383.2 14909.6 15236.7 15469.1 15506.1 15521.2 15476.3 15979.0 15986.3 15997.1 16013.0 2782.3 2820.8 2823.3 2823.4 2823.8 2646.1 2698.4 2750.4 2765.3 2768.9 2743.9 2760.0 2765.8 2771.4 2771.5 2694.3 2730.4 2739.7 2741.0 2747.0 2689.6 2695.2 2723.3 2723.4 2723.8 14920.7 15258.2 15267.5 15270.7 15278.1 8141.1 8445.9 8451.5 8456.1 8459.7 5181.2 5384.5 5553.3 5635.9 5641.9 1867.3 1885.1 1926.4 1934.1 1943.1 26961.6 27420.9 27493.5 27533.7 27542.6 6650.7 6738.7 6804.3 6878.4 7072.4 17209.1 17219.5 17224.0 17229.9 17234.7 7966.6 8107.6 8156.1 8156.8 8163.9 7061.2 7445.9 7509.0 7514.1 7522.0 5121.3 5152.9 5187.1 5246.2 5354.7 1233.1 1296.3 1438.7 1444.7 1453.7 889.8 904.8 911.8 911.9 925.2 7456.6 7513.7 7544.4 7567.4 7656.8 987.8 988.1 988.4 995.2 996.7 4594.5 5748.9 5773.0 5794.4 5945.6 21433.2 22400.1 22683.5 22731.7 22746.1 20838.7 21988.0 21999.6 22203.3 22231.1 11031.8 11094.8 11351.1 11359.7 11495.5 8344.7 8382.5 8398.5 8412.2 8485.4 18405.9 18901.2 19212.4 19247.2 19289.7 19185.0 19210.7 19222.3 19356.7 19513.3 11791.9 12253.7 12364.3 12378.1 12383.6 13364.9 13457.0 13457.7 13458.8 13487.2 11160.4 11206.3 11276.4 11277.3 11440.5 10142.7 10176.2 10221.5 10233.1 10423.4 9373.9 9514.8 9518.9 9521.9 9525.1 7348.0 7374.4 7387.5 7421.0 7422.3 7223.3 7326.9 7348.4 7349.9 7450.5 03:15:07 INFO - PID 11204 | 03:15:07 INFO - PID 11204 | [#4] prototype.html Cycles:180 Average:5642.33 Median:6375.91 stddev:1577.22 (24.7%) stddev-sans-first:1533.91 03:15:07 INFO - PID 11204 | Values: 511.6 525.4 540.9 541.5 542.3 6480.8 6499.2 6512.8 6534.7 6550.4 6369.2 6382.6 6412.0 6421.2 6422.5 5275.3 5320.8 5344.2 5347.2 5354.7 4970.5 5165.5 5267.9 5297.5 5327.0 5082.0 5106.5 5135.9 5141.6 5164.3 5071.3 5118.8 5151.5 5154.6 5159.4 4460.4 4527.5 4567.0 4572.9 4574.8 6273.4 6276.4 6300.7 6320.9 6344.3 5717.7 5801.3 5804.6 5839.4 5858.7 6737.8 6756.3 6772.3 6778.0 6785.4 4943.6 4947.4 4981.7 4989.6 4999.4 6603.3 6818.5 6920.0 6958.3 6960.5 4983.1 4991.0 5011.5 5082.9 5091.9 5076.9 5110.2 5136.0 5171.8 5182.7 5699.6 5748.8 5790.2 5798.4 5861.5 6709.6 6716.9 6731.7 6749.9 6808.9 1771.6 1779.4 1800.9 1804.2 1822.8 1172.4 1214.8 1226.3 1230.1 1262.3 5922.5 5925.9 5932.5 5951.0 5994.6 6644.2 6827.9 6840.0 6845.3 6867.3 5822.8 5920.4 5954.4 5955.4 6006.8 3621.9 3646.5 3656.1 3664.7 3676.7 6537.9 6591.0 6660.6 6700.2 6749.9 6669.3 6826.0 6907.5 6976.7 7047.9 6482.4 6563.4 6620.6 6719.0 6727.7 6837.0 6852.4 6939.6 6949.3 6951.5 6702.9 6814.3 6858.6 6859.9 6901.8 6650.9 6666.0 6696.5 6743.9 6788.2 5082.8 5094.6 5103.9 5130.8 5135.8 6570.3 6813.9 6950.2 6991.9 6996.6 6457.8 6559.4 6601.1 6659.3 6661.0 6541.0 6602.9 6663.9 6687.8 6711.3 6540.5 6564.6 6572.2 6578.2 6598.7 6700.8 6730.6 6730.9 6741.3 6748.7 6455.2 6459.4 6500.3 6516.5 6560.6 03:15:07 INFO - PID 11204 | 03:15:07 INFO - PID 11204 | [#5] yui.html Cycles:180 Average:23253.03 Median:19259.47 stddev:32520.57 (168.9%) stddev-sans-first:32568.38 03:15:07 INFO - PID 11204 | Values: 875.1 891.9 895.9 899.7 913.7 18067.4 18985.6 18998.2 19023.1 21309.4 19871.0 20759.2 20779.0 20812.3 21047.2 920.2 936.2 939.9 940.3 940.3 968.8 992.6 994.3 996.1 997.7 1046.7 1055.3 1055.7 1056.2 1057.1 1044.7 1045.3 1046.2 1046.6 1046.7 775.5 775.7 775.7 775.8 775.9 19109.2 19222.4 19483.2 19566.2 19899.4 18577.9 18695.3 18852.2 19059.5 19078.7 16816.3 16836.0 17079.5 17106.5 17112.3 3686.9 3696.8 3707.9 3720.0 3720.1 127398.4 129624.0 129744.0 129854.4 132390.0 15586.5 16352.1 16418.5 16443.0 16518.0 30167.7 30440.5 30655.0 30984.7 31109.1 7186.8 7433.0 8499.0 8800.5 8890.2 4253.0 4315.2 4316.1 4316.4 4349.1 5249.4 5291.3 5309.2 5343.0 5363.8 1063.3 1070.8 1078.8 1080.0 1080.3 667.6 680.5 686.4 688.0 696.4 11693.8 11824.9 11959.0 12294.2 12679.4 656.2 662.4 666.8 673.8 684.5 5372.3 5518.0 5566.6 5573.7 5701.6 109017.0 110793.0 111668.9 113206.0 117210.0 97558.0 97875.0 99740.0 102054.5 105217.5 87376.6 87729.0 88507.6 90839.5 91598.0 10506.4 10688.2 10758.3 11003.7 11013.1 40422.8 41029.0 41711.0 41716.2 41821.0 51503.2 51881.7 52248.2 55005.7 55219.4 13441.6 15123.7 15649.6 15700.8 15727.6 16622.3 18653.2 18716.3 19296.5 19666.5 16808.2 17129.7 17137.6 17375.3 17556.0 17288.7 17528.8 17615.8 17711.6 17781.3 14643.1 14730.9 14775.0 14800.4 18585.0 12488.3 12687.0 12697.9 13440.7 13698.7 12931.7 13385.6 13420.9 13433.0 14295.4 03:15:07 INFO - PID 11204 | -------- Summary: end -------- 03:15:07 INFO - PID 11204 | 03:15:07 INFO - TEST-INFO | 11204: exit 0 03:15:07 INFO - TEST-OK | dromaeo_css | took 1110578ms 03:15:07 INFO - TEST-START | kraken 03:15:07 INFO - Initialising browser for kraken test... 03:15:07 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:38787/getInfo.html -profile /tmp/tmpujQcw9/profile 03:15:07 INFO - TEST-INFO | started process 11575 (/builds/slave/test/build/application/firefox/firefox http://localhost:38787/getInfo.html) 03:15:12 INFO - TEST-INFO | 11575: exit 0 03:15:13 INFO - Browser initialized. 03:15:13 INFO - Running cycle 1/1 for kraken test... 03:15:13 INFO - Using env: {'DISPLAY': ':0', 03:15:13 INFO - 'HOME': '/home/cltbld', 03:15:13 INFO - 'JSGC_DISABLE_POISONING': '1', 03:15:13 INFO - 'LANG': 'en_US.UTF-8', 03:15:13 INFO - 'LANGUAGE': 'en_US:en', 03:15:13 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 03:15:13 INFO - 'LOGNAME': 'cltbld', 03:15:13 INFO - 'MAIL': '/var/mail/cltbld', 03:15:13 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:15:13 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:15:13 INFO - 'MOZ_CRASHREPORTER': '1', 03:15:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:15:13 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 03:15:13 INFO - 'MOZ_NO_REMOTE': '1', 03:15:13 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:15:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:15:13 INFO - 'NO_EM_RESTART': '1', 03:15:13 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:15:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:15:13 INFO - 'PWD': '/builds/slave/test', 03:15:13 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:15:13 INFO - 'RUST_BACKTRACE': 'full', 03:15:13 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 03:15:13 INFO - 'SHELL': '/bin/bash', 03:15:13 INFO - 'SHLVL': '1', 03:15:13 INFO - 'TERM': 'linux', 03:15:13 INFO - 'TMOUT': '86400', 03:15:13 INFO - 'USER': 'cltbld', 03:15:13 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1504084713.229460-263559356', 03:15:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:15:13 INFO - '_': '/tools/buildbot/bin/python'} 03:15:13 INFO - TEST-INFO | started process 11759 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpujQcw9/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 03:15:13 INFO - PID 11759 | 03:15:13 INFO - PID 11759 | (/builds/slave/test/build/application/firefox/firefox:11810): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 03:15:13 INFO - PID 11759 | 03:15:24 INFO - PID 11759 | RSS: Main: 169807872 03:15:24 INFO - PID 11759 | 03:15:26 INFO - PID 11759 | Cycle 1(1): loaded http://localhost:38787/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:38787/tests/kraken/driver.html?testName=audio-beat-detection) 03:15:27 INFO - PID 11759 | RSS: Main: 174870528 03:15:27 INFO - PID 11759 | 03:15:30 INFO - PID 11759 | Cycle 1(1): loaded http://localhost:38787/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:38787/tests/kraken/driver.html?testName=audio-dft) 03:15:31 INFO - PID 11759 | RSS: Main: 174878720 03:15:31 INFO - PID 11759 | 03:15:34 INFO - PID 11759 | Cycle 1(1): loaded http://localhost:38787/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:38787/tests/kraken/driver.html?testName=audio-fft) 03:15:34 INFO - PID 11759 | RSS: Main: 169394176 03:15:34 INFO - PID 11759 | 03:15:36 INFO - PID 11759 | Cycle 1(1): loaded http://localhost:38787/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:38787/tests/kraken/driver.html?testName=audio-oscillator) 03:15:37 INFO - PID 11759 | RSS: Main: 169930752 03:15:37 INFO - PID 11759 | 03:15:39 INFO - PID 11759 | Cycle 1(1): loaded http://localhost:38787/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:38787/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:15:40 INFO - PID 11759 | RSS: Main: 170729472 03:15:40 INFO - PID 11759 | 03:15:43 INFO - PID 11759 | Cycle 1(1): loaded http://localhost:38787/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:38787/tests/kraken/driver.html?testName=imaging-darkroom) 03:15:43 INFO - PID 11759 | RSS: Main: 173428736 03:15:43 INFO - PID 11759 | 03:15:47 INFO - PID 11759 | Cycle 1(1): loaded http://localhost:38787/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:38787/tests/kraken/driver.html?testName=imaging-desaturate) 03:15:48 INFO - PID 11759 | RSS: Main: 173228032 03:15:48 INFO - PID 11759 | 03:15:50 INFO - PID 11759 | Cycle 1(1): loaded http://localhost:38787/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:38787/tests/kraken/driver.html?testName=json-parse-financial) 03:15:51 INFO - PID 11759 | RSS: Main: 172945408 03:15:51 INFO - PID 11759 | 03:15:53 INFO - PID 11759 | Cycle 1(1): loaded http://localhost:38787/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:38787/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:15:53 INFO - PID 11759 | RSS: Main: 172892160 03:15:53 INFO - PID 11759 | 03:15:55 INFO - PID 11759 | Cycle 1(1): loaded http://localhost:38787/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:38787/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:15:56 INFO - PID 11759 | RSS: Main: 173096960 03:15:56 INFO - PID 11759 | 03:15:58 INFO - PID 11759 | Cycle 1(1): loaded http://localhost:38787/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:38787/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:15:58 INFO - PID 11759 | RSS: Main: 170467328 03:15:58 INFO - PID 11759 | 03:16:01 INFO - PID 11759 | Cycle 1(1): loaded http://localhost:38787/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:38787/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:16:02 INFO - PID 11759 | RSS: Main: 170635264 03:16:02 INFO - PID 11759 | 03:16:05 INFO - PID 11759 | Cycle 1(1): loaded http://localhost:38787/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:38787/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:16:05 INFO - PID 11759 | RSS: Main: 170983424 03:16:05 INFO - PID 11759 | 03:16:07 INFO - PID 11759 | Cycle 1(1): loaded http://localhost:38787/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:38787/tests/kraken/driver.html?testName=ai-astar) 03:16:07 INFO - PID 11759 | RSS: Main: 172449792 03:16:07 INFO - PID 11759 | 03:16:07 INFO - PID 11759 | __start_tp_report 03:16:07 INFO - PID 11759 | _x_x_mozilla_page_load 03:16:07 INFO - PID 11759 | _x_x_mozilla_page_load_details 03:16:07 INFO - PID 11759 | |i|pagename|runs| 03:16:07 INFO - PID 11759 | |0;ai-astar;96;109;101;110;107;112;109;98;107;110 03:16:07 INFO - PID 11759 | |1;audio-beat-detection;141;123;147;182;117;140;145;118;166;116 03:16:07 INFO - PID 11759 | |2;audio-dft;163;162;161;162;163;163;162;169;171;162 03:16:07 INFO - PID 11759 | |3;audio-fft;74;74;73;180;73;73;73;74;150;76 03:16:07 INFO - PID 11759 | |4;audio-oscillator;78;78;128;79;79;80;133;78;79;124 03:16:07 INFO - PID 11759 | |5;imaging-gaussian-blur;113;114;113;113;114;114;113;117;117;117 03:16:07 INFO - PID 11759 | |6;imaging-darkroom;149;148;148;148;147;148;148;147;149;156 03:16:07 INFO - PID 11759 | |7;imaging-desaturate;94;94;94;93;94;93;93;94;94;94 03:16:07 INFO - PID 11759 | |8;json-parse-financial;52;54;54;52;54;52;53;53;53;51 03:16:07 INFO - PID 11759 | |9;json-stringify-tinderbox;38;38;42;38;38;38;39;38;38;39 03:16:07 INFO - PID 11759 | |10;stanford-crypto-aes;75;77;75;77;77;74;74;74;75;78 03:16:07 INFO - PID 11759 | |11;stanford-crypto-ccm;129;121;126;118;115;117;118;119;118;172 03:16:07 INFO - PID 11759 | |12;stanford-crypto-pbkdf2;171;173;172;170;173;172;179;169;170;168 03:16:07 INFO - PID 11759 | |13;stanford-crypto-sha256-iterative;68;65;66;67;65;64;65;65;64;63 03:16:07 INFO - PID 11759 | __end_tp_report 03:16:07 INFO - PID 11759 | __start_cc_report 03:16:07 INFO - PID 11759 | _x_x_mozilla_cycle_collect,926 03:16:07 INFO - PID 11759 | __end_cc_report 03:16:07 INFO - PID 11759 | __startTimestamp1504088167619__endTimestamp 03:16:07 INFO - PID 11759 | 03:16:07 INFO - PID 11759 | ------- Summary: start ------- 03:16:07 INFO - PID 11759 | Number of tests: 14 03:16:07 INFO - PID 11759 | 03:16:07 INFO - PID 11759 | [#0] ai-astar Cycles:10 Average:105.90 Median:110.00 stddev:5.55 (5.0%) stddev-sans-first:4.58 03:16:07 INFO - PID 11759 | Values: 96.0 109.0 101.0 110.0 107.0 112.0 109.0 98.0 107.0 110.0 03:16:07 INFO - PID 11759 | 03:16:07 INFO - PID 11759 | [#1] audio-beat-detection Cycles:10 Average:139.50 Median:143.00 stddev:22.09 (15.4%) stddev-sans-first:23.42 03:16:07 INFO - PID 11759 | Values: 141.0 123.0 147.0 182.0 117.0 140.0 145.0 118.0 166.0 116.0 03:16:07 INFO - PID 11759 | 03:16:07 INFO - PID 11759 | [#2] audio-dft Cycles:10 Average:163.80 Median:163.00 stddev:3.36 (2.1%) stddev-sans-first:3.55 03:16:07 INFO - PID 11759 | Values: 163.0 162.0 161.0 162.0 163.0 163.0 162.0 169.0 171.0 162.0 03:16:07 INFO - PID 11759 | 03:16:07 INFO - PID 11759 | [#3] audio-fft Cycles:10 Average:92.00 Median:73.50 stddev:39.13 (53.2%) stddev-sans-first:40.96 03:16:07 INFO - PID 11759 | Values: 74.0 74.0 73.0 180.0 73.0 73.0 73.0 74.0 150.0 76.0 03:16:07 INFO - PID 11759 | 03:16:07 INFO - PID 11759 | [#4] audio-oscillator Cycles:10 Average:93.60 Median:78.50 stddev:24.07 (30.7%) stddev-sans-first:24.86 03:16:07 INFO - PID 11759 | Values: 78.0 78.0 128.0 79.0 79.0 80.0 133.0 78.0 79.0 124.0 03:16:07 INFO - PID 11759 | 03:16:07 INFO - PID 11759 | [#5] imaging-gaussian-blur Cycles:10 Average:114.50 Median:114.00 stddev:1.78 (1.6%) stddev-sans-first:1.80 03:16:07 INFO - PID 11759 | Values: 113.0 114.0 113.0 113.0 114.0 114.0 113.0 117.0 117.0 117.0 03:16:07 INFO - PID 11759 | 03:16:07 INFO - PID 11759 | [#6] imaging-darkroom Cycles:10 Average:148.80 Median:148.00 stddev:2.62 (1.8%) stddev-sans-first:2.77 03:16:07 INFO - PID 11759 | Values: 149.0 148.0 148.0 148.0 147.0 148.0 148.0 147.0 149.0 156.0 03:16:07 INFO - PID 11759 | 03:16:07 INFO - PID 11759 | [#7] imaging-desaturate Cycles:10 Average:93.70 Median:94.00 stddev:0.48 (0.5%) stddev-sans-first:0.50 03:16:07 INFO - PID 11759 | Values: 94.0 94.0 94.0 93.0 94.0 93.0 93.0 94.0 94.0 94.0 03:16:07 INFO - PID 11759 | 03:16:07 INFO - PID 11759 | [#8] json-parse-financial Cycles:10 Average:52.80 Median:53.00 stddev:1.03 (1.9%) stddev-sans-first:1.05 03:16:07 INFO - PID 11759 | Values: 52.0 54.0 54.0 52.0 54.0 52.0 53.0 53.0 53.0 51.0 03:16:07 INFO - PID 11759 | 03:16:07 INFO - PID 11759 | [#9] json-stringify-tinderbox Cycles:10 Average:38.60 Median:38.00 stddev:1.26 (3.3%) stddev-sans-first:1.32 03:16:07 INFO - PID 11759 | Values: 38.0 38.0 42.0 38.0 38.0 38.0 39.0 38.0 38.0 39.0 03:16:07 INFO - PID 11759 | 03:16:07 INFO - PID 11759 | [#10] stanford-crypto-aes Cycles:10 Average:75.60 Median:76.00 stddev:1.51 (2.0%) stddev-sans-first:1.58 03:16:07 INFO - PID 11759 | Values: 75.0 77.0 75.0 77.0 77.0 74.0 74.0 74.0 75.0 78.0 03:16:07 INFO - PID 11759 | 03:16:07 INFO - PID 11759 | [#11] stanford-crypto-ccm Cycles:10 Average:125.30 Median:120.00 stddev:16.96 (14.1%) stddev-sans-first:17.93 03:16:07 INFO - PID 11759 | Values: 129.0 121.0 126.0 118.0 115.0 117.0 118.0 119.0 118.0 172.0 03:16:07 INFO - PID 11759 | 03:16:07 INFO - PID 11759 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:171.70 Median:172.00 stddev:3.06 (1.8%) stddev-sans-first:3.23 03:16:07 INFO - PID 11759 | Values: 171.0 173.0 172.0 170.0 173.0 172.0 179.0 169.0 170.0 168.0 03:16:07 INFO - PID 11759 | 03:16:07 INFO - PID 11759 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.20 Median:65.00 stddev:1.48 (2.3%) stddev-sans-first:1.17 03:16:07 INFO - PID 11759 | Values: 68.0 65.0 66.0 67.0 65.0 64.0 65.0 65.0 64.0 63.0 03:16:07 INFO - PID 11759 | -------- Summary: end -------- 03:16:07 INFO - PID 11759 | 03:16:08 INFO - PID 11759 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 03:16:08 INFO - TEST-INFO | 11759: exit 0 03:16:08 INFO - TEST-OK | kraken | took 60531ms 03:16:08 INFO - SUITE-END | took 1171s 03:16:08 INFO - Completed test suite (00:19:31) 03:16:08 INFO - javascript benchmark 03:16:08 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8628.135303987732, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3363.5627368442097, 3369.600923778331, 3371.4942758586208, 3388.7289016878644, 3406.1825161961137, 6551.181102362205, 6570.44151247144, 6593.505487088476, 6605.702743376548, 6634.635095069769, 6387.680615969194, 6474.482041436686, 6486.0, 6518.804435866919, 6614.834629134274, 5932.822015339547, 5970.850728731773, 5998.10028495727, 6013.278406591205, 6030.66831324277, 938.5077204883722, 5643.830685079454, 5851.7074146292725, 5871.060630299153, 6070.271567411917, 5925.259342582177, 5935.347111817697, 6006.339302676702, 6025.668588227668, 6026.06595977622, 5928.318243402021, 5937.52499800015, 6015.09773533969, 6015.127806468081, 6035.78874739382, 5925.4370834770625, 5936.109583562457, 6011.30869949957, 6034.517238620943, 6044.032954727222, 6339.619622822599, 6495.6752162391695, 6515.4787616990525, 6533.705983230719, 6578.0, 6841.3842754151865, 6897.2757860424435, 6908.965455172743, 6927.965360173167, 6930.237673855871, 6961.303869612998, 6966.198887127995, 6967.303269673042, 7011.579305241651, 7087.078679771596, 6547.148870646833, 6561.40947314744, 6571.901421478682, 6573.474122070223, 6591.40677339037, 7200.387967022862, 7211.314925082109, 7283.890741638879, 7285.125784905846, 7285.744998925013, 6849.657517124124, 6877.828054298682, 6951.200611929693, 6959.0, 6979.8604027919155, 6829.692663830139, 6882.277360877136, 6948.235694073648, 6971.198312194061, 7016.7193312267955, 6974.407175390047, 6976.372126508537, 6989.720411183597, 7010.579365238102, 7022.789316320519, 6722.731090756315, 6824.351686589766, 6833.6241506716615, 7004.789856304319, 7046.61243631595, 5026.974865125651, 5046.293518907356, 5050.368703912011, 5051.166557517969, 5052.1663925451885, 4463.598276155097, 4528.026474307973, 4543.34121552373, 4543.386642803245, 4545.227311357077, 6074.635521868702, 6118.938810611837, 6192.628442293477, 6200.56596038273, 6206.968965155145, 7026.227115017344, 7031.507794454441, 7052.400545953651, 7142.142942846892, 7147.53541019832, 6137.754489820447, 6154.599951003171, 6161.137440758297, 6164.907526387108, 6165.167702360213, 6008.21893153896, 6022.277326720821, 6033.276006879202, 6037.969810151009, 6048.032314829607, 6789.694463749143, 6795.592264464148, 6871.244163142051, 6880.965595172092, 6903.102596662402, 6957.965210173917, 6991.2309645937885, 7002.964985175042, 7072.929270707227, 7101.289871013064, 6739.764108256188, 6805.523613347018, 6819.181698196248, 6834.0, 6837.145356830396, 6907.447404207752, 6927.965360173167, 6963.547369420971, 6985.650717464208, 6987.09167808202, 6959.930400696131, 7025.754098606524, 7032.578045317297, 7103.2186459488385, 7112.786616401516, 6523.217213934358, 6770.32296770319, 6792.083068785848, 6850.417714494324, 6860.3139686031, 6691.498137639599, 6713.56361836479, 6716.294789047275, 6717.529772915849, 6719.193696756421, 6567.671616419256, 6635.834104147435, 6644.534882558174, 6650.168728908887, 6652.334766523502, 6745.52781305304, 6789.796306110825, 6850.314968503109, 6872.759453419107, 6937.583744975318, 6465.482761379172, 6541.672916354068, 6545.410913017851, 6577.3422657735755, 6676.866462670817], "value": 6309.157655389047, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [827.1769589258688, 861.1560670542868, 877.8068824858531, 883.9204471597559, 1145.4730823821042, 26733.331666708316, 27296.72703272967, 39490.40764388533, 40055.19889602206, 40113.39829902554, 40758.59241407585, 41164.176716465656, 41210.38184427236, 41274.79362603183, 41361.0, 7086.433085353159, 7580.924190758077, 7663.731769388072, 7727.574983375912, 7747.2640099190485, 7507.549547027196, 7581.431392645546, 7613.162552119263, 7613.657385417642, 7619.123800762928, 7533.209013053633, 7552.69789208431, 7556.319931206191, 7556.622168891561, 7558.546487210758, 7508.436867234952, 7585.696572137108, 7595.772126836204, 7599.202083781234, 7602.125755538129, 7560.168381478033, 7607.885881711779, 7610.353119984808, 7614.390848732088, 7614.581198034106, 37196.628033719855, 37463.06342341436, 37560.248795023945, 37628.24743505142, 37659.62340376589, 3466.6013408457848, 3468.9479657805155, 3469.7397695172963, 3490.4415293553166, 3494.755367124302, 31263.0, 31370.843145784354, 31563.684363156302, 31573.684263157535, 31597.8420107898, 5066.0, 5134.922976155361, 5168.22476628505, 5199.792008319662, 5210.505002024802, 193219.0, 193353.0, 194168.02915985472, 194672.0, 195287.0, 48809.0, 48857.5114248853, 48912.75543622259, 48917.266241006415, 49153.508464915605, 50945.23582146271, 50945.74527127341, 50967.74516127395, 50985.49014509882, 51006.489935100915, 15233.0, 15461.690766184613, 15472.303746331441, 15473.071615703095, 15475.458358957383, 11576.594819181288, 11778.11664125194, 11979.46092425843, 12999.415026323839, 13133.802992955114, 14264.144151350953, 14274.072185307921, 14315.355808988621, 14346.856531434552, 14479.420823166955, 5615.185798059259, 5845.0, 5845.649261044351, 5865.654694316559, 5901.17383566301, 998.1216529454034, 1006.2704539209014, 1014.9847752283722, 1030.4847576211894, 1033.2715435617872, 22229.333120006424, 28275.71724282772, 28382.432351352858, 28946.84212631471, 29342.413151736844, 1086.9510872010778, 1103.7847619714078, 1106.3251416635846, 1106.435717783936, 1112.866456025282, 12096.516139354504, 12096.81854772179, 12297.754044919051, 12320.81518777219, 12374.319412432222, 243075.0, 244087.0, 244510.0, 245397.0, 245438.0, 50851.237231441555, 50881.0, 51008.48991510112, 51091.0, 51105.74447127741, 1423.629856237365, 1424.2522675595396, 1441.8990670652954, 1442.5311773673388, 1472.2785834940803, 22805.543889122455, 22913.65629515525, 22934.197303094978, 23049.423764406023, 23235.53528929378, 37319.81340093282, 37567.0, 37574.43638345372, 37625.4356184652, 37670.81164594269, 209531.0, 209574.0, 210450.0, 210822.0, 211853.0, 33121.84073557414, 35612.0, 37241.81379093087, 37338.06654833651, 37342.439863402615, 45310.0, 45633.771831140635, 45654.31518527158, 45709.0, 45847.541524584325, 33036.834815825765, 33221.50167747534, 33397.49903751397, 33789.15527111842, 33846.0, 32964.835175823966, 33316.83341583276, 33398.0, 33509.0, 34004.0, 37530.812345938095, 37646.058848529006, 37778.62221377861, 37904.0, 37990.62009379871, 32623.673763262064, 32713.34573308568, 33149.337013259115, 33247.16882077871, 33305.50041749327, 24174.0, 24508.26475205747, 24657.383565411008, 24764.752352476244, 24813.13154039672], "value": 17298.789668635465, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [562.3532937122309, 579.2469789273943, 580.3529065092423, 586.8122200895713, 592.9199558059662, 21721.805300708456, 22078.22726204583, 22894.084236630537, 22924.083036678534, 22961.0, 25220.36949076274, 25731.356716082108, 25775.355616109606, 25780.74219257807, 25867.353316167104, 6723.462123030145, 6878.828029299258, 6887.931120688804, 6892.551984121016, 6892.827679308032, 6317.399847014549, 6594.571352862073, 6624.23821260554, 6689.464842812563, 6745.4940879434, 6587.209534855824, 6589.967050164766, 6592.637404942726, 6594.07682924391, 6595.406413422791, 6583.539152259344, 6586.440152587036, 6587.80236592903, 6589.901151482708, 6596.8020959370815, 6114.755409783604, 6115.1744514490865, 6116.724747386378, 6127.632342059491, 6128.080787881809, 28548.571771423238, 29600.70399296001, 29986.0, 30001.549976750368, 30115.849420752977, 2078.8025137612053, 2117.93646190613, 2125.075592117418, 2131.4458240857334, 2135.1245989144377, 19487.220511179537, 19748.802511974984, 20633.277835275694, 20660.5867882643, 20679.069441875152, 2371.537550177716, 2428.477877256398, 2445.76765207305, 2463.285647162321, 2492.7881130103965, 143960.0, 150967.0, 151073.24463377614, 151074.0, 151127.2443637786, 14815.333310001075, 15052.172130532927, 15071.354333226744, 15239.695206095816, 15486.845131548765, 23374.7662523376, 23386.0, 23426.7657323428, 23431.648525272136, 23462.530749384918, 9958.850617240747, 10118.54466549007, 10238.846417303746, 10265.0, 10355.430451325099, 9659.613615455444, 9713.22294216461, 9762.072603102693, 9847.950760246153, 9923.057309555725, 8885.866711999326, 8934.329925255632, 8951.41815781972, 8956.552172391355, 9058.320625952949, 2484.7079518650203, 2496.6268552296165, 2523.785478234334, 2652.2573679369807, 2666.480036392885, 940.2102234123232, 940.8588711693301, 968.5641461342424, 980.3284749946309, 1003.8945910679273, 19491.707624385646, 19694.409167724993, 19694.70457943132, 19776.60446791085, 20075.096620651813, 1040.3757745352727, 1059.6715018344337, 1062.5802807890839, 1062.7608788022633, 1065.9680209593726, 10582.259241853151, 10802.783944321069, 10918.290311129751, 10918.344899306067, 10978.835317470244, 513092.0, 536045.0, 545493.0, 546178.0, 550967.0, 112783.43608281905, 115984.42007789906, 118729.0, 121219.0, 121291.0, 1078.250615822012, 1087.141158484804, 1139.1171841822654, 1145.6849366424133, 1203.0976767424431, 20684.896575517025, 21084.367468975954, 21838.126474940826, 21931.67102493432, 21975.890120549295, 56966.43033569777, 57802.0, 58403.0, 59706.70146649239, 60446.395536044074, 68828.65585672039, 68969.65515172391, 69186.30813691799, 69329.65335173292, 69608.30391696018, 14646.707065858836, 14779.334929927963, 14910.925445372704, 14937.178455185069, 14973.251337433303, 23364.182253621042, 23479.765202347757, 23752.881235594403, 23926.88036559806, 24135.637965430884, 18784.15471303822, 18865.434036978913, 19034.14346354445, 19144.425667229447, 19177.424677259703, 17097.914510427367, 17518.124093795006, 17727.468175954742, 18077.186526606598, 18342.541436464195, 19629.70555441698, 19793.60412791765, 19987.100580473623, 20006.299779507648, 20116.195352186332, 14636.268186590842, 14990.625234368792, 14994.325255363317, 14999.0, 15014.77477837855, 18526.536836579195, 18590.44228673142, 18599.07004649789, 18623.81376186221, 18709.532261693566], "value": 12223.291154437375, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1299.3762993762994, 1356.3557310277615, 1365.0649305225916, 1382.9170249785013, 1383.2253937794835, 14909.552713418601, 15236.695266094672, 15469.149196794171, 15506.069635821845, 15521.22393880307, 15476.303566339513, 15979.0, 15986.280617372217, 15997.120158391284, 16013.039217646949, 2782.3044238940215, 2820.844853533055, 2823.308289469083, 2823.435312937421, 2823.8446885421295, 2646.126778163211, 2698.433329000912, 2750.4499100179946, 2765.3224959884856, 2768.861556922156, 2743.9451210975867, 2760.0, 2765.806393552452, 2771.3764403009263, 2771.4872748541484, 2694.3398867277447, 2730.3993121513236, 2739.7123302053396, 2740.986295068532, 2746.986265068682, 2689.6368990186465, 2695.1510274263546, 2723.2783312422225, 2723.4280801031805, 2723.8365698057983, 14920.70158596822, 15258.160801155933, 15267.541973740805, 15270.694586108326, 15278.083315001024, 8141.1451797561585, 8445.915540844575, 8451.53516556589, 8456.06983231844, 8459.746207613782, 5181.222816577506, 5384.461553844584, 5553.250311207976, 5635.943640563582, 5641.887162256732, 1867.2530987605066, 1885.1045753267088, 1926.4413320137144, 1934.0909772406947, 1943.0964601460248, 26961.595576066375, 27420.862895685394, 27493.48785816779, 27533.724662753317, 27542.5868611971, 6650.73397064112, 6738.7304507819135, 6804.285550017276, 6878.38094571491, 7072.398846098139, 17209.139543023048, 17219.483415497554, 17224.0, 17229.91385043092, 17234.741478877826, 7966.561839098905, 8107.635156417975, 8156.0620528639965, 8156.755297341089, 8163.918360816435, 7061.2232654409, 7445.9255407445235, 7509.0, 7514.135874374517, 7521.962390188014, 5121.28302037715, 5152.896942061137, 5187.0922588546855, 5246.186841039645, 5354.732263386815, 1233.0998371188903, 1296.293520031585, 1438.6834896322791, 1444.6821699226152, 1453.7019910918236, 889.8487257166297, 904.8418024927971, 911.8221946720325, 911.8951320598084, 925.167349385545, 7456.552606843607, 7513.699452021858, 7544.396448284124, 7567.356774674215, 7656.846863062708, 987.787625660472, 988.0662773678805, 988.367444835306, 995.2088089968348, 996.7059717383245, 4594.517575654575, 5748.913766293509, 5772.9711351442975, 5794.420557944256, 5945.583809133405, 21433.249836255658, 22400.103995839985, 22683.546329073655, 22731.659025114637, 22746.09015639389, 20838.68741968872, 21988.0, 21999.560008799734, 22203.333899983027, 22231.110755569596, 11031.83452248217, 11094.778104437866, 11351.091912646969, 11359.716007099889, 11495.540178392937, 8344.707935222481, 8382.49705017701, 8398.454100483448, 8412.200840920103, 8485.406021578678, 18405.907970460063, 18901.243950241835, 19212.423627291202, 19247.23011079541, 19289.710654339917, 19185.0, 19210.711839322143, 19222.32721854784, 19356.70964935555, 19513.317033903746, 11791.88208117908, 12253.69365765863, 12364.258144511357, 12378.071644626509, 12383.566575169825, 13364.933175334061, 13457.0, 13457.730845383234, 13458.798118028433, 13487.190768553919, 11160.386178760245, 11206.271592346471, 11276.3797991108, 11277.266977646426, 11440.485178166837, 10142.746431339276, 10176.18590512772, 10221.488925553842, 10233.13018393415, 10423.42671153094, 9373.906260937576, 9514.809703805746, 9518.904810951803, 9521.904780952102, 9525.095115963972, 7347.963260183665, 7374.446916481178, 7387.519811212254, 7421.0, 7422.331990120915, 7223.2776722329445, 7326.853462930818, 7348.412127029718, 7349.889751653836, 7450.515716478411], "value": 6718.917839428205, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [511.55239165729984, 525.419411550237, 540.9188621706745, 541.4855886907436, 542.2544001997254, 6480.805575832726, 6499.1876015498065, 6512.80461586152, 6534.738610455576, 6550.443212326958, 6369.235691717, 6382.585131966419, 6411.967940160304, 6421.229452465711, 6422.486201103912, 5275.261463395128, 5320.76056577453, 5344.171653393731, 5347.224652425396, 5354.705491197982, 4970.478099799505, 5165.483451654842, 5267.894642107156, 5297.549708274802, 5326.973365133188, 5081.974590127044, 5106.514881086291, 5135.948640513603, 5141.5886729061585, 5164.251183578379, 5071.290019397269, 5118.769655365518, 5151.484851514837, 5154.587632989352, 5159.355080614923, 4460.353248778942, 4527.456705195397, 4566.995261042565, 4572.9314060289125, 4574.839880604163, 6273.435390814803, 6276.403741644536, 6300.716467758962, 6320.905186422207, 6344.33384494626, 5717.685527295997, 5801.332846722638, 5804.622699524517, 5839.386864379264, 5858.736356863951, 6737.7978660639765, 6756.290589488082, 6772.322767723188, 6778.0, 6785.355391237874, 4943.555080042777, 4947.406311242638, 4981.676191047571, 4989.625778066659, 4999.4250661174065, 6603.306652801435, 6818.4886133540185, 6919.965400173017, 6958.304169583052, 6960.512764106464, 4983.103041452501, 4991.0, 5011.498850114959, 5082.923756143661, 5091.949080509222, 5076.949230507722, 5110.182370820651, 5135.974320128375, 5171.844844654666, 5182.663126896739, 5699.629524080921, 5748.798792042258, 5790.218320526675, 5798.420157984167, 5861.531077513789, 6709.630970296681, 6716.932830671729, 6731.730730770714, 6749.932500675029, 6808.93191068083, 1771.5571107223195, 1779.4163194756677, 1800.8829426087264, 1804.1881153480986, 1822.824278340463, 1172.4489489939886, 1214.8117041858527, 1226.2887525235521, 1230.0897335971495, 1262.267884626914, 5922.496587790086, 5925.881482370414, 5932.516870782304, 5951.0, 5994.610350327215, 6644.202695676549, 6827.863442731117, 6840.016395245422, 6845.315468453115, 6867.278935711778, 5822.767089316465, 5920.407959204045, 5954.4045595440975, 5955.40445955401, 6006.819795406145, 3621.855125794939, 3646.507721457622, 3656.1225305926387, 3664.670179683841, 3676.742628016013, 6537.869242615121, 6590.9670451647435, 6660.567063140978, 6700.162479690039, 6749.932500675029, 6669.333066693389, 6825.965870170717, 6907.481938854506, 6976.686049127801, 7047.89428158578, 6482.384173503509, 6563.442107420923, 6620.602763834089, 6718.966405168041, 6727.730890764413, 6836.965815170893, 6852.383285504328, 6939.618320992394, 6949.270326615632, 6951.478639102189, 6702.899456508254, 6814.284500127415, 6858.622775747382, 6859.862802744017, 6901.758438454831, 6650.900236496554, 6665.966670166618, 6696.531242812956, 6743.898841517479, 6788.185417749902, 5082.796688132434, 5094.592432605456, 5103.92344114846, 5130.846074617767, 5135.845924622267, 6570.277269500256, 6813.863722725617, 6950.165980082423, 6991.881298992137, 6996.551207077482, 6457.806265812033, 6559.376859198369, 6601.095517972077, 6659.267480577036, 6661.0, 6541.0, 6602.933970660232, 6663.866722665616, 6687.832804179934, 6711.3288671132495, 6540.476761859135, 6564.573302735307, 6572.244191917799, 6578.243501997332, 6598.736050557924, 6700.832479187864, 6730.562513436611, 6730.899036514554, 6741.2921643226755, 6748.7300507979135, 6455.225372955276, 6459.4174427265625, 6500.349965003651, 6516.478681705547, 6560.573562718408], "value": 5176.967104923488, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [875.0636818604092, 891.8751374807528, 895.9283257339414, 899.6716198587518, 913.70761356366, 18067.36764213253, 18985.62028759424, 18998.24007039717, 19023.143958521865, 21309.36071917845, 19871.0, 20759.169633214653, 20779.0, 20812.271570495035, 21047.158113675436, 920.1580553793272, 936.180841763457, 939.8684184214216, 940.2994768897181, 940.3182692547882, 968.8449848024318, 992.602958816472, 994.328828041073, 996.1433167475965, 997.6558087459815, 1046.748780292732, 1055.2718624149313, 1055.7255113670424, 1056.1867362131154, 1057.1437135919891, 1044.7074819050679, 1045.2892033417272, 1046.1526163807264, 1046.6232156423682, 1046.6650671784994, 775.4571799740204, 775.6664634207289, 775.7401270574364, 775.8176828445311, 775.8758598624249, 19109.23563057476, 19222.423327300203, 19483.220671173138, 19566.21735130593, 19899.403017909484, 18577.907110464497, 18695.345662901735, 18852.151653175504, 19059.523511912314, 19078.713819292723, 16816.32734690611, 16836.0, 17079.487615371436, 17106.48680539586, 17112.31550737969, 3686.889393318205, 3696.796676182809, 3707.888763337104, 3719.9628003719886, 3720.088578298334, 127398.36300818529, 129624.0, 129744.0, 129854.3507282467, 132390.0, 15586.454474093352, 16352.100634465218, 16418.507444776675, 16443.0, 16518.0, 30167.698323016488, 30440.54339184914, 30655.0, 30984.690153098632, 31109.066727998197, 7186.8203294917, 7433.0, 8498.957505212435, 8800.515971621622, 8890.19988201052, 4252.978735106305, 4315.180115777992, 4316.093620339701, 4316.395704601358, 4349.108432771274, 5249.396319423239, 5291.3385826771655, 5309.203619457112, 5342.973285133549, 5363.758630861621, 1063.2769716592636, 1070.8340207267886, 1078.8273876179774, 1080.0387654987069, 1080.3247970018738, 667.6061123936901, 680.5100327764002, 686.3822559696313, 687.9621620810904, 696.435886931587, 11693.766124677459, 11824.881751182551, 11959.0, 12294.193137615746, 12679.429425675868, 656.1896058367912, 662.3740565165969, 666.8399584099878, 673.8214373191108, 684.5105749389111, 5372.328458942632, 5518.0, 5566.582506312043, 5573.665580065209, 5701.600887937888, 109017.0, 110793.0, 111668.88331116748, 113206.0, 117210.0, 97558.04883902424, 97875.0, 99740.0, 102054.48972755088, 105217.47391263148, 87376.563117184, 87729.0, 88507.55746221356, 90839.54580227057, 91598.0, 10506.422146782, 10688.198385120992, 10758.300710453796, 11003.724906877393, 11013.118950484102, 40422.79788601038, 41029.0, 41711.0, 41716.165676686905, 41821.0, 51503.154707659305, 51881.7405912968, 52248.21627675512, 55005.72497137489, 55219.44780552143, 13441.596752097452, 15123.697526049198, 15649.608759781097, 15700.76448853291, 15727.606809829846, 16622.3351065956, 18653.16060777241, 18716.25134994585, 19296.517587060436, 19666.50833729168, 16808.159592020107, 17129.657406852042, 17137.571560711083, 17375.304987800348, 17556.0, 17288.740668890227, 17528.82471175272, 17615.823841761932, 17711.645767084843, 17781.28874844992, 14643.12141271527, 14730.852691472948, 14775.039622424509, 14800.407983680963, 18585.0, 12488.25070495773, 12687.0, 12697.93651031739, 13440.663983400493, 13698.65753356174, 12931.741365172831, 13385.598432047054, 13420.932895335462, 13432.985052242242, 14295.428182872569], "value": 8890.513739705293, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1481.0, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 109.0, 101.0, 110.0, 107.0, 112.0, 109.0, 98.0, 107.0, 110.0], "value": 105.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 123.0, 147.0, 182.0, 117.0, 140.0, 145.0, 118.0, 166.0, 116.0], "value": 139.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 162.0, 161.0, 162.0, 163.0, 163.0, 162.0, 169.0, 171.0, 162.0], "value": 163.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 74.0, 73.0, 180.0, 73.0, 73.0, 73.0, 74.0, 150.0, 76.0], "value": 92.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 78.0, 128.0, 79.0, 79.0, 80.0, 133.0, 78.0, 79.0, 124.0], "value": 93.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 114.0, 113.0, 113.0, 114.0, 114.0, 113.0, 117.0, 117.0, 117.0], "value": 114.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 148.0, 148.0, 148.0, 147.0, 148.0, 148.0, 147.0, 149.0, 156.0], "value": 148.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 93.0, 94.0, 93.0, 93.0, 94.0, 94.0, 94.0], "value": 93.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 54.0, 54.0, 52.0, 54.0, 52.0, 53.0, 53.0, 53.0, 51.0], "value": 52.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [38.0, 38.0, 42.0, 38.0, 38.0, 38.0, 39.0, 38.0, 38.0, 39.0], "value": 38.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 77.0, 75.0, 77.0, 77.0, 74.0, 74.0, 74.0, 75.0, 78.0], "value": 75.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [129.0, 121.0, 126.0, 118.0, 115.0, 117.0, 118.0, 119.0, 118.0, 172.0], "value": 125.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 173.0, 172.0, 170.0, 173.0, 172.0, 179.0, 169.0, 170.0, 168.0], "value": 171.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 65.0, 66.0, 67.0, 65.0, 64.0, 65.0, 65.0, 64.0, 63.0], "value": 65.2, "unit": "score"}]}]} 03:16:08 INFO - Return code: 0 03:16:08 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:16:08 INFO - # TBPL SUCCESS # 03:16:08 INFO - Running post-action listener: _package_coverage_data 03:16:08 INFO - Running post-action listener: _resource_record_post_action 03:16:08 INFO - [mozharness: 2017-08-30 10:16:08.935275Z] Finished run-tests step (success) 03:16:08 INFO - Running post-run listener: _resource_record_post_run 03:16:09 INFO - Total resource usage - Wall time: 1188s; CPU: 13.0%; Read bytes: 4096; Write bytes: 391835648; Read time: 20; Write time: 256752 03:16:09 INFO - TinderboxPrint: CPU usage
13.1% 03:16:09 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 20 03:16:09 INFO - TinderboxPrint: I/O write bytes / time
391,835,648 / 256,752 03:16:09 INFO - TinderboxPrint: CPU idle
8,232.8 (86.9%) 03:16:09 INFO - TinderboxPrint: CPU user
1,191.5 (12.6%) 03:16:09 INFO - TinderboxPrint: Swap in / out
0 / 0 03:16:09 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 107167744; Read time: 0; Write time: 90824 03:16:09 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 03:16:09 INFO - run-tests - Wall time: 1172s; CPU: 13.0%; Read bytes: 4096; Write bytes: 284667904; Read time: 20; Write time: 165928 03:16:09 INFO - Running post-run listener: _upload_blobber_files 03:16:09 INFO - Blob upload gear active. 03:16:09 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:16:09 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 03:16:09 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-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 03:16:09 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-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 03:16:09 INFO - (blobuploader) - INFO - Open directory for files ... 03:16:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 03:16:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:16:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:16:11 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 03:16:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:16:11 INFO - (blobuploader) - INFO - Done attempting. 03:16:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:16:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:16:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:16:15 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:16:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:16:15 INFO - (blobuploader) - INFO - Done attempting. 03:16:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 03:16:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:16:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:16:16 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 03:16:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:16:16 INFO - (blobuploader) - INFO - Done attempting. 03:16:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 03:16:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:16:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:16:17 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 03:16:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:16:17 INFO - (blobuploader) - INFO - Done attempting. 03:16:17 INFO - (blobuploader) - INFO - Iteration through files over. 03:16:17 INFO - Return code: 0 03:16:17 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:16:17 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:16:17 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b94c66e0d19830af2ec36811309812c225360eeaba3ee2b9e0a5c409c120ea5e5a3628e4910f9a1551228f612ffa2a5e88b0581fde1b0c3ef1db284c3e729d22", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ab80d4be85d318e6fc8dc0c0452994f04c56ca90ae7b53933edcf57e75c8d84f8ae1db10fcff167d8d8ae9dd1ae1991d872d10ceda3514a1535c9f5eefa5b439", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/409ef52e74b043ef9b64b988fb8d0bbd11c271e14c973986d984762fcebb311847eea2f6da4c51a83def1f670d576e3a3cb3ddaf86d5095bdf16683a49001978", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9de585210144ce062594f8a1347716e77b0065b3e658c90ab8866a5d824e1813e3b8b5dcd849b3470249658bc1e672890b90e9e25e51824a1d5817bf3ebebf93"} 03:16:17 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:16:17 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:16:17 INFO - Contents: 03:16:17 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b94c66e0d19830af2ec36811309812c225360eeaba3ee2b9e0a5c409c120ea5e5a3628e4910f9a1551228f612ffa2a5e88b0581fde1b0c3ef1db284c3e729d22", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ab80d4be85d318e6fc8dc0c0452994f04c56ca90ae7b53933edcf57e75c8d84f8ae1db10fcff167d8d8ae9dd1ae1991d872d10ceda3514a1535c9f5eefa5b439", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/409ef52e74b043ef9b64b988fb8d0bbd11c271e14c973986d984762fcebb311847eea2f6da4c51a83def1f670d576e3a3cb3ddaf86d5095bdf16683a49001978", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9de585210144ce062594f8a1347716e77b0065b3e658c90ab8866a5d824e1813e3b8b5dcd849b3470249658bc1e672890b90e9e25e51824a1d5817bf3ebebf93"} 03:16:17 INFO - Running post-run listener: copy_logs_to_upload_dir 03:16:17 INFO - Copying logs to upload dir... 03:16:17 INFO - mkdir: /builds/slave/test/build/upload/logs 03:16:17 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1250.234867 ========= master_lag: 0.07 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 50 secs) (at 2017-08-30 03:16:17.221052) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-30 03:16:17.226145) ========= 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=56c518bb7cbc29fb3c9463e2000001c4-1504084713.229460-263559356 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b94c66e0d19830af2ec36811309812c225360eeaba3ee2b9e0a5c409c120ea5e5a3628e4910f9a1551228f612ffa2a5e88b0581fde1b0c3ef1db284c3e729d22", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ab80d4be85d318e6fc8dc0c0452994f04c56ca90ae7b53933edcf57e75c8d84f8ae1db10fcff167d8d8ae9dd1ae1991d872d10ceda3514a1535c9f5eefa5b439", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/409ef52e74b043ef9b64b988fb8d0bbd11c271e14c973986d984762fcebb311847eea2f6da4c51a83def1f670d576e3a3cb3ddaf86d5095bdf16683a49001978", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9de585210144ce062594f8a1347716e77b0065b3e658c90ab8866a5d824e1813e3b8b5dcd849b3470249658bc1e672890b90e9e25e51824a1d5817bf3ebebf93"} build_url:https://queue.taskcluster.net/v1/task/eSP-W7eQTHm081zyISUr-A/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011242 build_url: 'https://queue.taskcluster.net/v1/task/eSP-W7eQTHm081zyISUr-A/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b94c66e0d19830af2ec36811309812c225360eeaba3ee2b9e0a5c409c120ea5e5a3628e4910f9a1551228f612ffa2a5e88b0581fde1b0c3ef1db284c3e729d22", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ab80d4be85d318e6fc8dc0c0452994f04c56ca90ae7b53933edcf57e75c8d84f8ae1db10fcff167d8d8ae9dd1ae1991d872d10ceda3514a1535c9f5eefa5b439", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/409ef52e74b043ef9b64b988fb8d0bbd11c271e14c973986d984762fcebb311847eea2f6da4c51a83def1f670d576e3a3cb3ddaf86d5095bdf16683a49001978", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9de585210144ce062594f8a1347716e77b0065b3e658c90ab8866a5d824e1813e3b8b5dcd849b3470249658bc1e672890b90e9e25e51824a1d5817bf3ebebf93"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-30 03:16:17.255488) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-30 03:16:17.255824) ========= 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=56c518bb7cbc29fb3c9463e2000001c4-1504084713.229460-263559356 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004532 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-30 03:16:17.338588) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-30 03:16:17.341945) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-30 03:16:17.342255) ========= ========= Total master_lag: 0.33 =========