builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-029 starttime: 1506595575.96 results: success (0) revision: 76a26ef7c493311c170ae83eb0c1d6592a21396d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-28 03:46:15.956816) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-28 03:46:15.957266) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-28 03:46:15.957545) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-28 03:46:15.974992) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-28 03:46:15.975378) ========= 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=ba164b0a7b0ec7655860e956000001c1-1506595574.805415-722265750 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-28 03:46:15-- 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% 110M=0s 2017-09-28 03:46:16 (110 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.144827 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-28 03:46:16.139843) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-28 03:46:16.140180) ========= 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=ba164b0a7b0ec7655860e956000001c1-1506595574.805415-722265750 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017455 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-28 03:46:16.189667) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-28 03:46:16.193008) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 76a26ef7c493311c170ae83eb0c1d6592a21396d --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 76a26ef7c493311c170ae83eb0c1d6592a21396d --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=ba164b0a7b0ec7655860e956000001c1-1506595574.805415-722265750 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-28 03:46:16,221 truncating revision to first 12 chars 2017-09-28 03:46:16,221 Setting DEBUG logging. 2017-09-28 03:46:16,221 attempt 1/10 2017-09-28 03:46:16,221 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/76a26ef7c493?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-28 03:46:16,514 unpacking tar archive at: mozilla-central-76a26ef7c493/testing/mozharness/ program finished with exit code 0 elapsedTime=0.591525 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-28 03:46:16.800780) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-28 03:46:16.804125) ========= script_repo_revision: 76a26ef7c493311c170ae83eb0c1d6592a21396d ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-28 03:46:16.804538) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-28 03:46:16.804806) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-28 03:46:16.818389) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 42 secs) (at 2017-09-28 03:46:16.818670) ========= /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=ba164b0a7b0ec7655860e956000001c1-1506595574.805415-722265750 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 03:46:16 INFO - MultiFileLogger online at 20170928 03:46:16 in /builds/slave/test 03:46:16 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 03:46:16 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:46:16 INFO - {'append_to_log': False, 03:46:16 INFO - 'base_work_dir': '/builds/slave/test', 03:46:16 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 03:46:16 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:46:16 INFO - 'branch': 'Firefox-Non-PGO', 03:46:16 INFO - 'buildbot_json_path': 'buildprops.json', 03:46:16 INFO - 'code_coverage': False, 03:46:16 INFO - 'config_files': ('talos/linux_config.py',), 03:46:16 INFO - 'default_actions': ('clobber', 03:46:16 INFO - 'read-buildbot-config', 03:46:16 INFO - 'download-and-extract', 03:46:16 INFO - 'populate-webroot', 03:46:16 INFO - 'create-virtualenv', 03:46:16 INFO - 'install', 03:46:16 INFO - 'setup-mitmproxy', 03:46:16 INFO - 'run-tests'), 03:46:16 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:46:16 INFO - 'disable_ccov_upload': False, 03:46:16 INFO - 'disable_stylo': False, 03:46:16 INFO - 'download_minidump_stackwalk': True, 03:46:16 INFO - 'download_symbols': 'ondemand', 03:46:16 INFO - 'enable_stylo': False, 03:46:16 INFO - 'enable_webrender': False, 03:46:16 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 03:46:16 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:46:16 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:46:16 INFO - 'gecko_profile': False, 03:46:16 INFO - 'gecko_profile_interval': 0, 03:46:16 INFO - 'installer_path': 'installer.exe', 03:46:16 INFO - 'log_level': 'info', 03:46:16 INFO - 'log_name': 'talos', 03:46:16 INFO - 'log_to_console': True, 03:46:16 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 03:46:16 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 03:46:16 INFO - 'opt_config_files': (), 03:46:16 INFO - 'pip_index': False, 03:46:16 INFO - 'suite': 'dromaeojs-e10s', 03:46:16 INFO - 'system_bits': '32', 03:46:16 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:46:16 INFO - 'title': 'talos-linux64-ix-029', 03:46:16 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:46:16 INFO - 'use_talos_json': True, 03:46:16 INFO - 'verify': 'False', 03:46:16 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:46:16 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:46:16 INFO - 'work_dir': 'build'} 03:46:16 INFO - [mozharness: 2017-09-28 10:46:16.984640Z] Running clobber step. 03:46:16 INFO - Running pre-action listener: _resource_record_pre_action 03:46:16 INFO - Running main action method: clobber 03:46:16 INFO - rmtree: /builds/slave/test/build 03:46:16 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:46:18 INFO - Running post-action listener: _resource_record_post_action 03:46:18 INFO - [mozharness: 2017-09-28 10:46:18.218369Z] Finished clobber step (success) 03:46:18 INFO - [mozharness: 2017-09-28 10:46:18.218466Z] Running read-buildbot-config step. 03:46:18 INFO - Running pre-action listener: _resource_record_pre_action 03:46:18 INFO - Running main action method: read_buildbot_config 03:46:18 INFO - Using buildbot properties: 03:46:18 INFO - { 03:46:18 INFO - "project": "", 03:46:18 INFO - "product": "firefox", 03:46:18 INFO - "who": "archaeopteryx@coole-files.de", 03:46:18 INFO - "installer_path": "public/build/target.tar.bz2", 03:46:18 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 03:46:18 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 03:46:18 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 03:46:18 INFO - "stage_platform": "linux64", 03:46:18 INFO - "basedir": "/builds/slave/test", 03:46:18 INFO - "buildnumber": 878, 03:46:18 INFO - "platform": "ubuntu64_hw", 03:46:18 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 03:46:18 INFO - "slavebuilddir": "test", 03:46:18 INFO - "taskId": "eN98pKXORjWC_LrDG6-RJg", 03:46:18 INFO - "branch": "mozilla-central", 03:46:18 INFO - "script_repo_revision": "production", 03:46:18 INFO - "revision": "76a26ef7c493311c170ae83eb0c1d6592a21396d", 03:46:18 INFO - "slavename": "talos-linux64-ix-029", 03:46:18 INFO - "repo_path": "mozilla-central" 03:46:18 INFO - } 03:46:18 INFO - Finding installer, test and symbols from parent task. 03:46:18 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/eN98pKXORjWC_LrDG6-RJg'}, attempt #1 03:46:18 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/eN98pKXORjWC_LrDG6-RJg'}, attempt #1 03:46:19 INFO - Task dependencies: RdxJmsEUSFuRZWELzxBWSw 03:46:19 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RdxJmsEUSFuRZWELzxBWSw'}, attempt #1 03:46:19 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/RdxJmsEUSFuRZWELzxBWSw/artifacts/public/build/target.tar.bz2 03:46:19 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/RdxJmsEUSFuRZWELzxBWSw/artifacts/public/build/target.test_packages.json 03:46:19 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/RdxJmsEUSFuRZWELzxBWSw/artifacts/public/build/target.crashreporter-symbols.zip 03:46:19 INFO - Running post-action listener: _resource_record_post_action 03:46:19 INFO - [mozharness: 2017-09-28 10:46:19.653539Z] Finished read-buildbot-config step (success) 03:46:19 INFO - [mozharness: 2017-09-28 10:46:19.653757Z] Running download-and-extract step. 03:46:19 INFO - Running pre-action listener: _resource_record_pre_action 03:46:19 INFO - Running main action method: download_and_extract 03:46:19 INFO - mkdir: /builds/slave/test/build/tests 03:46: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')]} 03:46:19 INFO - trying https://queue.taskcluster.net/v1/task/RdxJmsEUSFuRZWELzxBWSw/artifacts/public/build/target.test_packages.json 03:46:19 INFO - Downloading https://queue.taskcluster.net/v1/task/RdxJmsEUSFuRZWELzxBWSw/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 03:46:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RdxJmsEUSFuRZWELzxBWSw/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 03:46:20 INFO - Downloaded 989 bytes. 03:46:20 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 03:46:20 INFO - Using the following test package requirements: 03:46:20 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 03:46:20 INFO - u'common': [u'target.common.tests.zip'], 03:46:20 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 03:46:20 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 03:46:20 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 03:46:20 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 03:46:20 INFO - u'mozbase': [u'target.common.tests.zip'], 03:46:20 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 03:46:20 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 03:46:20 INFO - u'web-platform': [u'target.common.tests.zip', 03:46:20 INFO - u'target.web-platform.tests.tar.gz'], 03:46:20 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 03:46:20 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 03:46:20 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RdxJmsEUSFuRZWELzxBWSw/artifacts/public/build/target.common.tests.zip 03:46:20 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RdxJmsEUSFuRZWELzxBWSw/artifacts/public/build/target.common.tests.zip'}, attempt #1 03:46:20 INFO - Fetch https://queue.taskcluster.net/v1/task/RdxJmsEUSFuRZWELzxBWSw/artifacts/public/build/target.common.tests.zip into memory 03:46:21 INFO - Content-Length response header: 55262973 03:46:21 INFO - Bytes received: 55262973 03:46:32 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RdxJmsEUSFuRZWELzxBWSw/artifacts/public/build/target.talos.tests.zip 03:46:32 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RdxJmsEUSFuRZWELzxBWSw/artifacts/public/build/target.talos.tests.zip'}, attempt #1 03:46:32 INFO - Fetch https://queue.taskcluster.net/v1/task/RdxJmsEUSFuRZWELzxBWSw/artifacts/public/build/target.talos.tests.zip into memory 03:46:32 INFO - Content-Length response header: 14521162 03:46:32 INFO - Bytes received: 14521162 03:46:33 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')]} 03:46:33 INFO - trying https://queue.taskcluster.net/v1/task/RdxJmsEUSFuRZWELzxBWSw/artifacts/public/build/target.tar.bz2 03:46:33 INFO - Downloading https://queue.taskcluster.net/v1/task/RdxJmsEUSFuRZWELzxBWSw/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 03:46:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RdxJmsEUSFuRZWELzxBWSw/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 03:46:34 INFO - Downloaded 61586385 bytes. 03:46:34 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/RdxJmsEUSFuRZWELzxBWSw/artifacts/public/build/target.tar.bz2 03:46:34 INFO - mkdir: /builds/slave/test/properties 03:46:34 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:46:34 INFO - Writing to file /builds/slave/test/properties/build_url 03:46:34 INFO - Contents: 03:46:34 INFO - build_url:https://queue.taskcluster.net/v1/task/RdxJmsEUSFuRZWELzxBWSw/artifacts/public/build/target.tar.bz2 03:46:34 INFO - Running post-action listener: _resource_record_post_action 03:46:34 INFO - Running post-action listener: find_tests_for_verification 03:46:34 INFO - Running post-action listener: set_extra_try_arguments 03:46:34 INFO - [mozharness: 2017-09-28 10:46:34.334553Z] Finished download-and-extract step (success) 03:46:34 INFO - [mozharness: 2017-09-28 10:46:34.334652Z] Running populate-webroot step. 03:46:34 INFO - Running pre-action listener: _resource_record_pre_action 03:46:34 INFO - Running main action method: populate_webroot 03:46:34 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:46:34 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:46:34 INFO - 'tests': ['tresize', 03:46:34 INFO - 'tcanvasmark']}, 03:46:34 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:46:34 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:46:34 INFO - 'tests': ['dromaeo_css', 03:46:34 INFO - 'kraken']}, 03:46:34 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 03:46:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:46:34 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 03:46:34 INFO - 'talos_options': ['--disable-stylo'], 03:46:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:46:34 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 03:46:34 INFO - 'tests': ['damp', 'tps']}, 03:46:34 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 03:46:34 INFO - 'talos_options': ['--disable-stylo'], 03:46:34 INFO - 'tests': ['damp', 'tps']}, 03:46:34 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:46:34 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:46:34 INFO - 'tests': ['dromaeo_dom']}, 03:46:34 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 03:46:34 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:46:34 INFO - 'tests': ['basic_compositor_video', 03:46:34 INFO - 'glvideo']}, 03:46:34 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 03:46:34 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 03:46:34 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 03:46:34 INFO - 'talos_options': ['--disable-stylo'], 03:46:34 INFO - 'tests': ['ts_paint_webext', 03:46:34 INFO - 'tp5o_webext']}, 03:46:34 INFO - 'other-e10s': {'tests': ['a11yr', 03:46:34 INFO - 'ts_paint', 03:46:34 INFO - 'tpaint', 03:46:34 INFO - 'sessionrestore', 03:46:34 INFO - 'sessionrestore_many_windows', 03:46:34 INFO - 'sessionrestore_no_auto_restore', 03:46:34 INFO - 'tabpaint', 03:46:34 INFO - 'cpstartup']}, 03:46:34 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:46:34 INFO - 'tests': ['a11yr', 03:46:34 INFO - 'ts_paint', 03:46:34 INFO - 'tpaint', 03:46:34 INFO - 'sessionrestore', 03:46:34 INFO - 'sessionrestore_many_windows', 03:46:34 INFO - 'sessionrestore_no_auto_restore', 03:46:34 INFO - 'tabpaint', 03:46:34 INFO - 'cpstartup']}, 03:46:34 INFO - 'perf-reftest-e10s': {'tests': ['perf_reftest']}, 03:46:34 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']}, 03:46:34 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:46:34 INFO - 'tests': ['perf_reftest_singletons']}, 03:46:34 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:46:34 INFO - 'tests': ['perf_reftest']}, 03:46:34 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:46:34 INFO - 'tsvgr_opacity', 03:46:34 INFO - 'tart', 03:46:34 INFO - 'tscrollx', 03:46:34 INFO - 'tsvg_static']}, 03:46:34 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:46:34 INFO - 'tests': ['tsvgx', 03:46:34 INFO - 'tsvgr_opacity', 03:46:34 INFO - 'tart', 03:46:34 INFO - 'tscrollx', 03:46:34 INFO - 'tsvg_static']}, 03:46:34 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 03:46:34 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 03:46:34 INFO - 'talos_options': ['--disable-stylo'], 03:46:34 INFO - 'tests': ['tp5o']}, 03:46:34 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 03:46:34 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 03:46:34 INFO - 'talos_options': ['--mitmproxy', 03:46:34 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 03:46:34 INFO - '--firstNonBlankPaint'], 03:46:34 INFO - 'tests': ['tp6_google', 03:46:34 INFO - 'tp6_youtube', 03:46:34 INFO - 'tp6_amazon', 03:46:34 INFO - 'tp6_facebook']}, 03:46:34 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 03:46:34 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 03:46:34 INFO - 'talos_options': ['--disable-stylo', 03:46:34 INFO - '--mitmproxy', 03:46:34 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 03:46:34 INFO - '--firstNonBlankPaint'], 03:46:34 INFO - 'tests': ['tp6_google', 03:46:34 INFO - 'tp6_youtube', 03:46:34 INFO - 'tp6_amazon', 03:46:34 INFO - 'tp6_facebook']}, 03:46:34 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 03:46:34 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 03:46:34 INFO - 'talos_options': ['--stylo-threads=1', 03:46:34 INFO - '--mitmproxy', 03:46:34 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 03:46:34 INFO - '--firstNonBlankPaint'], 03:46:34 INFO - 'tests': ['tp6_google', 03:46:34 INFO - 'tp6_youtube', 03:46:34 INFO - 'tp6_amazon', 03:46:34 INFO - 'tp6_facebook']}, 03:46:34 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 03:46:34 INFO - 'talos_options': ['--xperf_path', 03:46:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:46:34 INFO - 'tests': ['tp5n']}, 03:46:34 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 03:46:34 INFO - 'talos_options': ['--disable-stylo', 03:46:34 INFO - '--xperf_path', 03:46:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:46:34 INFO - 'tests': ['tp5n']}}} 03:46:34 INFO - Running post-action listener: _resource_record_post_action 03:46:34 INFO - [mozharness: 2017-09-28 10:46:34.351044Z] Finished populate-webroot step (success) 03:46:34 INFO - [mozharness: 2017-09-28 10:46:34.351150Z] Running create-virtualenv step. 03:46:34 INFO - Running pre-action listener: _resource_record_pre_action 03:46:34 INFO - Running main action method: create_virtualenv 03:46:34 INFO - Creating virtualenv /builds/slave/test/build/venv 03:46:34 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 03:46:34 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 03:46:34 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 03:46:34 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:46:35 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:46:35 INFO - Installing distribute.............................................................................................................................................................................................done. 03:46:37 INFO - Installing pip...............done. 03:46:37 INFO - Return code: 0 03:46:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 03:46:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 03:46:37 INFO - Reading from file tmpfile_stdout 03:46:37 INFO - Output received: 03:46:37 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 03:46:37 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:46:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:46:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:46:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:46:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:46:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b731f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d3bb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ee09c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eddd60>, '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 0x1edea60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1edeef0>, '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': 'ba164b0a7b0ec7655860e956000001c1-1506595574.805415-722265750', '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 03:46:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 03:46:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 03:46:37 INFO - Using env: {'DISPLAY': ':0', 03:46:37 INFO - 'HOME': '/home/cltbld', 03:46:37 INFO - 'LANG': 'en_US.UTF-8', 03:46:37 INFO - 'LANGUAGE': 'en_US:en', 03:46:37 INFO - 'LOGNAME': 'cltbld', 03:46:37 INFO - 'MAIL': '/var/mail/cltbld', 03:46:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:46:37 INFO - 'MOZ_NO_REMOTE': '1', 03:46:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:46:37 INFO - 'NO_EM_RESTART': '1', 03:46:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:46:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:46:37 INFO - 'PWD': '/builds/slave/test', 03:46:37 INFO - 'SHELL': '/bin/bash', 03:46:37 INFO - 'SHLVL': '1', 03:46:37 INFO - 'TERM': 'linux', 03:46:37 INFO - 'TMOUT': '86400', 03:46:37 INFO - 'USER': 'cltbld', 03:46:37 INFO - 'XDG_SESSION_COOKIE': 'ba164b0a7b0ec7655860e956000001c1-1506595574.805415-722265750', 03:46:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:46:37 INFO - '_': '/tools/buildbot/bin/python'} 03:46:38 INFO - Ignoring indexes: http://pypi.python.org/simple/ 03:46:38 INFO - Downloading/unpacking pip>=1.5 03:46:38 INFO - Running setup.py egg_info for package pip 03:46:38 INFO - warning: no files found matching 'pip/cacert.pem' 03:46:38 INFO - warning: no files found matching '*.html' under directory 'docs' 03:46:38 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 03:46:38 INFO - no previously-included directories found matching 'docs/_build/_sources' 03:46:38 INFO - Installing collected packages: pip 03:46:38 INFO - Found existing installation: pip 1.1 03:46:38 INFO - Uninstalling pip: 03:46:38 INFO - Successfully uninstalled pip 03:46:38 INFO - Running setup.py install for pip 03:46:38 INFO - warning: no files found matching 'pip/cacert.pem' 03:46:38 INFO - warning: no files found matching '*.html' under directory 'docs' 03:46:38 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 03:46:38 INFO - no previously-included directories found matching 'docs/_build/_sources' 03:46:38 INFO - Installing pip script to /builds/slave/test/build/venv/bin 03:46:38 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 03:46:38 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 03:46:38 INFO - Successfully installed pip 03:46:38 INFO - Cleaning up... 03:46:38 INFO - Return code: 0 03:46:38 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:46:38 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')]} 03:46:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:46:38 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')]} 03:46:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:46:38 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 0x1b731f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d3bb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ee09c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eddd60>, '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 0x1edea60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1edeef0>, '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': 'ba164b0a7b0ec7655860e956000001c1-1506595574.805415-722265750', '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 03:46:38 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 03:46:38 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 03:46:38 INFO - Using env: {'DISPLAY': ':0', 03:46:38 INFO - 'HOME': '/home/cltbld', 03:46:38 INFO - 'LANG': 'en_US.UTF-8', 03:46:38 INFO - 'LANGUAGE': 'en_US:en', 03:46:38 INFO - 'LOGNAME': 'cltbld', 03:46:38 INFO - 'MAIL': '/var/mail/cltbld', 03:46:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:46:38 INFO - 'MOZ_NO_REMOTE': '1', 03:46:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:46:38 INFO - 'NO_EM_RESTART': '1', 03:46:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:46:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:46:38 INFO - 'PWD': '/builds/slave/test', 03:46:38 INFO - 'SHELL': '/bin/bash', 03:46:38 INFO - 'SHLVL': '1', 03:46:38 INFO - 'TERM': 'linux', 03:46:38 INFO - 'TMOUT': '86400', 03:46:38 INFO - 'USER': 'cltbld', 03:46:38 INFO - 'XDG_SESSION_COOKIE': 'ba164b0a7b0ec7655860e956000001c1-1506595574.805415-722265750', 03:46:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:46:38 INFO - '_': '/tools/buildbot/bin/python'} 03:46:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:46:39 INFO - Downloading/unpacking psutil>=3.1.1 03:46:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:46:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:46:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:46:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:46:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:46:40 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:46:40 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:46:40 INFO - Installing collected packages: psutil 03:46:40 INFO - Running setup.py install for psutil 03:46:40 INFO - building 'psutil._psutil_linux' extension 03:46:40 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 03:46:40 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 03:46:40 INFO - building 'psutil._psutil_posix' extension 03:46:40 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 03:46:40 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 03:46:40 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:46:40 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:46:40 INFO - Successfully installed psutil 03:46:40 INFO - Cleaning up... 03:46:40 INFO - Return code: 0 03:46:40 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:46:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:46:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:46:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:46:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:46:40 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 0x1b731f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d3bb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ee09c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eddd60>, '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 0x1edea60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1edeef0>, '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': 'ba164b0a7b0ec7655860e956000001c1-1506595574.805415-722265750', '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 03:46:40 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 03:46:40 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 03:46:40 INFO - Using env: {'DISPLAY': ':0', 03:46:40 INFO - 'HOME': '/home/cltbld', 03:46:40 INFO - 'LANG': 'en_US.UTF-8', 03:46:40 INFO - 'LANGUAGE': 'en_US:en', 03:46:40 INFO - 'LOGNAME': 'cltbld', 03:46:40 INFO - 'MAIL': '/var/mail/cltbld', 03:46:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:46:40 INFO - 'MOZ_NO_REMOTE': '1', 03:46:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:46:40 INFO - 'NO_EM_RESTART': '1', 03:46:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:46:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:46:40 INFO - 'PWD': '/builds/slave/test', 03:46:40 INFO - 'SHELL': '/bin/bash', 03:46:40 INFO - 'SHLVL': '1', 03:46:40 INFO - 'TERM': 'linux', 03:46:40 INFO - 'TMOUT': '86400', 03:46:40 INFO - 'USER': 'cltbld', 03:46:40 INFO - 'XDG_SESSION_COOKIE': 'ba164b0a7b0ec7655860e956000001c1-1506595574.805415-722265750', 03:46:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:46:40 INFO - '_': '/tools/buildbot/bin/python'} 03:46:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:46:40 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:46:40 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 03:46:40 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 03:46:40 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 03:46:40 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 03:46:42 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:46:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:46:42 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:46:42 INFO - Installing collected packages: mozsystemmonitor 03:46:42 INFO - Running setup.py install for mozsystemmonitor 03:46:42 INFO - Successfully installed mozsystemmonitor 03:46:42 INFO - Cleaning up... 03:46:44 INFO - Return code: 0 03:46:44 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:46:44 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')]} 03:46:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:46:44 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')]} 03:46:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:46:44 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 0x1b731f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d3bb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ee09c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eddd60>, '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 0x1edea60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1edeef0>, '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': 'ba164b0a7b0ec7655860e956000001c1-1506595574.805415-722265750', '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 03:46:44 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 03:46:44 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 03:46:44 INFO - Using env: {'DISPLAY': ':0', 03:46:44 INFO - 'HOME': '/home/cltbld', 03:46:44 INFO - 'LANG': 'en_US.UTF-8', 03:46:44 INFO - 'LANGUAGE': 'en_US:en', 03:46:44 INFO - 'LOGNAME': 'cltbld', 03:46:44 INFO - 'MAIL': '/var/mail/cltbld', 03:46:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:46:44 INFO - 'MOZ_NO_REMOTE': '1', 03:46:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:46:44 INFO - 'NO_EM_RESTART': '1', 03:46:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:46:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:46:44 INFO - 'PWD': '/builds/slave/test', 03:46:44 INFO - 'SHELL': '/bin/bash', 03:46:44 INFO - 'SHLVL': '1', 03:46:44 INFO - 'TERM': 'linux', 03:46:44 INFO - 'TMOUT': '86400', 03:46:44 INFO - 'USER': 'cltbld', 03:46:44 INFO - 'XDG_SESSION_COOKIE': 'ba164b0a7b0ec7655860e956000001c1-1506595574.805415-722265750', 03:46:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:46:44 INFO - '_': '/tools/buildbot/bin/python'} 03:46:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:46:44 INFO - Downloading/unpacking jsonschema==2.5.1 03:46:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:46:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:46:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:46:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:46:48 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:46:48 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:46:48 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 03:46:48 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 03:46:48 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 03:46:48 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 03:46:49 INFO - Downloading functools32-3.2.3-2.tar.gz 03:46:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:46:49 INFO - warning: no files found matching '*.txt' 03:46:49 INFO - no previously-included directories found matching 'build' 03:46:49 INFO - no previously-included directories found matching 'dist' 03:46:49 INFO - no previously-included directories found matching '.git*' 03:46:49 INFO - Installing collected packages: jsonschema, functools32 03:46:49 INFO - Running setup.py install for functools32 03:46:49 INFO - warning: no files found matching '*.txt' 03:46:49 INFO - no previously-included directories found matching 'build' 03:46:49 INFO - no previously-included directories found matching 'dist' 03:46:49 INFO - no previously-included directories found matching '.git*' 03:46:49 INFO - Successfully installed jsonschema functools32 03:46:49 INFO - Cleaning up... 03:46:49 INFO - Return code: 0 03:46:49 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 03:46:49 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')]} 03:46:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:46:49 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')]} 03:46:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:46:49 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 0x1b731f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d3bb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ee09c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eddd60>, '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 0x1edea60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1edeef0>, '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': 'ba164b0a7b0ec7655860e956000001c1-1506595574.805415-722265750', '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 03:46:49 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 03:46:49 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 03:46:49 INFO - Using env: {'DISPLAY': ':0', 03:46:49 INFO - 'HOME': '/home/cltbld', 03:46:49 INFO - 'LANG': 'en_US.UTF-8', 03:46:49 INFO - 'LANGUAGE': 'en_US:en', 03:46:49 INFO - 'LOGNAME': 'cltbld', 03:46:49 INFO - 'MAIL': '/var/mail/cltbld', 03:46:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:46:49 INFO - 'MOZ_NO_REMOTE': '1', 03:46:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:46:49 INFO - 'NO_EM_RESTART': '1', 03:46:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:46:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:46:49 INFO - 'PWD': '/builds/slave/test', 03:46:49 INFO - 'SHELL': '/bin/bash', 03:46:49 INFO - 'SHLVL': '1', 03:46:49 INFO - 'TERM': 'linux', 03:46:49 INFO - 'TMOUT': '86400', 03:46:49 INFO - 'USER': 'cltbld', 03:46:49 INFO - 'XDG_SESSION_COOKIE': 'ba164b0a7b0ec7655860e956000001c1-1506595574.805415-722265750', 03:46:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:46:49 INFO - '_': '/tools/buildbot/bin/python'} 03:46:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:46:49 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 03:46:49 INFO - Cleaning up... 03:46:49 INFO - Return code: 0 03:46:49 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:46:49 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')]} 03:46:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:46:49 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')]} 03:46:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:46:49 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 0x1b731f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d3bb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ee09c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eddd60>, '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 0x1edea60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1edeef0>, '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': 'ba164b0a7b0ec7655860e956000001c1-1506595574.805415-722265750', '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 03:46:49 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 03:46:49 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 03:46:49 INFO - Using env: {'DISPLAY': ':0', 03:46:49 INFO - 'HOME': '/home/cltbld', 03:46:49 INFO - 'LANG': 'en_US.UTF-8', 03:46:49 INFO - 'LANGUAGE': 'en_US:en', 03:46:49 INFO - 'LOGNAME': 'cltbld', 03:46:49 INFO - 'MAIL': '/var/mail/cltbld', 03:46:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:46:49 INFO - 'MOZ_NO_REMOTE': '1', 03:46:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:46:49 INFO - 'NO_EM_RESTART': '1', 03:46:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:46:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:46:49 INFO - 'PWD': '/builds/slave/test', 03:46:49 INFO - 'SHELL': '/bin/bash', 03:46:49 INFO - 'SHLVL': '1', 03:46:49 INFO - 'TERM': 'linux', 03:46:49 INFO - 'TMOUT': '86400', 03:46:49 INFO - 'USER': 'cltbld', 03:46:49 INFO - 'XDG_SESSION_COOKIE': 'ba164b0a7b0ec7655860e956000001c1-1506595574.805415-722265750', 03:46:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:46:49 INFO - '_': '/tools/buildbot/bin/python'} 03:46:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:46:49 INFO - Downloading/unpacking blobuploader==1.2.4 03:46:49 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 03:46:49 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 03:46:49 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 03:46:49 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 03:46:50 INFO - Downloading blobuploader-1.2.4.tar.gz 03:46:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:46:50 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:46:50 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 03:46:50 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 03:46:50 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 03:46:50 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 03:46:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:46:51 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:46:51 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 03:46:51 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 03:46:51 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 03:46:51 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 03:46:51 INFO - Downloading docopt-0.6.1.tar.gz 03:46:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:46:51 INFO - Installing collected packages: blobuploader, requests, docopt 03:46:51 INFO - Running setup.py install for blobuploader 03:46:51 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:46:51 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:46:51 INFO - Running setup.py install for requests 03:46:51 INFO - Running setup.py install for docopt 03:46:52 INFO - Successfully installed blobuploader requests docopt 03:46:52 INFO - Cleaning up... 03:46:52 INFO - Return code: 0 03:46:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:46:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:46:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:46:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:46:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:46:52 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 0x1b731f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d3bb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ee09c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eddd60>, '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 0x1edea60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1edeef0>, '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': 'ba164b0a7b0ec7655860e956000001c1-1506595574.805415-722265750', '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 03:46:52 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 03:46:52 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 03:46:52 INFO - Using env: {'DISPLAY': ':0', 03:46:52 INFO - 'HOME': '/home/cltbld', 03:46:52 INFO - 'LANG': 'en_US.UTF-8', 03:46:52 INFO - 'LANGUAGE': 'en_US:en', 03:46:52 INFO - 'LOGNAME': 'cltbld', 03:46:52 INFO - 'MAIL': '/var/mail/cltbld', 03:46:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:46:52 INFO - 'MOZ_NO_REMOTE': '1', 03:46:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:46:52 INFO - 'NO_EM_RESTART': '1', 03:46:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:46:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:46:52 INFO - 'PWD': '/builds/slave/test', 03:46:52 INFO - 'SHELL': '/bin/bash', 03:46:52 INFO - 'SHLVL': '1', 03:46:52 INFO - 'TERM': 'linux', 03:46:52 INFO - 'TMOUT': '86400', 03:46:52 INFO - 'USER': 'cltbld', 03:46:52 INFO - 'XDG_SESSION_COOKIE': 'ba164b0a7b0ec7655860e956000001c1-1506595574.805415-722265750', 03:46:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:46:52 INFO - '_': '/tools/buildbot/bin/python'} 03:46:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:46:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:46:52 INFO - Running setup.py (path:/tmp/pip-oDALu7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:46:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:46:52 INFO - Running setup.py (path:/tmp/pip-OylwLc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:46:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:46:52 INFO - Running setup.py (path:/tmp/pip-K84tMc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:46:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:46:52 INFO - Running setup.py (path:/tmp/pip-_fZRA6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:46:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:46:52 INFO - Running setup.py (path:/tmp/pip-FRnnvF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:46:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:46:52 INFO - Running setup.py (path:/tmp/pip-jFuyrZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:46:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:46:52 INFO - Running setup.py (path:/tmp/pip-iERzTU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:46:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:46:52 INFO - Running setup.py (path:/tmp/pip-YGE9pc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:46:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:46:52 INFO - Running setup.py (path:/tmp/pip-sMP7BM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:46:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:46:52 INFO - Running setup.py (path:/tmp/pip-1Vmwar-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:46:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:46:52 INFO - Running setup.py (path:/tmp/pip-xKJ7Xr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:46:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:46:53 INFO - Running setup.py (path:/tmp/pip-4oHdoY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:46:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:46:53 INFO - Running setup.py (path:/tmp/pip-qs0nLl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:46:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:46:53 INFO - Running setup.py (path:/tmp/pip-RR2gze-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:46:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:46:53 INFO - Running setup.py (path:/tmp/pip-EdI4KJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:46:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:46:53 INFO - Running setup.py (path:/tmp/pip-kwUGDn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:46:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:46:53 INFO - Running setup.py (path:/tmp/pip-HmCltO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:46:53 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:46:53 INFO - Running setup.py install for manifestparser 03:46:53 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:46:53 INFO - Running setup.py install for mozcrash 03:46:53 INFO - Running setup.py install for mozdebug 03:46:53 INFO - Running setup.py install for mozdevice 03:46:53 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:46:53 INFO - Running setup.py install for mozfile 03:46:53 INFO - Running setup.py install for mozhttpd 03:46:54 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:46:54 INFO - Running setup.py install for mozinfo 03:46:54 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:46:54 INFO - Running setup.py install for mozInstall 03:46:54 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:46:54 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:46:54 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:46:54 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:46:54 INFO - Running setup.py install for mozleak 03:46:54 INFO - Running setup.py install for mozlog 03:46:54 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:46:54 INFO - Running setup.py install for moznetwork 03:46:54 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:46:54 INFO - Running setup.py install for mozprocess 03:46:54 INFO - Running setup.py install for mozprofile 03:46:54 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:46:54 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:46:54 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:46:54 INFO - Running setup.py install for mozrunner 03:46:54 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:46:54 INFO - Running setup.py install for mozscreenshot 03:46:55 INFO - Running setup.py install for moztest 03:46:55 INFO - Running setup.py install for mozversion 03:46:55 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:46:55 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:46:55 INFO - Cleaning up... 03:46:55 INFO - Return code: 0 03:46:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:46:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:46:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:46:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:46:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:46:55 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 0x1b731f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d3bb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ee09c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eddd60>, '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 0x1edea60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1edeef0>, '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': 'ba164b0a7b0ec7655860e956000001c1-1506595574.805415-722265750', '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 03:46:55 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 03:46:55 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 03:46:55 INFO - Using env: {'DISPLAY': ':0', 03:46:55 INFO - 'HOME': '/home/cltbld', 03:46:55 INFO - 'LANG': 'en_US.UTF-8', 03:46:55 INFO - 'LANGUAGE': 'en_US:en', 03:46:55 INFO - 'LOGNAME': 'cltbld', 03:46:55 INFO - 'MAIL': '/var/mail/cltbld', 03:46:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:46:55 INFO - 'MOZ_NO_REMOTE': '1', 03:46:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:46:55 INFO - 'NO_EM_RESTART': '1', 03:46:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:46:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:46:55 INFO - 'PWD': '/builds/slave/test', 03:46:55 INFO - 'SHELL': '/bin/bash', 03:46:55 INFO - 'SHLVL': '1', 03:46:55 INFO - 'TERM': 'linux', 03:46:55 INFO - 'TMOUT': '86400', 03:46:55 INFO - 'USER': 'cltbld', 03:46:55 INFO - 'XDG_SESSION_COOKIE': 'ba164b0a7b0ec7655860e956000001c1-1506595574.805415-722265750', 03:46:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:46:55 INFO - '_': '/tools/buildbot/bin/python'} 03:46:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:46:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:46:55 INFO - Running setup.py (path:/tmp/pip-ay19Cm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:46:55 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)) 03:46:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:46:55 INFO - Running setup.py (path:/tmp/pip-ucJ8By-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:46:55 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)) 03:46:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:46:55 INFO - Running setup.py (path:/tmp/pip-JIQBrm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:46:55 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)) 03:46:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:46:55 INFO - Running setup.py (path:/tmp/pip-3zpblX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:46:55 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)) 03:46:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:46:55 INFO - Running setup.py (path:/tmp/pip-0dB5fG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:46:55 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)) 03:46:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:46:55 INFO - Running setup.py (path:/tmp/pip-6hsoJy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:46:55 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)) 03:46:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:46:55 INFO - Running setup.py (path:/tmp/pip-SgqFpP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:46:55 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)) 03:46:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:46:55 INFO - Running setup.py (path:/tmp/pip-x99Vot-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:46:56 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)) 03:46:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:46:56 INFO - Running setup.py (path:/tmp/pip-al_P6N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:46:56 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)) 03:46:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:46:56 INFO - Running setup.py (path:/tmp/pip-qDaWO1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:46:56 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)) 03:46:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:46:56 INFO - Running setup.py (path:/tmp/pip-VaX2MW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:46:56 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)) 03:46:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:46:56 INFO - Running setup.py (path:/tmp/pip-C7DQkx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:46:56 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)) 03:46:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:46:56 INFO - Running setup.py (path:/tmp/pip-7aIqSv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:46:56 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)) 03:46:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:46:56 INFO - Running setup.py (path:/tmp/pip-7ISC_U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:46:56 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)) 03:46:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:46:56 INFO - Running setup.py (path:/tmp/pip-rqPeBJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:46:56 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)) 03:46:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:46:56 INFO - Running setup.py (path:/tmp/pip-gDpGCH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:46:56 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)) 03:46:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:46:56 INFO - Running setup.py (path:/tmp/pip-mRNVLY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:46:56 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)) 03:46:56 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)) 03:46:56 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)) 03:46:56 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)) 03:46:56 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)) 03:46:56 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)) 03:46:56 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)) 03:46:56 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:46:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:46:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:46:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:46:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:46:57 INFO - Downloading blessings-1.6.tar.gz 03:46:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:46:57 INFO - Installing collected packages: blessings 03:46:57 INFO - Running setup.py install for blessings 03:46:58 INFO - Successfully installed blessings 03:46:58 INFO - Cleaning up... 03:46:58 INFO - Return code: 0 03:46:58 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:46:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:46:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:46:58 INFO - Reading from file tmpfile_stdout 03:46:58 INFO - Current package versions: 03:46:58 INFO - argparse == 1.2.1 03:46:58 INFO - blessings == 1.6 03:46:58 INFO - blobuploader == 1.2.4 03:46:58 INFO - docopt == 0.6.1 03:46:58 INFO - functools32 == 3.2.3-2 03:46:58 INFO - jsonschema == 2.5.1 03:46:58 INFO - manifestparser == 1.1 03:46:58 INFO - mozInstall == 1.13 03:46:58 INFO - mozcrash == 1.0 03:46:58 INFO - mozdebug == 0.1 03:46:58 INFO - mozdevice == 0.51 03:46:58 INFO - mozfile == 1.2 03:46:58 INFO - mozhttpd == 0.7 03:46:58 INFO - mozinfo == 0.10 03:46:58 INFO - mozleak == 0.1 03:46:58 INFO - mozlog == 3.5 03:46:58 INFO - moznetwork == 0.27 03:46:58 INFO - mozprocess == 0.25 03:46:58 INFO - mozprofile == 0.28 03:46:58 INFO - mozrunner == 6.13 03:46:58 INFO - mozscreenshot == 0.1 03:46:58 INFO - mozsystemmonitor == 0.3 03:46:58 INFO - moztest == 0.8 03:46:58 INFO - mozversion == 1.4 03:46:58 INFO - psutil == 3.1.1 03:46:58 INFO - requests == 1.2.3 03:46:58 INFO - wsgiref == 0.1.2 03:46:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:46:58 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')]} 03:46:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:46:58 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')]} 03:46:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:46:58 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 0x1b731f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d3bb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ee09c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eddd60>, '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 0x1edea60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1edeef0>, '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': 'ba164b0a7b0ec7655860e956000001c1-1506595574.805415-722265750', '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 03:46:58 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 03:46:58 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 03:46:58 INFO - Using env: {'DISPLAY': ':0', 03:46:58 INFO - 'HOME': '/home/cltbld', 03:46:58 INFO - 'LANG': 'en_US.UTF-8', 03:46:58 INFO - 'LANGUAGE': 'en_US:en', 03:46:58 INFO - 'LOGNAME': 'cltbld', 03:46:58 INFO - 'MAIL': '/var/mail/cltbld', 03:46:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:46:58 INFO - 'MOZ_NO_REMOTE': '1', 03:46:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:46:58 INFO - 'NO_EM_RESTART': '1', 03:46:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:46:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:46:58 INFO - 'PWD': '/builds/slave/test', 03:46:58 INFO - 'SHELL': '/bin/bash', 03:46:58 INFO - 'SHLVL': '1', 03:46:58 INFO - 'TERM': 'linux', 03:46:58 INFO - 'TMOUT': '86400', 03:46:58 INFO - 'USER': 'cltbld', 03:46:58 INFO - 'XDG_SESSION_COOKIE': 'ba164b0a7b0ec7655860e956000001c1-1506595574.805415-722265750', 03:46:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:46:58 INFO - '_': '/tools/buildbot/bin/python'} 03:46:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:46:58 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)) 03:46:58 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)) 03:46:58 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)) 03:46:58 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)) 03:46:58 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)) 03:46:58 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)) 03:46:58 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)) 03:46:58 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)) 03:46:58 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)) 03:46:58 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 03:46:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:46:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:46:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:46:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:47:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 03:47:00 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)) 03:47:00 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)) 03:47:00 INFO - Installing collected packages: simplejson 03:47:00 INFO - Running setup.py install for simplejson 03:47:00 INFO - building 'simplejson._speedups' extension 03:47:00 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 03:47:01 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 03:47:01 INFO - Successfully installed simplejson 03:47:01 INFO - Cleaning up... 03:47:01 INFO - Return code: 0 03:47:01 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:47: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')]} 03:47:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:47: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')]} 03:47:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:47: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b731f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d3bb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ee09c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eddd60>, '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 0x1edea60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1edeef0>, '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': 'ba164b0a7b0ec7655860e956000001c1-1506595574.805415-722265750', '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 03:47: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', 'jsonschema'] in /builds/slave/test/build 03:47: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 jsonschema 03:47:01 INFO - Using env: {'DISPLAY': ':0', 03:47:01 INFO - 'HOME': '/home/cltbld', 03:47:01 INFO - 'LANG': 'en_US.UTF-8', 03:47:01 INFO - 'LANGUAGE': 'en_US:en', 03:47:01 INFO - 'LOGNAME': 'cltbld', 03:47:01 INFO - 'MAIL': '/var/mail/cltbld', 03:47:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:47:01 INFO - 'MOZ_NO_REMOTE': '1', 03:47:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:47:01 INFO - 'NO_EM_RESTART': '1', 03:47:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:47:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:47:01 INFO - 'PWD': '/builds/slave/test', 03:47:01 INFO - 'SHELL': '/bin/bash', 03:47:01 INFO - 'SHLVL': '1', 03:47:01 INFO - 'TERM': 'linux', 03:47:01 INFO - 'TMOUT': '86400', 03:47:01 INFO - 'USER': 'cltbld', 03:47:01 INFO - 'XDG_SESSION_COOKIE': 'ba164b0a7b0ec7655860e956000001c1-1506595574.805415-722265750', 03:47:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:47:01 INFO - '_': '/tools/buildbot/bin/python'} 03:47:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:47:01 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:47:01 INFO - Cleaning up... 03:47:01 INFO - Return code: 0 03:47:01 INFO - Running post-action listener: _resource_record_post_action 03:47:01 INFO - Running post-action listener: _start_resource_monitoring 03:47:01 INFO - Starting resource monitoring. 03:47:01 INFO - [mozharness: 2017-09-28 10:47:01.982512Z] Finished create-virtualenv step (success) 03:47:01 INFO - [mozharness: 2017-09-28 10:47:01.983030Z] Running install step. 03:47:01 INFO - Running pre-action listener: _resource_record_pre_action 03:47:01 INFO - Running main action method: install 03:47:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:47:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:47:02 INFO - Reading from file tmpfile_stdout 03:47:02 INFO - Detecting whether we're running mozinstall >=1.0... 03:47:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:47:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:47:02 INFO - Reading from file tmpfile_stdout 03:47:02 INFO - Output received: 03:47:02 INFO - Usage: mozinstall [options] installer 03:47:02 INFO - Options: 03:47:02 INFO - -h, --help show this help message and exit 03:47:02 INFO - -d DEST, --destination=DEST 03:47:02 INFO - Directory to install application into. [default: 03:47:02 INFO - "/builds/slave/test"] 03:47:02 INFO - --app=APP Application being installed. [default: firefox] 03:47:02 INFO - mkdir: /builds/slave/test/build/application 03:47:02 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'] 03:47:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 03:47:20 INFO - Reading from file tmpfile_stdout 03:47:20 INFO - Output received: 03:47:20 INFO - /builds/slave/test/build/application/firefox/firefox 03:47:20 INFO - Running post-action listener: _resource_record_post_action 03:47:20 INFO - [mozharness: 2017-09-28 10:47:20.123556Z] Finished install step (success) 03:47:20 INFO - [mozharness: 2017-09-28 10:47:20.123813Z] Running setup-mitmproxy step. 03:47:20 INFO - Running pre-action listener: _resource_record_pre_action 03:47:20 INFO - Running main action method: setup_mitmproxy 03:47:20 INFO - Skipping: mitmproxy is not required 03:47:20 INFO - Running post-action listener: _resource_record_post_action 03:47:20 INFO - [mozharness: 2017-09-28 10:47:20.124948Z] Finished setup-mitmproxy step (success) 03:47:20 INFO - [mozharness: 2017-09-28 10:47:20.125186Z] Running run-tests step. 03:47:20 INFO - Running pre-action listener: _resource_record_pre_action 03:47:20 INFO - Running pre-action listener: _set_gcov_prefix 03:47:20 INFO - Running main action method: run_tests 03:47:20 WARNING - Try message not found. 03:47:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:47:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:47:20 INFO - Python 2.7.3 03:47:20 INFO - Return code: 0 03:47:20 INFO - grabbing minidump binary from tooltool 03:47:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:47:20 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 0x1eddd60>, '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 0x1edea60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1edeef0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:47:20 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 03:47:20 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 03:47:20 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 03:47:20 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:47:20 INFO - Return code: 0 03:47:20 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 03:47:20 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:47:20 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:47:20 INFO - ENV: RUST_BACKTRACE is now full 03:47:20 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 03:47:20 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:47:20 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/RdxJmsEUSFuRZWELzxBWSw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-029', '--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 03:47:20 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/RdxJmsEUSFuRZWELzxBWSw/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-029 --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 03:47:20 INFO - Using env: {'DISPLAY': ':0', 03:47:20 INFO - 'HOME': '/home/cltbld', 03:47:20 INFO - 'LANG': 'en_US.UTF-8', 03:47:20 INFO - 'LANGUAGE': 'en_US:en', 03:47:20 INFO - 'LOGNAME': 'cltbld', 03:47:20 INFO - 'MAIL': '/var/mail/cltbld', 03:47:20 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:47:20 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:47:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:47:20 INFO - 'MOZ_NO_REMOTE': '1', 03:47:20 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:47:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:47:20 INFO - 'NO_EM_RESTART': '1', 03:47:20 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:47:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:47:20 INFO - 'PWD': '/builds/slave/test', 03:47:20 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:47:20 INFO - 'RUST_BACKTRACE': 'full', 03:47:20 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 03:47:20 INFO - 'SHELL': '/bin/bash', 03:47:20 INFO - 'SHLVL': '1', 03:47:20 INFO - 'TERM': 'linux', 03:47:20 INFO - 'TMOUT': '86400', 03:47:20 INFO - 'USER': 'cltbld', 03:47:20 INFO - 'XDG_SESSION_COOKIE': 'ba164b0a7b0ec7655860e956000001c1-1506595574.805415-722265750', 03:47:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:47:20 INFO - '_': '/tools/buildbot/bin/python'} 03:47:20 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/RdxJmsEUSFuRZWELzxBWSw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-029', '--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 03:47:20 INFO - mozversion application_buildid: 20170928094443 03:47:20 INFO - mozversion application_changeset: 76a26ef7c493311c170ae83eb0c1d6592a21396d 03:47:20 INFO - mozversion application_display_name: Nightly 03:47:20 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:47:20 INFO - mozversion application_name: Firefox 03:47:20 INFO - mozversion application_remotingname: firefox 03:47:20 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 03:47:20 INFO - mozversion application_vendor: Mozilla 03:47:20 INFO - mozversion application_version: 58.0a1 03:47:20 INFO - mozversion platform_buildid: 20170928094443 03:47:20 INFO - mozversion platform_changeset: 76a26ef7c493311c170ae83eb0c1d6592a21396d 03:47:20 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 03:47:20 INFO - mozversion platform_version: 58.0a1 03:47:20 INFO - using testdate: 1506595640 03:47:20 INFO - actual date: 1506595640 03:47:20 INFO - starting webserver on 'localhost:38775' 03:47:20 INFO - SUITE-START | Running 2 tests 03:47:20 INFO - TEST-START | dromaeo_css 03:47:20 INFO - Initialising browser for dromaeo_css test... 03:47:20 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:38775/getInfo.html -profile /tmp/tmpSZct4O/profile 03:47:20 INFO - TEST-INFO | started process 8015 (/builds/slave/test/build/application/firefox/firefox http://localhost:38775/getInfo.html) 03:47:27 INFO - TEST-INFO | 8015: exit 0 03:47:27 INFO - Browser initialized. 03:47:27 INFO - Running cycle 1/1 for dromaeo_css test... 03:47:27 INFO - Using env: {'DISPLAY': ':0', 03:47:27 INFO - 'HOME': '/home/cltbld', 03:47:27 INFO - 'JSGC_DISABLE_POISONING': '1', 03:47:27 INFO - 'LANG': 'en_US.UTF-8', 03:47:27 INFO - 'LANGUAGE': 'en_US:en', 03:47:27 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 03:47:27 INFO - 'LOGNAME': 'cltbld', 03:47:27 INFO - 'MAIL': '/var/mail/cltbld', 03:47:27 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:47:27 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:47:27 INFO - 'MOZ_CRASHREPORTER': '1', 03:47:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:47:27 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 03:47:27 INFO - 'MOZ_NO_REMOTE': '1', 03:47:27 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:47:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:47:27 INFO - 'NO_EM_RESTART': '1', 03:47:27 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:47:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:47:27 INFO - 'PWD': '/builds/slave/test', 03:47:27 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:47:27 INFO - 'RUST_BACKTRACE': 'full', 03:47:27 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 03:47:27 INFO - 'SHELL': '/bin/bash', 03:47:27 INFO - 'SHLVL': '1', 03:47:27 INFO - 'TERM': 'linux', 03:47:27 INFO - 'TMOUT': '86400', 03:47:27 INFO - 'USER': 'cltbld', 03:47:27 INFO - 'XDG_SESSION_COOKIE': 'ba164b0a7b0ec7655860e956000001c1-1506595574.805415-722265750', 03:47:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:47:27 INFO - '_': '/tools/buildbot/bin/python'} 03:47:27 INFO - TEST-INFO | started process 8247 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpSZct4O/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 03:47:28 INFO - PID 8247 | 03:47:28 INFO - PID 8247 | (/builds/slave/test/build/application/firefox/firefox:8296): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 03:47:28 INFO - PID 8247 | 03:47:38 INFO - PID 8247 | RSS: Main: 147419136 03:47:38 INFO - PID 8247 | 03:50:34 INFO - PID 8247 | Cycle 1(1): loaded http://localhost:38775/tests/dromaeo/cssquery-dojo.html (next: http://localhost:38775/tests/dromaeo/cssquery-ext.html) 03:50:36 INFO - PID 8247 | RSS: Main: 148619264 03:50:36 INFO - PID 8247 | 03:53:38 INFO - PID 8247 | Cycle 1(1): loaded http://localhost:38775/tests/dromaeo/cssquery-ext.html (next: http://localhost:38775/tests/dromaeo/cssquery-jquery.html) 03:53:38 INFO - PID 8247 | RSS: Main: 149610496 03:53:38 INFO - PID 8247 | 03:56:40 INFO - PID 8247 | Cycle 1(1): loaded http://localhost:38775/tests/dromaeo/cssquery-jquery.html (next: http://localhost:38775/tests/dromaeo/cssquery-mootools.html) 03:56:40 INFO - PID 8247 | RSS: Main: 150802432 03:56:40 INFO - PID 8247 | 03:59:42 INFO - PID 8247 | Cycle 1(1): loaded http://localhost:38775/tests/dromaeo/cssquery-mootools.html (next: http://localhost:38775/tests/dromaeo/cssquery-prototype.html) 03:59:43 INFO - PID 8247 | RSS: Main: 151572480 03:59:43 INFO - PID 8247 | 04:02:46 INFO - PID 8247 | Cycle 1(1): loaded http://localhost:38775/tests/dromaeo/cssquery-prototype.html (next: http://localhost:38775/tests/dromaeo/cssquery-yui.html) 04:02:47 INFO - PID 8247 | RSS: Main: 154718208 04:02:47 INFO - PID 8247 | 04:05:48 INFO - PID 8247 | Cycle 1(1): loaded http://localhost:38775/tests/dromaeo/cssquery-yui.html (next: http://localhost:38775/tests/dromaeo/cssquery-dojo.html) 04:05:48 INFO - PID 8247 | RSS: Main: 155033600 04:05:48 INFO - PID 8247 | 04:05:48 INFO - PID 8247 | __start_tp_report 04:05:48 INFO - PID 8247 | _x_x_mozilla_page_load 04:05:48 INFO - PID 8247 | _x_x_mozilla_page_load_details 04:05:48 INFO - PID 8247 | |i|pagename|runs| 04:05:48 INFO - PID 8247 | |0;dojo.html;3173.8571764270614;3174;3177.1421716136642;3195.824229667369;3200.0399880035984;6165.290991535975;6176.444120029197;6198.411150940665;6225.4085861843105;6307.306196318407;6204.10040544122;6236.407541283571;6258.249010118792;6281.057841323804;6289.559730818844;5741.942580574193;5768.509676677488;5781.768729250825;5813.767449302044;5882.823515294548;5847.502962248214;5870.20752198452;5882.088276317178;5911.083782013795;5919.704014799243;38.53348459163849;5714.257146570961;5782.681952492611;5854.356020837705;5865.384134665863;5322;5703.7148142592705;5729;5756.280464941882;5809.7095145242565;5733.942660573382;5747.712614369265;5829.8251052468495;5830.242068531106;5852.473277405012;6093.969530152365;6224.346443623445;6237.937620623781;6306.400891915261;6319.0837328587095;6634.104395906587;6704.564203326768;6750.696218670122;6767;6776.7628133015105;6629.6022238665355;6729.461643068543;6753.729850806011;6762;6843.520953533254;6167.321594624633;6183.041628547582;6183.350748171467;6223;6290;6797.1843378794365;6928.064711263914;6931.6880740366805;6941.548799328028;6946.16646002483;6547.672616369163;6558.344165583499;6626.7017984190825;6659.700313485905;6666.666666666647;6421.614703117828;6626.86746265072;6654.401103900672;6683.966580167068;6752.5948443093575;6473.741050358027;6489.513286503531;6509.446697030806;6510.44661203803;6613.272540020594;6606.306337834554;6629.7679581214425;6684.632345220963;6715.865682686319;6727.260001399842;5225.817096401608;5298.496642818926;5300.893982120336;5306.62853600252;5310.2831117798605;4543.795529201194;4580.129775342674;4624.213883639722;4637;4691.038337140878;5722.4277572242445;5784.161296611967;5787.623804452697;5795.420457954255;5811.88376232473;6648.634325112069;6711.194656641234;6750.426213771786;6780.32196780318;6782.457403407814;5762.337331206965;5762.625429347079;5781.19063331134;5806.2451881255165;5828.562857785683;5761;5831.300243970666;5835.562332825055;5844.970775146182;5847.152162936351;6526.738930442729;6533.673316334164;6540.542162048706;6617.669116544153;6629.303923088103;6407.327230640809;6499.057636642755;6505.186851643544;6513.413792758674;6762.087118239073;6465.644389558432;6466.676666166767;6484.902726459012;6540.50946179048;6555.704993275219;6464.127342808661;6679.966600166968;6693.631850248283;6705.530612857053;6706.195256569048;6735.090762746966;6750.898736518858;6763.2898545652;6851.725930962705;6853.417459515997;6328.968355158195;6464.41820236181;6498.382653648085;6575.769848055295;6582.736690532325;6498.447631951148;6532.314107018885;6539.70571324282;6556.901646475211;6568.901466477911;6323.051542268697;6339.746410143727;6444.709988050456;6469.126667899774;6469.967650161719;6486.967565162144;6500.057491663588;6577.046328282468;6594.637294948632;6637.601743895381;6219.626822390671;6301.4328710416285;6308.463780578702;6360.49116070704;6382.648954307558 04:05:48 INFO - PID 8247 | |1;ext.html;855.567938191213;865.1262225152595;893.4996402014872;894.4499133033185;902.6795487601897;43192;43487.78256108723;43574.782126089405;43610.78194609027;43737.78131109344;40077.799611001974;40411.59588404115;40475.595244047625;40591;40793.59206407935;7223.241559636242;7363.079615048119;7391.445641576876;7396.889046664304;7405.111386633612;7130;7138.964305178467;7161.140663120433;7190.496665233436;7197.424206063529;7920.841583168333;8158.673653053871;8173.918260817405;8177.632006559719;8188.222118898695;8120.106788253287;8149.511029338259;8177.591120443954;8181.754547363588;8193.918060819375;7799.5710235937;8016.67933282668;8021.27808497238;8043.718469853586;8113.878291825568;38433;38552.61447385518;38775;38777.61222387768;38839.61160388388;3583.1937813991904;3593.1017245688577;3601.549806274216;3623;3630.364686179908;31634.525482117788;31676.36647267065;31823.681763182303;31887.840560797278;31900.680993190002;5194.11700010999;5239.240310155044;5267.130923397634;5292.391374991887;5293.973530132324;188619;189364;191241;191536;191641;42926;43836.3424548632;51400.48599514032;52208.47791522112;52456.737716311174;54626;54738.17892731612;54753.1787023195;54761.452385476434;54848.45151548513;16227.432039878548;16449.342026319053;16469.34122635105;16502.83497165037;16527.91736041312;13193.40629671667;13338.133021353775;13499.865001350057;13505.189688618715;13512.864871351358;14679.339429725687;14820.258987050604;14875.404983800743;14898.702025959637;14982.775258371134;5637.182608521742;5678.5741069419955;5852.209951656472;5921.348651648315;5946.524278057832;993.6671215143008;1009.28340877976;1009.9747506312254;1021.6424251511911;1033.4832583708146;22834.543309134056;22962.770372296396;23117.422064448525;23243.651345229835;23413.297601071994;1103.2332528892503;1104.8342748587615;1104.8784633690289;1123.0622430270816;1123.7359220583157;12256.632301030982;12258.38328284314;12269.938650306869;12331.50673973049;12395.690107747198;223394.1812036571;225052;226517;226604;226871;52096.21855672168;52583;52612.73693631507;52725.209121863205;52814.20778688323;1449.4347204590217;1474.1523623916291;1475.2476237119147;1475.3139789997704;1485.2425263115672;22384;22460.43848903725;22473.213437530263;22612.208572700536;22894.542109158057;38256.426153607164;38312.616873830906;38389.616103838605;38461.4230786544;38465.80767096258;187146;187955;188117;188575;189061;31116.844415778683;31672;32061.51907721433;32070.83964580162;34876.30247394987;43706.34440483332;43713.56286437095;43957.56042439662;44120.33819492646;44229.77885110554;31749.5237571432;32129.196770080936;32365.676343236268;32407.675923240466;32494.187645309055;31952.36095278128;32002.19994500156;32423.67576324301;32453.51319730159;32656.510152348208;35959.460608090376;35970.4604430939;36337.27325453423;36422.27155456929;36661.08347291339;31596.684033159374;31758.206044849063;31828;32193.517097243093;32418.513722293712;22290.77709222887;22431.139699818155;23098.191563295204;23440.53118937646;23481 04:05:48 INFO - PID 8247 | |2;jquery.html;582.1442479555053;596.5674885707863;597.2176448852001;598.1386803003677;609.6464050850507;22431;23269;23284.534309313804;23296.767032329673;23325.416864578394;21177.57644847102;21313;21472.355829325104;21482;23459.530809383843;6575.9013614795695;6624.933750662504;6696;6706.664666766666;6718.160229971254;6485.8054258372085;6548.607083575001;6565.835854103638;6576.177977752781;6600.966995165041;6483.610983340991;6507.9023814642815;6521.771737989171;6558.049082882979;6568.704408301614;6338.429541341278;6544.770933017346;6609.074729537844;6624.635645039521;6653.866922661519;6152.692365381713;6159.38406159385;6170.950938340493;6175.783847565313;6182.072689096626;27407.86296068527;28041.719582804115;28227.435451291065;28246.71753282482;28266.576001359997;2080.209520382245;2089.759677637076;2099.370188943311;2102;2113.0702490904105;17753.822461775475;19403.80596194034;19444.319448819366;19525;19528.414147575597;2421.7093948726265;2436.561418944589;2458.496008318308;2515.937101572457;2519.282004628672;123549;128138;131527;138206;145833.2708336462;14512.927435362755;14615.707266408239;14621.488247911271;14806.259687015821;14834.258287085819;21922.342329730134;22101.447463813533;22146.44633884166;22172.55654886893;22202.777972220396;9989.200863930868;10059.597616095274;10147.695569132939;10197.643082492226;10216.233782466345;9413.435193888388;9456.669016584387;9491.335606507479;9553.522323883779;9618.51907404641;8628.482291062557;8674.826503470022;8683.652653893774;8686.739397818075;8824;2572.488553124971;2589.7928165746694;2610.738926107412;2665.7067722550505;2680.303121188505;983.1593987140892;983.4492684096929;998.7003898830321;1000.4547521600745;1010.3735683876043;19058.61882762337;19495.805041949683;19948.70076948847;20032;20214.191432342835;1033.1373303291684;1043.9634612788516;1049.9002594753485;1070.288258308232;1073.2969904712427;10689.518971646294;10853.51159197838;10995.725106872233;11042.779144417067;11051.336919784839;483928;489148;489279;489486;493602;115479.42260288645;117689.41155294169;117746;118587;118803;1082.2640604388935;1129.2998341028615;1176.9352685602373;1196.1507329795768;1244.6514975806608;20039.398818035483;20217.89891050535;20265.2907148255;20511.077001534668;20840.270590529257;56550.71724641351;57874.7106264466;58115.418845811;58491.4150858503;59760.402395977224;70370.64814676098;71873.92189117272;72072.27927720656;72312;73569.6321518389;14364.35360408805;14630.049046811922;14638.634034149232;14704.117752934859;14787.408503659733;22846.657300140843;22940.197093101662;22980;23066.884665576566;23485.647715284627;18078.45764627063;18271.26914923388;18314.816851831845;18396.080195990413;18431.81568184301;16441.26014329334;16771.413000544922;17324.220410081827;17338.393156239472;17357.739633905756;18904.52738681544;18972.146253418352;19081.42755717331;19209.32767353136;19215.51961200981;14412.711745765235;14477.131372117707;14498;14563.199024053354;14605.342759576057;17889.73165402494;18285.54286142857;18408.907955460138;18430.81569184291;18496.907515462335 04:05:48 INFO - PID 8247 | |3;mootools.html;1378.2695171559074;1407.7466056109902;1416.8441471438146;1432.613194437502;1439.424230307877;14813.111213327194;15469.303881325353;15482.380704771796;15484.922575387136;15492.302846371913;15123.168225747579;15224.771628425555;15243.61890952724;15254.923725381384;15256.84743152568;2631.5131700635347;2746.382064035596;2748.8350698958125;2749.738774816389;2750.3949131191107;2576.3945472813875;2706.9323266918286;2763.77889768819;2767.79241556883;2778.138776979143;2686.1404350607813;2714.2264454630435;2715.7963152763523;2729.426820367715;2730.276476733667;2648.9205323840315;2668.239551727748;2671.719469455718;2682.597610358452;2693.272816339597;2539.0859290655535;2540.174443305933;2544.2621639724457;2588.6505321781697;2592.3648706066947;14764.70470590593;15243.39026438941;15244.23778811055;15262.38950441981;15317.770233446507;8001.519908805491;8292;8300.875486867702;8302.045264794566;8303.750887473385;5341.385740639837;5468.535174510172;5540.113581826889;5614.101743721026;5702.429757024264;1928.855964777418;1968.1635304995464;1982.1773963805003;2012.9899350503301;2016.7075774012833;26446.471070578675;26784.732152678418;26974;27043.729562704317;27096.051638192766;6734.528582999143;6735.326467353226;6743.730250790011;6798.592084474947;6886.827829304308;17292.740608890876;17309.30762769475;17430.564235894206;17440.215190316467;17450.12749362552;7852.057753069668;8071.354291656653;8254;8340.124286949784;8354.164583541597;7376.188619251878;7424.888626670604;7442.590657513781;7483.4013278937555;7485.812854678677;4985.725785081783;5161;5170.45710200431;5212.374515058217;5227.529522343008;1331.5273078057344;1332.8067430222563;1412.0821466046946;1424.7293014327452;1458.8905832062637;896.3456676626099;912.6030176873081;917.958691858868;926.6015613286352;937.8968313485511;8473.49159050459;8632.482051076955;8662.133786621287;8738.475691458532;8816.338774591932;907.8774365460711;957.8467445208734;986.7977064701719;989.5645915797027;1002.0830939690102;5408.91886621701;5551.308379927303;5566.55467562594;5631.155326700946;5662.235598194274;20504.282350117974;21749.891250543962;21763.564728705653;21800.236991705213;22179.223727169796;21119.683204751942;21774.564508709736;21911;22010.559788804134;22062.558748824933;10722.303050301705;10939.945300273606;11101.666949991513;11133.276337038229;11133.443327833576;7762.146163922077;7906.756655791505;8057.597120144086;8133.511989280662;8172.918270817454;18125.36561220351;18540.629187416445;18929.621407572045;18999.525011874815;19362.70955935634;19151.329703460313;19195.32816351421;19201.32795352156;19208.13563389679;19308.517287067934;11378.54485820558;11440.485178166837;11448;11580.884191158317;11632.53469861196;13557.796633050315;13757.724845503235;13758.518451854137;13765.655858603615;13792.586222413343;11232.213745038096;11233;11318.60384886525;11353.54585816558;11597.130215233723;11191.16066295015;11270.380129092979;11282.266652667551;11345;11631.47658355359;9857.852132218166;10030.398176109165;10052.296339256181;10055.69832905014;10075.19398448137;7428.814279643052;7492.662830172546;7499.700011999459;7511.098668159856;7530.774076777706;7458.589777562286;7511.51175173612;7517.736879209201;7522.435817313614;7549.849003019799 04:05:49 INFO - PID 8247 | |4;prototype.html;529.3118945371017;532.398389819504;536.0779459329952;547.8274343581769;549.9175123731442;6244.438000579947;6266.433391652087;6286.591371560845;6331.77838775643;6395.8081257562235;6224.15973843531;6255.812325630227;6287.434130928215;6303.716332765025;6342.714577843996;5225.503577160163;5253.238280449333;5278.630495865291;5279.498447647467;5298.258243845865;4897.461279259279;4956.801727930879;5220.947790522103;5234.842954711346;5271.4464981177;4953.455119936804;4998.2752500887345;5003.424606170301;5012.699238045729;5030.57240134589;4958.454569997298;4981.950180498203;5011.548960593527;5033.798648054073;5056.544910958013;4521;4531.886702832423;4550.8862278442975;4560.384348113029;4569.177548041352;6107.511399088063;6210.596311239755;6221.626702397871;6223.346548612421;6235.688215589203;5756.510696590794;5759.868848526072;5788.623739456922;5798.652080875161;5812.8837423251725;6525.314841941576;6576.166517609553;6627;6649.60102393858;6702.597844129368;4953.2074868020945;4957.3307603473795;4962.255661650745;4978.551930326288;4993.90012199754;6669;6775.220849602359;6847.589144651387;6894.310568943066;7065.57606543609;4803.423589169322;5035.77339019745;5047.848564543069;5100.668456550312;5110.616703747234;5075.289459475676;5111.131107711698;5132.204508301144;5144.9742751286;5160.664556803871;5649.548036157097;5726.169705392695;5744.827655170351;5747.9137812932;5786;6594.142761440982;6598.43913267368;6634.203895532471;6698.22970358415;6699.162604674416;1782.3850771483817;1799.8290162434548;1802.1439816087516;1803.9176494103433;1807.8463330616783;1197.8143387774908;1247.25788156047;1248.843894513186;1266.3288457117742;1268.2960956669137;5618.410066942912;5629.802956896571;5669.165417291354;5768.2501274834;5775.711214439261;6633.535652504278;6711.798646040626;6731.855584550638;6750.966245168841;6765.699662114905;5717.742701578439;5730.942690573124;5731.283589551306;5741.880333335043;5746.482816546447;3482.791032538056;3485.4423292273113;3520.559930008749;3529.3294274087843;3543.574771027493;6079.422454866781;6383.0744542042075;6401.55189136756;6460.806175814733;6514.5114116441455;6786;6847.904335306327;6852.725890964405;6929.099217101744;6941.201761797458;6449.871002579922;6475.417212450902;6529.118568993125;6538.738450462023;6546.18172728409;6706.429953454011;6754.324567543206;6756.594604323756;6761.121054262914;6798.592084474947;6678;6722.764703235363;6737.27140508095;6746.763863264762;6750.561213521106;6519.641419721961;6524.151860258136;6549.934500654933;6584.670766461753;6640.734370625121;4979.626528010342;5027.145385284583;5041.571466425395;5042.495750425075;5052.974735126448;6492.35076492347;6723.562968407038;6731.293214212437;6759.932400675931;6778.220504641832;6305.337939516285;6547.541672082908;6571.010190363278;6602.801915942529;6622.701978410887;6521;6577.6053436793945;6598.37315455031;6617.569857959217;6620.139381880324;6408.14163892466;6466.191726034246;6502.252240992158;6505.544611877123;6507.707153178024;6497.920665387031;6504.959206526933;6520.152380190545;6626.271110177973;6647.933520664731;6341.621891790579;6404.775832845828;6416.903746443901;6445.6454894981225;6480.125183100221 04:05:49 INFO - PID 8247 | |5;yui.html;889.244142478893;903.5482258870564;908.4413085952133;910.2581396162127;932.7341707613327;18681.719774203382;19139;19190.712139317886;19196.520086997833;19284;17999;18107.547311317223;18113;18131.72802407965;18158.909205453987;934.4673536084417;963.1476143612883;967.3615413826876;967.5500892085179;969.5443141723379;981.8232718110737;984.320818635143;984.9606015759362;985.1232403161192;989.7773001074772;992.8659630949837;1016.5425558498705;1020.0462567499375;1025.789713108811;1026.2200727447118;1015.8882522922472;1017.7659138398172;1019.4800651667703;1019.6278358399205;1021.7752094539189;749.5577609210592;750.0998801438296;750.5009168902673;751.2112282103824;751.9924800752032;18367.17347719356;19041.14314855835;19190.13644386006;19213.615727685366;19213.807861921483;15897;16314.428994985119;16371.754423683655;16380.344786208656;16453;17377;17560.121993900382;17969.281228750835;18107.366242181593;18131.4560563182;3688.6495782900583;3703.0742314421395;3716.7026637869176;3719.4048952167523;3734.1971476132762;111159.44420277847;147252.26373868063;148203;149188;149701;17332.39336623212;17592.208350624252;17666.911665441847;17820.19809108593;17828.197731102133;32048.839755801073;32444.35111297761;32532.83733581364;32641;32668.83665581657;8727.432716873383;8981.640734370683;9045.095490450902;9056.683016094537;9084.500352480545;4146.751194928314;4201.13876655291;4204.26425375558;4207.368894665825;4235.343521754133;5201.739913004335;5244.895102097937;5265.578753699771;5318.228856815817;5353.652012619167;1086.6088208245026;1102.4873433853297;1106.1151079136819;1114.1449486604874;1115.11905594581;627.7583130494793;645.9483241340681;655.1973832055694;666.8866292730247;669.263809809206;11629.069674426026;12389.318587477595;12707.301098439675;13415.262160581262;13429.0599658023;687.9552829066095;688.9931100688929;699.9405050570657;702.2345643248883;706.6819931031057;5829.271341082364;5958.493528050078;5987.622846745273;6018.518518518508;6038.245219347582;118680;119575;120459;121899.39050304692;122664;101884;102363.4881825601;104376;106334;107456;90271.54864225637;91390;92136.53931730433;92163.53918230366;92844;10877.891221087688;10966.945165274123;11045.558177672803;11049.779004420026;11419.200655954335;43899.56100438955;44278.33582496201;45304.32043519416;45384.092318154115;45746.085078298915;56356.43643563676;57420;57843.13235301558;59067.40932590619;59278.40721592729;15619.375224990874;15633;16125.75811362807;16126.435574754827;16201.513954581382;16828.411005614744;17116.82883171202;17130.057846818047;17319.134043297534;17323.7401438976;16287.59281017984;16449.83550164483;16611;16714.4149954751;16852.831471685127;16741.1629418531;16743.581410464834;16805.495835124966;16928.915355423145;16970;16268.430604928772;16532.669346612758;16609.16954152312;16662.333506659597;16812.495625131265;11173.776524469627;11455.484503197209;11556.768864622492;11675;11769.17615766888;12924.353782311035;13015.349232538525;13037.47850085986;13039.413226404646;13064.738705226033 04:05:49 INFO - PID 8247 | __end_tp_report 04:05:49 INFO - PID 8247 | __start_cc_report 04:05:49 INFO - PID 8247 | _x_x_mozilla_cycle_collect,278 04:05:49 INFO - PID 8247 | __end_cc_report 04:05:49 INFO - PID 8247 | __startTimestamp1506596748987__endTimestamp 04:05:49 INFO - PID 8247 | 04:05:49 INFO - PID 8247 | ------- Summary: start ------- 04:05:49 INFO - PID 8247 | Number of tests: 6 04:05:49 INFO - PID 8247 | 04:05:49 INFO - PID 8247 | [#0] dojo.html Cycles:165 Average:6124.27 Median:6382.65 stddev:866.43 (13.6%) stddev-sans-first:837.60 04:05:49 INFO - PID 8247 | Values: 3173.9 3174.0 3177.1 3195.8 3200.0 6165.3 6176.4 6198.4 6225.4 6307.3 6204.1 6236.4 6258.2 6281.1 6289.6 5741.9 5768.5 5781.8 5813.8 5882.8 5847.5 5870.2 5882.1 5911.1 5919.7 38.5 5714.3 5782.7 5854.4 5865.4 5322.0 5703.7 5729.0 5756.3 5809.7 5733.9 5747.7 5829.8 5830.2 5852.5 6094.0 6224.3 6237.9 6306.4 6319.1 6634.1 6704.6 6750.7 6767.0 6776.8 6629.6 6729.5 6753.7 6762.0 6843.5 6167.3 6183.0 6183.4 6223.0 6290.0 6797.2 6928.1 6931.7 6941.5 6946.2 6547.7 6558.3 6626.7 6659.7 6666.7 6421.6 6626.9 6654.4 6684.0 6752.6 6473.7 6489.5 6509.4 6510.4 6613.3 6606.3 6629.8 6684.6 6715.9 6727.3 5225.8 5298.5 5300.9 5306.6 5310.3 4543.8 4580.1 4624.2 4637.0 4691.0 5722.4 5784.2 5787.6 5795.4 5811.9 6648.6 6711.2 6750.4 6780.3 6782.5 5762.3 5762.6 5781.2 5806.2 5828.6 5761.0 5831.3 5835.6 5845.0 5847.2 6526.7 6533.7 6540.5 6617.7 6629.3 6407.3 6499.1 6505.2 6513.4 6762.1 6465.6 6466.7 6484.9 6540.5 6555.7 6464.1 6680.0 6693.6 6705.5 6706.2 6735.1 6750.9 6763.3 6851.7 6853.4 6329.0 6464.4 6498.4 6575.8 6582.7 6498.4 6532.3 6539.7 6556.9 6568.9 6323.1 6339.7 6444.7 6469.1 6470.0 6487.0 6500.1 6577.0 6594.6 6637.6 6219.6 6301.4 6308.5 6360.5 6382.6 04:05:49 INFO - PID 8247 | 04:05:49 INFO - PID 8247 | [#1] ext.html Cycles:180 Average:37380.25 Median:19776.28 stddev:52360.07 (264.8%) stddev-sans-first:52435.13 04:05:49 INFO - PID 8247 | Values: 855.6 865.1 893.5 894.4 902.7 43192.0 43487.8 43574.8 43610.8 43737.8 40077.8 40411.6 40475.6 40591.0 40793.6 7223.2 7363.1 7391.4 7396.9 7405.1 7130.0 7139.0 7161.1 7190.5 7197.4 7920.8 8158.7 8173.9 8177.6 8188.2 8120.1 8149.5 8177.6 8181.8 8193.9 7799.6 8016.7 8021.3 8043.7 8113.9 38433.0 38552.6 38775.0 38777.6 38839.6 3583.2 3593.1 3601.5 3623.0 3630.4 31634.5 31676.4 31823.7 31887.8 31900.7 5194.1 5239.2 5267.1 5292.4 5294.0 188619.0 189364.0 191241.0 191536.0 191641.0 42926.0 43836.3 51400.5 52208.5 52456.7 54626.0 54738.2 54753.2 54761.5 54848.5 16227.4 16449.3 16469.3 16502.8 16527.9 13193.4 13338.1 13499.9 13505.2 13512.9 14679.3 14820.3 14875.4 14898.7 14982.8 5637.2 5678.6 5852.2 5921.3 5946.5 993.7 1009.3 1010.0 1021.6 1033.5 22834.5 22962.8 23117.4 23243.7 23413.3 1103.2 1104.8 1104.9 1123.1 1123.7 12256.6 12258.4 12269.9 12331.5 12395.7 223394.2 225052.0 226517.0 226604.0 226871.0 52096.2 52583.0 52612.7 52725.2 52814.2 1449.4 1474.2 1475.2 1475.3 1485.2 22384.0 22460.4 22473.2 22612.2 22894.5 38256.4 38312.6 38389.6 38461.4 38465.8 187146.0 187955.0 188117.0 188575.0 189061.0 31116.8 31672.0 32061.5 32070.8 34876.3 43706.3 43713.6 43957.6 44120.3 44229.8 31749.5 32129.2 32365.7 32407.7 32494.2 31952.4 32002.2 32423.7 32453.5 32656.5 35959.5 35970.5 36337.3 36422.3 36661.1 31596.7 31758.2 31828.0 32193.5 32418.5 22290.8 22431.1 23098.2 23440.5 23481.0 04:05:49 INFO - PID 8247 | 04:05:49 INFO - PID 8247 | [#2] jquery.html Cycles:180 Average:34639.83 Median:22011.89 stddev:82367.81 (374.2%) stddev-sans-first:82559.18 04:05:49 INFO - PID 8247 | Values: 582.1 596.6 597.2 598.1 609.6 22431.0 23269.0 23284.5 23296.8 23325.4 21177.6 21313.0 21472.4 21482.0 23459.5 6575.9 6624.9 6696.0 6706.7 6718.2 6485.8 6548.6 6565.8 6576.2 6601.0 6483.6 6507.9 6521.8 6558.0 6568.7 6338.4 6544.8 6609.1 6624.6 6653.9 6152.7 6159.4 6171.0 6175.8 6182.1 27407.9 28041.7 28227.4 28246.7 28266.6 2080.2 2089.8 2099.4 2102.0 2113.1 17753.8 19403.8 19444.3 19525.0 19528.4 2421.7 2436.6 2458.5 2515.9 2519.3 123549.0 128138.0 131527.0 138206.0 145833.3 14512.9 14615.7 14621.5 14806.3 14834.3 21922.3 22101.4 22146.4 22172.6 22202.8 9989.2 10059.6 10147.7 10197.6 10216.2 9413.4 9456.7 9491.3 9553.5 9618.5 8628.5 8674.8 8683.7 8686.7 8824.0 2572.5 2589.8 2610.7 2665.7 2680.3 983.2 983.4 998.7 1000.5 1010.4 19058.6 19495.8 19948.7 20032.0 20214.2 1033.1 1044.0 1049.9 1070.3 1073.3 10689.5 10853.5 10995.7 11042.8 11051.3 483928.0 489148.0 489279.0 489486.0 493602.0 115479.4 117689.4 117746.0 118587.0 118803.0 1082.3 1129.3 1176.9 1196.2 1244.7 20039.4 20217.9 20265.3 20511.1 20840.3 56550.7 57874.7 58115.4 58491.4 59760.4 70370.6 71873.9 72072.3 72312.0 73569.6 14364.4 14630.0 14638.6 14704.1 14787.4 22846.7 22940.2 22980.0 23066.9 23485.6 18078.5 18271.3 18314.8 18396.1 18431.8 16441.3 16771.4 17324.2 17338.4 17357.7 18904.5 18972.1 19081.4 19209.3 19215.5 14412.7 14477.1 14498.0 14563.2 14605.3 17889.7 18285.5 18408.9 18430.8 18496.9 04:05:49 INFO - PID 8247 | 04:05:49 INFO - PID 8247 | [#3] mootools.html Cycles:180 Average:9414.80 Median:2611.94 stddev:6717.29 (257.2%) stddev-sans-first:6708.99 04:05:49 INFO - PID 8247 | Values: 1378.3 1407.7 1416.8 1432.6 1439.4 14813.1 15469.3 15482.4 15484.9 15492.3 15123.2 15224.8 15243.6 15254.9 15256.8 2631.5 2746.4 2748.8 2749.7 2750.4 2576.4 2706.9 2763.8 2767.8 2778.1 2686.1 2714.2 2715.8 2729.4 2730.3 2648.9 2668.2 2671.7 2682.6 2693.3 2539.1 2540.2 2544.3 2588.7 2592.4 14764.7 15243.4 15244.2 15262.4 15317.8 8001.5 8292.0 8300.9 8302.0 8303.8 5341.4 5468.5 5540.1 5614.1 5702.4 1928.9 1968.2 1982.2 2013.0 2016.7 26446.5 26784.7 26974.0 27043.7 27096.1 6734.5 6735.3 6743.7 6798.6 6886.8 17292.7 17309.3 17430.6 17440.2 17450.1 7852.1 8071.4 8254.0 8340.1 8354.2 7376.2 7424.9 7442.6 7483.4 7485.8 4985.7 5161.0 5170.5 5212.4 5227.5 1331.5 1332.8 1412.1 1424.7 1458.9 896.3 912.6 918.0 926.6 937.9 8473.5 8632.5 8662.1 8738.5 8816.3 907.9 957.8 986.8 989.6 1002.1 5408.9 5551.3 5566.6 5631.2 5662.2 20504.3 21749.9 21763.6 21800.2 22179.2 21119.7 21774.6 21911.0 22010.6 22062.6 10722.3 10939.9 11101.7 11133.3 11133.4 7762.1 7906.8 8057.6 8133.5 8172.9 18125.4 18540.6 18929.6 18999.5 19362.7 19151.3 19195.3 19201.3 19208.1 19308.5 11378.5 11440.5 11448.0 11580.9 11632.5 13557.8 13757.7 13758.5 13765.7 13792.6 11232.2 11233.0 11318.6 11353.5 11597.1 11191.2 11270.4 11282.3 11345.0 11631.5 9857.9 10030.4 10052.3 10055.7 10075.2 7428.8 7492.7 7499.7 7511.1 7530.8 7458.6 7511.5 7517.7 7522.4 7549.8 04:05:49 INFO - PID 8247 | 04:05:49 INFO - PID 8247 | [#4] prototype.html Cycles:180 Average:5549.92 Median:6229.92 stddev:1542.61 (24.8%) stddev-sans-first:1500.20 04:05:49 INFO - PID 8247 | Values: 529.3 532.4 536.1 547.8 549.9 6244.4 6266.4 6286.6 6331.8 6395.8 6224.2 6255.8 6287.4 6303.7 6342.7 5225.5 5253.2 5278.6 5279.5 5298.3 4897.5 4956.8 5220.9 5234.8 5271.4 4953.5 4998.3 5003.4 5012.7 5030.6 4958.5 4982.0 5011.5 5033.8 5056.5 4521.0 4531.9 4550.9 4560.4 4569.2 6107.5 6210.6 6221.6 6223.3 6235.7 5756.5 5759.9 5788.6 5798.7 5812.9 6525.3 6576.2 6627.0 6649.6 6702.6 4953.2 4957.3 4962.3 4978.6 4993.9 6669.0 6775.2 6847.6 6894.3 7065.6 4803.4 5035.8 5047.8 5100.7 5110.6 5075.3 5111.1 5132.2 5145.0 5160.7 5649.5 5726.2 5744.8 5747.9 5786.0 6594.1 6598.4 6634.2 6698.2 6699.2 1782.4 1799.8 1802.1 1803.9 1807.8 1197.8 1247.3 1248.8 1266.3 1268.3 5618.4 5629.8 5669.2 5768.3 5775.7 6633.5 6711.8 6731.9 6751.0 6765.7 5717.7 5730.9 5731.3 5741.9 5746.5 3482.8 3485.4 3520.6 3529.3 3543.6 6079.4 6383.1 6401.6 6460.8 6514.5 6786.0 6847.9 6852.7 6929.1 6941.2 6449.9 6475.4 6529.1 6538.7 6546.2 6706.4 6754.3 6756.6 6761.1 6798.6 6678.0 6722.8 6737.3 6746.8 6750.6 6519.6 6524.2 6549.9 6584.7 6640.7 4979.6 5027.1 5041.6 5042.5 5053.0 6492.4 6723.6 6731.3 6759.9 6778.2 6305.3 6547.5 6571.0 6602.8 6622.7 6521.0 6577.6 6598.4 6617.6 6620.1 6408.1 6466.2 6502.3 6505.5 6507.7 6497.9 6505.0 6520.2 6626.3 6647.9 6341.6 6404.8 6416.9 6445.6 6480.1 04:05:49 INFO - PID 8247 | 04:05:49 INFO - PID 8247 | [#5] yui.html Cycles:180 Average:24138.58 Median:18122.23 stddev:34896.98 (192.6%) stddev-sans-first:34951.21 04:05:49 INFO - PID 8247 | Values: 889.2 903.5 908.4 910.3 932.7 18681.7 19139.0 19190.7 19196.5 19284.0 17999.0 18107.5 18113.0 18131.7 18158.9 934.5 963.1 967.4 967.6 969.5 981.8 984.3 985.0 985.1 989.8 992.9 1016.5 1020.0 1025.8 1026.2 1015.9 1017.8 1019.5 1019.6 1021.8 749.6 750.1 750.5 751.2 752.0 18367.2 19041.1 19190.1 19213.6 19213.8 15897.0 16314.4 16371.8 16380.3 16453.0 17377.0 17560.1 17969.3 18107.4 18131.5 3688.6 3703.1 3716.7 3719.4 3734.2 111159.4 147252.3 148203.0 149188.0 149701.0 17332.4 17592.2 17666.9 17820.2 17828.2 32048.8 32444.4 32532.8 32641.0 32668.8 8727.4 8981.6 9045.1 9056.7 9084.5 4146.8 4201.1 4204.3 4207.4 4235.3 5201.7 5244.9 5265.6 5318.2 5353.7 1086.6 1102.5 1106.1 1114.1 1115.1 627.8 645.9 655.2 666.9 669.3 11629.1 12389.3 12707.3 13415.3 13429.1 688.0 689.0 699.9 702.2 706.7 5829.3 5958.5 5987.6 6018.5 6038.2 118680.0 119575.0 120459.0 121899.4 122664.0 101884.0 102363.5 104376.0 106334.0 107456.0 90271.5 91390.0 92136.5 92163.5 92844.0 10877.9 10966.9 11045.6 11049.8 11419.2 43899.6 44278.3 45304.3 45384.1 45746.1 56356.4 57420.0 57843.1 59067.4 59278.4 15619.4 15633.0 16125.8 16126.4 16201.5 16828.4 17116.8 17130.1 17319.1 17323.7 16287.6 16449.8 16611.0 16714.4 16852.8 16741.2 16743.6 16805.5 16928.9 16970.0 16268.4 16532.7 16609.2 16662.3 16812.5 11173.8 11455.5 11556.8 11675.0 11769.2 12924.4 13015.3 13037.5 13039.4 13064.7 04:05:49 INFO - PID 8247 | -------- Summary: end -------- 04:05:49 INFO - PID 8247 | 04:05:49 INFO - TEST-INFO | 8247: exit 0 04:05:49 INFO - TEST-OK | dromaeo_css | took 1109054ms 04:05:49 INFO - TEST-START | kraken 04:05:49 INFO - Initialising browser for kraken test... 04:05:49 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:38775/getInfo.html -profile /tmp/tmp8juUoA/profile 04:05:49 INFO - TEST-INFO | started process 8606 (/builds/slave/test/build/application/firefox/firefox http://localhost:38775/getInfo.html) 04:05:55 INFO - TEST-INFO | 8606: exit 0 04:05:55 INFO - Browser initialized. 04:05:55 INFO - Running cycle 1/1 for kraken test... 04:05:55 INFO - Using env: {'DISPLAY': ':0', 04:05:55 INFO - 'HOME': '/home/cltbld', 04:05:55 INFO - 'JSGC_DISABLE_POISONING': '1', 04:05:55 INFO - 'LANG': 'en_US.UTF-8', 04:05:55 INFO - 'LANGUAGE': 'en_US:en', 04:05:55 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 04:05:55 INFO - 'LOGNAME': 'cltbld', 04:05:55 INFO - 'MAIL': '/var/mail/cltbld', 04:05:55 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:05:55 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 04:05:55 INFO - 'MOZ_CRASHREPORTER': '1', 04:05:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:05:55 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 04:05:55 INFO - 'MOZ_NO_REMOTE': '1', 04:05:55 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:05:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:05:55 INFO - 'NO_EM_RESTART': '1', 04:05:55 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:05:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:05:55 INFO - 'PWD': '/builds/slave/test', 04:05:55 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:05:55 INFO - 'RUST_BACKTRACE': 'full', 04:05:55 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 04:05:55 INFO - 'SHELL': '/bin/bash', 04:05:55 INFO - 'SHLVL': '1', 04:05:55 INFO - 'TERM': 'linux', 04:05:55 INFO - 'TMOUT': '86400', 04:05:55 INFO - 'USER': 'cltbld', 04:05:55 INFO - 'XDG_SESSION_COOKIE': 'ba164b0a7b0ec7655860e956000001c1-1506595574.805415-722265750', 04:05:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:05:55 INFO - '_': '/tools/buildbot/bin/python'} 04:05:55 INFO - TEST-INFO | started process 8837 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp8juUoA/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 04:05:56 INFO - PID 8837 | 04:05:56 INFO - PID 8837 | (/builds/slave/test/build/application/firefox/firefox:8885): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 04:05:56 INFO - PID 8837 | 04:06:06 INFO - PID 8837 | RSS: Main: 153505792 04:06:06 INFO - PID 8837 | 04:06:09 INFO - PID 8837 | Cycle 1(1): loaded http://localhost:38775/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:38775/tests/kraken/driver.html?testName=audio-beat-detection) 04:06:10 INFO - PID 8837 | RSS: Main: 158969856 04:06:10 INFO - PID 8837 | 04:06:12 INFO - PID 8837 | Cycle 1(1): loaded http://localhost:38775/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:38775/tests/kraken/driver.html?testName=audio-dft) 04:06:13 INFO - PID 8837 | RSS: Main: 159490048 04:06:13 INFO - PID 8837 | 04:06:16 INFO - PID 8837 | Cycle 1(1): loaded http://localhost:38775/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:38775/tests/kraken/driver.html?testName=audio-fft) 04:06:17 INFO - PID 8837 | RSS: Main: 155230208 04:06:17 INFO - PID 8837 | 04:06:19 INFO - PID 8837 | Cycle 1(1): loaded http://localhost:38775/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:38775/tests/kraken/driver.html?testName=audio-oscillator) 04:06:19 INFO - PID 8837 | RSS: Main: 156209152 04:06:19 INFO - PID 8837 | 04:06:21 INFO - PID 8837 | Cycle 1(1): loaded http://localhost:38775/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:38775/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:06:22 INFO - PID 8837 | RSS: Main: 156078080 04:06:22 INFO - PID 8837 | 04:06:25 INFO - PID 8837 | Cycle 1(1): loaded http://localhost:38775/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:38775/tests/kraken/driver.html?testName=imaging-darkroom) 04:06:26 INFO - PID 8837 | RSS: Main: 157888512 04:06:26 INFO - PID 8837 | 04:06:29 INFO - PID 8837 | Cycle 1(1): loaded http://localhost:38775/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:38775/tests/kraken/driver.html?testName=imaging-desaturate) 04:06:30 INFO - PID 8837 | RSS: Main: 158175232 04:06:30 INFO - PID 8837 | 04:06:33 INFO - PID 8837 | Cycle 1(1): loaded http://localhost:38775/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:38775/tests/kraken/driver.html?testName=json-parse-financial) 04:06:34 INFO - PID 8837 | RSS: Main: 158388224 04:06:34 INFO - PID 8837 | 04:06:35 INFO - PID 8837 | Cycle 1(1): loaded http://localhost:38775/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:38775/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:06:36 INFO - PID 8837 | RSS: Main: 159358976 04:06:36 INFO - PID 8837 | 04:06:37 INFO - PID 8837 | Cycle 1(1): loaded http://localhost:38775/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:38775/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:06:38 INFO - PID 8837 | RSS: Main: 158605312 04:06:38 INFO - PID 8837 | 04:06:40 INFO - PID 8837 | Cycle 1(1): loaded http://localhost:38775/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:38775/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:06:41 INFO - PID 8837 | RSS: Main: 155656192 04:06:41 INFO - PID 8837 | 04:06:43 INFO - PID 8837 | Cycle 1(1): loaded http://localhost:38775/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:38775/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:06:44 INFO - PID 8837 | RSS: Main: 156225536 04:06:44 INFO - PID 8837 | 04:06:47 INFO - PID 8837 | Cycle 1(1): loaded http://localhost:38775/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:38775/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:06:48 INFO - PID 8837 | RSS: Main: 156323840 04:06:48 INFO - PID 8837 | 04:06:49 INFO - PID 8837 | Cycle 1(1): loaded http://localhost:38775/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:38775/tests/kraken/driver.html?testName=ai-astar) 04:06:49 INFO - PID 8837 | RSS: Main: 158060544 04:06:49 INFO - PID 8837 | 04:06:49 INFO - PID 8837 | __start_tp_report 04:06:49 INFO - PID 8837 | _x_x_mozilla_page_load 04:06:49 INFO - PID 8837 | _x_x_mozilla_page_load_details 04:06:49 INFO - PID 8837 | |i|pagename|runs| 04:06:49 INFO - PID 8837 | |0;ai-astar;108;106;108;106;107;110;108;111;110;107 04:06:49 INFO - PID 8837 | |1;audio-beat-detection;117;127;121;118;120;119;117;116;144;118 04:06:49 INFO - PID 8837 | |2;audio-dft;162;162;162;162;162;164;161;164;165;164 04:06:49 INFO - PID 8837 | |3;audio-fft;75;73;74;72;74;75;75;74;74;73 04:06:49 INFO - PID 8837 | |4;audio-oscillator;81;79;78;78;79;81;80;87;77;78 04:06:49 INFO - PID 8837 | |5;imaging-gaussian-blur;111;113;110;114;114;113;114;114;114;114 04:06:49 INFO - PID 8837 | |6;imaging-darkroom;156;148;147;147;150;149;148;149;148;147 04:06:49 INFO - PID 8837 | |7;imaging-desaturate;94;95;95;94;94;94;94;94;94;94 04:06:49 INFO - PID 8837 | |8;json-parse-financial;54;53;50;51;52;51;52;55;51;51 04:06:49 INFO - PID 8837 | |9;json-stringify-tinderbox;37;36;37;37;37;37;37;38;38;38 04:06:49 INFO - PID 8837 | |10;stanford-crypto-aes;74;74;72;71;75;74;72;74;73;73 04:06:49 INFO - PID 8837 | |11;stanford-crypto-ccm;106;107;112;112;114;116;117;112;111;116 04:06:49 INFO - PID 8837 | |12;stanford-crypto-pbkdf2;167;164;170;167;169;167;165;161;169;161 04:06:49 INFO - PID 8837 | |13;stanford-crypto-sha256-iterative;66;62;63;64;63;63;63;64;65;64 04:06:49 INFO - PID 8837 | __end_tp_report 04:06:49 INFO - PID 8837 | __start_cc_report 04:06:49 INFO - PID 8837 | _x_x_mozilla_cycle_collect,772 04:06:49 INFO - PID 8837 | __end_cc_report 04:06:49 INFO - PID 8837 | __startTimestamp1506596809973__endTimestamp 04:06:49 INFO - PID 8837 | 04:06:49 INFO - PID 8837 | ------- Summary: start ------- 04:06:49 INFO - PID 8837 | Number of tests: 14 04:06:49 INFO - PID 8837 | 04:06:49 INFO - PID 8837 | [#0] ai-astar Cycles:10 Average:108.10 Median:108.00 stddev:1.73 (1.6%) stddev-sans-first:1.83 04:06:49 INFO - PID 8837 | Values: 108.0 106.0 108.0 106.0 107.0 110.0 108.0 111.0 110.0 107.0 04:06:49 INFO - PID 8837 | 04:06:49 INFO - PID 8837 | [#1] audio-beat-detection Cycles:10 Average:121.70 Median:119.50 stddev:8.43 (7.1%) stddev-sans-first:8.77 04:06:49 INFO - PID 8837 | Values: 117.0 127.0 121.0 118.0 120.0 119.0 117.0 116.0 144.0 118.0 04:06:49 INFO - PID 8837 | 04:06:49 INFO - PID 8837 | [#2] audio-dft Cycles:10 Average:162.80 Median:163.00 stddev:1.32 (0.8%) stddev-sans-first:1.36 04:06:49 INFO - PID 8837 | Values: 162.0 162.0 162.0 162.0 162.0 164.0 161.0 164.0 165.0 164.0 04:06:49 INFO - PID 8837 | 04:06:49 INFO - PID 8837 | [#3] audio-fft Cycles:10 Average:73.90 Median:74.00 stddev:0.99 (1.3%) stddev-sans-first:0.97 04:06:49 INFO - PID 8837 | Values: 75.0 73.0 74.0 72.0 74.0 75.0 75.0 74.0 74.0 73.0 04:06:49 INFO - PID 8837 | 04:06:49 INFO - PID 8837 | [#4] audio-oscillator Cycles:10 Average:79.80 Median:79.50 stddev:2.86 (3.6%) stddev-sans-first:3.00 04:06:49 INFO - PID 8837 | Values: 81.0 79.0 78.0 78.0 79.0 81.0 80.0 87.0 77.0 78.0 04:06:49 INFO - PID 8837 | 04:06:49 INFO - PID 8837 | [#5] imaging-gaussian-blur Cycles:10 Average:113.10 Median:114.00 stddev:1.45 (1.3%) stddev-sans-first:1.32 04:06:49 INFO - PID 8837 | Values: 111.0 113.0 110.0 114.0 114.0 113.0 114.0 114.0 114.0 114.0 04:06:49 INFO - PID 8837 | 04:06:49 INFO - PID 8837 | [#6] imaging-darkroom Cycles:10 Average:148.90 Median:148.50 stddev:2.69 (1.8%) stddev-sans-first:1.05 04:06:49 INFO - PID 8837 | Values: 156.0 148.0 147.0 147.0 150.0 149.0 148.0 149.0 148.0 147.0 04:06:49 INFO - PID 8837 | 04:06:49 INFO - PID 8837 | [#7] imaging-desaturate Cycles:10 Average:94.20 Median:94.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 04:06:49 INFO - PID 8837 | Values: 94.0 95.0 95.0 94.0 94.0 94.0 94.0 94.0 94.0 94.0 04:06:49 INFO - PID 8837 | 04:06:49 INFO - PID 8837 | [#8] json-parse-financial Cycles:10 Average:52.00 Median:52.00 stddev:1.56 (3.0%) stddev-sans-first:1.48 04:06:49 INFO - PID 8837 | Values: 54.0 53.0 50.0 51.0 52.0 51.0 52.0 55.0 51.0 51.0 04:06:49 INFO - PID 8837 | 04:06:49 INFO - PID 8837 | [#9] json-stringify-tinderbox Cycles:10 Average:37.20 Median:37.00 stddev:0.63 (1.7%) stddev-sans-first:0.67 04:06:49 INFO - PID 8837 | Values: 37.0 36.0 37.0 37.0 37.0 37.0 37.0 38.0 38.0 38.0 04:06:49 INFO - PID 8837 | 04:06:49 INFO - PID 8837 | [#10] stanford-crypto-aes Cycles:10 Average:73.20 Median:74.00 stddev:1.23 (1.7%) stddev-sans-first:1.27 04:06:49 INFO - PID 8837 | Values: 74.0 74.0 72.0 71.0 75.0 74.0 72.0 74.0 73.0 73.0 04:06:49 INFO - PID 8837 | 04:06:49 INFO - PID 8837 | [#11] stanford-crypto-ccm Cycles:10 Average:112.30 Median:113.00 stddev:3.68 (3.3%) stddev-sans-first:3.12 04:06:49 INFO - PID 8837 | Values: 106.0 107.0 112.0 112.0 114.0 116.0 117.0 112.0 111.0 116.0 04:06:49 INFO - PID 8837 | 04:06:49 INFO - PID 8837 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:166.00 Median:167.00 stddev:3.20 (1.9%) stddev-sans-first:3.37 04:06:49 INFO - PID 8837 | Values: 167.0 164.0 170.0 167.0 169.0 167.0 165.0 161.0 169.0 161.0 04:06:49 INFO - PID 8837 | 04:06:49 INFO - PID 8837 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.70 Median:64.00 stddev:1.16 (1.8%) stddev-sans-first:0.88 04:06:49 INFO - PID 8837 | Values: 66.0 62.0 63.0 64.0 63.0 63.0 63.0 64.0 65.0 64.0 04:06:49 INFO - PID 8837 | -------- Summary: end -------- 04:06:49 INFO - PID 8837 | 04:06:50 INFO - PID 8837 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 04:06:50 INFO - TEST-INFO | 8837: exit 0 04:06:50 INFO - TEST-OK | kraken | took 61234ms 04:06:50 INFO - SUITE-END | took 1170s 04:06:51 INFO - Completed test suite (00:19:30) 04:06:51 INFO - javascript benchmark 04:06:51 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8529.557284971497, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3173.8571764270614, 3174.0, 3177.1421716136642, 3195.824229667369, 3200.0399880035984, 6165.290991535975, 6176.444120029197, 6198.411150940665, 6225.4085861843105, 6307.306196318407, 6204.10040544122, 6236.407541283571, 6258.249010118792, 6281.057841323804, 6289.559730818844, 5741.942580574193, 5768.509676677488, 5781.768729250825, 5813.767449302044, 5882.823515294548, 5847.502962248214, 5870.20752198452, 5882.088276317178, 5911.083782013795, 5919.704014799243, 38.53348459163849, 5714.257146570961, 5782.681952492611, 5854.356020837705, 5865.384134665863, 5322.0, 5703.7148142592705, 5729.0, 5756.280464941882, 5809.7095145242565, 5733.942660573382, 5747.712614369265, 5829.8251052468495, 5830.242068531106, 5852.473277405012, 6093.969530152365, 6224.346443623445, 6237.937620623781, 6306.400891915261, 6319.0837328587095, 6634.104395906587, 6704.564203326768, 6750.696218670122, 6767.0, 6776.7628133015105, 6629.6022238665355, 6729.461643068543, 6753.729850806011, 6762.0, 6843.520953533254, 6167.321594624633, 6183.041628547582, 6183.350748171467, 6223.0, 6290.0, 6797.1843378794365, 6928.064711263914, 6931.6880740366805, 6941.548799328028, 6946.16646002483, 6547.672616369163, 6558.344165583499, 6626.7017984190825, 6659.700313485905, 6666.666666666647, 6421.614703117828, 6626.86746265072, 6654.401103900672, 6683.966580167068, 6752.5948443093575, 6473.741050358027, 6489.513286503531, 6509.446697030806, 6510.44661203803, 6613.272540020594, 6606.306337834554, 6629.7679581214425, 6684.632345220963, 6715.865682686319, 6727.260001399842, 5225.817096401608, 5298.496642818926, 5300.893982120336, 5306.62853600252, 5310.2831117798605, 4543.795529201194, 4580.129775342674, 4624.213883639722, 4637.0, 4691.038337140878, 5722.4277572242445, 5784.161296611967, 5787.623804452697, 5795.420457954255, 5811.88376232473, 6648.634325112069, 6711.194656641234, 6750.426213771786, 6780.32196780318, 6782.457403407814, 5762.337331206965, 5762.625429347079, 5781.19063331134, 5806.2451881255165, 5828.562857785683, 5761.0, 5831.300243970666, 5835.562332825055, 5844.970775146182, 5847.152162936351, 6526.738930442729, 6533.673316334164, 6540.542162048706, 6617.669116544153, 6629.303923088103, 6407.327230640809, 6499.057636642755, 6505.186851643544, 6513.413792758674, 6762.087118239073, 6465.644389558432, 6466.676666166767, 6484.902726459012, 6540.50946179048, 6555.704993275219, 6464.127342808661, 6679.966600166968, 6693.631850248283, 6705.530612857053, 6706.195256569048, 6735.090762746966, 6750.898736518858, 6763.2898545652, 6851.725930962705, 6853.417459515997, 6328.968355158195, 6464.41820236181, 6498.382653648085, 6575.769848055295, 6582.736690532325, 6498.447631951148, 6532.314107018885, 6539.70571324282, 6556.901646475211, 6568.901466477911, 6323.051542268697, 6339.746410143727, 6444.709988050456, 6469.126667899774, 6469.967650161719, 6486.967565162144, 6500.057491663588, 6577.046328282468, 6594.637294948632, 6637.601743895381, 6219.626822390671, 6301.4328710416285, 6308.463780578702, 6360.49116070704, 6382.648954307558], "value": 6064.440206585021, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [855.567938191213, 865.1262225152595, 893.4996402014872, 894.4499133033185, 902.6795487601897, 43192.0, 43487.78256108723, 43574.782126089405, 43610.78194609027, 43737.78131109344, 40077.799611001974, 40411.59588404115, 40475.595244047625, 40591.0, 40793.59206407935, 7223.241559636242, 7363.079615048119, 7391.445641576876, 7396.889046664304, 7405.111386633612, 7130.0, 7138.964305178467, 7161.140663120433, 7190.496665233436, 7197.424206063529, 7920.841583168333, 8158.673653053871, 8173.918260817405, 8177.632006559719, 8188.222118898695, 8120.106788253287, 8149.511029338259, 8177.591120443954, 8181.754547363588, 8193.918060819375, 7799.5710235937, 8016.67933282668, 8021.27808497238, 8043.718469853586, 8113.878291825568, 38433.0, 38552.61447385518, 38775.0, 38777.61222387768, 38839.61160388388, 3583.1937813991904, 3593.1017245688577, 3601.549806274216, 3623.0, 3630.364686179908, 31634.525482117788, 31676.36647267065, 31823.681763182303, 31887.840560797278, 31900.680993190002, 5194.11700010999, 5239.240310155044, 5267.130923397634, 5292.391374991887, 5293.973530132324, 188619.0, 189364.0, 191241.0, 191536.0, 191641.0, 42926.0, 43836.3424548632, 51400.48599514032, 52208.47791522112, 52456.737716311174, 54626.0, 54738.17892731612, 54753.1787023195, 54761.452385476434, 54848.45151548513, 16227.432039878548, 16449.342026319053, 16469.34122635105, 16502.83497165037, 16527.91736041312, 13193.40629671667, 13338.133021353775, 13499.865001350057, 13505.189688618715, 13512.864871351358, 14679.339429725687, 14820.258987050604, 14875.404983800743, 14898.702025959637, 14982.775258371134, 5637.182608521742, 5678.5741069419955, 5852.209951656472, 5921.348651648315, 5946.524278057832, 993.6671215143008, 1009.28340877976, 1009.9747506312254, 1021.6424251511911, 1033.4832583708146, 22834.543309134056, 22962.770372296396, 23117.422064448525, 23243.651345229835, 23413.297601071994, 1103.2332528892503, 1104.8342748587615, 1104.8784633690289, 1123.0622430270816, 1123.7359220583157, 12256.632301030982, 12258.38328284314, 12269.938650306869, 12331.50673973049, 12395.690107747198, 223394.1812036571, 225052.0, 226517.0, 226604.0, 226871.0, 52096.21855672168, 52583.0, 52612.73693631507, 52725.209121863205, 52814.20778688323, 1449.4347204590217, 1474.1523623916291, 1475.2476237119147, 1475.3139789997704, 1485.2425263115672, 22384.0, 22460.43848903725, 22473.213437530263, 22612.208572700536, 22894.542109158057, 38256.426153607164, 38312.616873830906, 38389.616103838605, 38461.4230786544, 38465.80767096258, 187146.0, 187955.0, 188117.0, 188575.0, 189061.0, 31116.844415778683, 31672.0, 32061.51907721433, 32070.83964580162, 34876.30247394987, 43706.34440483332, 43713.56286437095, 43957.56042439662, 44120.33819492646, 44229.77885110554, 31749.5237571432, 32129.196770080936, 32365.676343236268, 32407.675923240466, 32494.187645309055, 31952.36095278128, 32002.19994500156, 32423.67576324301, 32453.51319730159, 32656.510152348208, 35959.460608090376, 35970.4604430939, 36337.27325453423, 36422.27155456929, 36661.08347291339, 31596.684033159374, 31758.206044849063, 31828.0, 32193.517097243093, 32418.513722293712, 22290.77709222887, 22431.139699818155, 23098.191563295204, 23440.53118937646, 23481.0], "value": 17273.480442685468, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [582.1442479555053, 596.5674885707863, 597.2176448852001, 598.1386803003677, 609.6464050850507, 22431.0, 23269.0, 23284.534309313804, 23296.767032329673, 23325.416864578394, 21177.57644847102, 21313.0, 21472.355829325104, 21482.0, 23459.530809383843, 6575.9013614795695, 6624.933750662504, 6696.0, 6706.664666766666, 6718.160229971254, 6485.8054258372085, 6548.607083575001, 6565.835854103638, 6576.177977752781, 6600.966995165041, 6483.610983340991, 6507.9023814642815, 6521.771737989171, 6558.049082882979, 6568.704408301614, 6338.429541341278, 6544.770933017346, 6609.074729537844, 6624.635645039521, 6653.866922661519, 6152.692365381713, 6159.38406159385, 6170.950938340493, 6175.783847565313, 6182.072689096626, 27407.86296068527, 28041.719582804115, 28227.435451291065, 28246.71753282482, 28266.576001359997, 2080.209520382245, 2089.759677637076, 2099.370188943311, 2102.0, 2113.0702490904105, 17753.822461775475, 19403.80596194034, 19444.319448819366, 19525.0, 19528.414147575597, 2421.7093948726265, 2436.561418944589, 2458.496008318308, 2515.937101572457, 2519.282004628672, 123549.0, 128138.0, 131527.0, 138206.0, 145833.2708336462, 14512.927435362755, 14615.707266408239, 14621.488247911271, 14806.259687015821, 14834.258287085819, 21922.342329730134, 22101.447463813533, 22146.44633884166, 22172.55654886893, 22202.777972220396, 9989.200863930868, 10059.597616095274, 10147.695569132939, 10197.643082492226, 10216.233782466345, 9413.435193888388, 9456.669016584387, 9491.335606507479, 9553.522323883779, 9618.51907404641, 8628.482291062557, 8674.826503470022, 8683.652653893774, 8686.739397818075, 8824.0, 2572.488553124971, 2589.7928165746694, 2610.738926107412, 2665.7067722550505, 2680.303121188505, 983.1593987140892, 983.4492684096929, 998.7003898830321, 1000.4547521600745, 1010.3735683876043, 19058.61882762337, 19495.805041949683, 19948.70076948847, 20032.0, 20214.191432342835, 1033.1373303291684, 1043.9634612788516, 1049.9002594753485, 1070.288258308232, 1073.2969904712427, 10689.518971646294, 10853.51159197838, 10995.725106872233, 11042.779144417067, 11051.336919784839, 483928.0, 489148.0, 489279.0, 489486.0, 493602.0, 115479.42260288645, 117689.41155294169, 117746.0, 118587.0, 118803.0, 1082.2640604388935, 1129.2998341028615, 1176.9352685602373, 1196.1507329795768, 1244.6514975806608, 20039.398818035483, 20217.89891050535, 20265.2907148255, 20511.077001534668, 20840.270590529257, 56550.71724641351, 57874.7106264466, 58115.418845811, 58491.4150858503, 59760.402395977224, 70370.64814676098, 71873.92189117272, 72072.27927720656, 72312.0, 73569.6321518389, 14364.35360408805, 14630.049046811922, 14638.634034149232, 14704.117752934859, 14787.408503659733, 22846.657300140843, 22940.197093101662, 22980.0, 23066.884665576566, 23485.647715284627, 18078.45764627063, 18271.26914923388, 18314.816851831845, 18396.080195990413, 18431.81568184301, 16441.26014329334, 16771.413000544922, 17324.220410081827, 17338.393156239472, 17357.739633905756, 18904.52738681544, 18972.146253418352, 19081.42755717331, 19209.32767353136, 19215.51961200981, 14412.711745765235, 14477.131372117707, 14498.0, 14563.199024053354, 14605.342759576057, 17889.73165402494, 18285.54286142857, 18408.907955460138, 18430.81569184291, 18496.907515462335], "value": 11963.940601352655, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1378.2695171559074, 1407.7466056109902, 1416.8441471438146, 1432.613194437502, 1439.424230307877, 14813.111213327194, 15469.303881325353, 15482.380704771796, 15484.922575387136, 15492.302846371913, 15123.168225747579, 15224.771628425555, 15243.61890952724, 15254.923725381384, 15256.84743152568, 2631.5131700635347, 2746.382064035596, 2748.8350698958125, 2749.738774816389, 2750.3949131191107, 2576.3945472813875, 2706.9323266918286, 2763.77889768819, 2767.79241556883, 2778.138776979143, 2686.1404350607813, 2714.2264454630435, 2715.7963152763523, 2729.426820367715, 2730.276476733667, 2648.9205323840315, 2668.239551727748, 2671.719469455718, 2682.597610358452, 2693.272816339597, 2539.0859290655535, 2540.174443305933, 2544.2621639724457, 2588.6505321781697, 2592.3648706066947, 14764.70470590593, 15243.39026438941, 15244.23778811055, 15262.38950441981, 15317.770233446507, 8001.519908805491, 8292.0, 8300.875486867702, 8302.045264794566, 8303.750887473385, 5341.385740639837, 5468.535174510172, 5540.113581826889, 5614.101743721026, 5702.429757024264, 1928.855964777418, 1968.1635304995464, 1982.1773963805003, 2012.9899350503301, 2016.7075774012833, 26446.471070578675, 26784.732152678418, 26974.0, 27043.729562704317, 27096.051638192766, 6734.528582999143, 6735.326467353226, 6743.730250790011, 6798.592084474947, 6886.827829304308, 17292.740608890876, 17309.30762769475, 17430.564235894206, 17440.215190316467, 17450.12749362552, 7852.057753069668, 8071.354291656653, 8254.0, 8340.124286949784, 8354.164583541597, 7376.188619251878, 7424.888626670604, 7442.590657513781, 7483.4013278937555, 7485.812854678677, 4985.725785081783, 5161.0, 5170.45710200431, 5212.374515058217, 5227.529522343008, 1331.5273078057344, 1332.8067430222563, 1412.0821466046946, 1424.7293014327452, 1458.8905832062637, 896.3456676626099, 912.6030176873081, 917.958691858868, 926.6015613286352, 937.8968313485511, 8473.49159050459, 8632.482051076955, 8662.133786621287, 8738.475691458532, 8816.338774591932, 907.8774365460711, 957.8467445208734, 986.7977064701719, 989.5645915797027, 1002.0830939690102, 5408.91886621701, 5551.308379927303, 5566.55467562594, 5631.155326700946, 5662.235598194274, 20504.282350117974, 21749.891250543962, 21763.564728705653, 21800.236991705213, 22179.223727169796, 21119.683204751942, 21774.564508709736, 21911.0, 22010.559788804134, 22062.558748824933, 10722.303050301705, 10939.945300273606, 11101.666949991513, 11133.276337038229, 11133.443327833576, 7762.146163922077, 7906.756655791505, 8057.597120144086, 8133.511989280662, 8172.918270817454, 18125.36561220351, 18540.629187416445, 18929.621407572045, 18999.525011874815, 19362.70955935634, 19151.329703460313, 19195.32816351421, 19201.32795352156, 19208.13563389679, 19308.517287067934, 11378.54485820558, 11440.485178166837, 11448.0, 11580.884191158317, 11632.53469861196, 13557.796633050315, 13757.724845503235, 13758.518451854137, 13765.655858603615, 13792.586222413343, 11232.213745038096, 11233.0, 11318.60384886525, 11353.54585816558, 11597.130215233723, 11191.16066295015, 11270.380129092979, 11282.266652667551, 11345.0, 11631.47658355359, 9857.852132218166, 10030.398176109165, 10052.296339256181, 10055.69832905014, 10075.19398448137, 7428.814279643052, 7492.662830172546, 7499.700011999459, 7511.098668159856, 7530.774076777706, 7458.589777562286, 7511.51175173612, 7517.736879209201, 7522.435817313614, 7549.849003019799], "value": 6727.61014987684, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [529.3118945371017, 532.398389819504, 536.0779459329952, 547.8274343581769, 549.9175123731442, 6244.438000579947, 6266.433391652087, 6286.591371560845, 6331.77838775643, 6395.8081257562235, 6224.15973843531, 6255.812325630227, 6287.434130928215, 6303.716332765025, 6342.714577843996, 5225.503577160163, 5253.238280449333, 5278.630495865291, 5279.498447647467, 5298.258243845865, 4897.461279259279, 4956.801727930879, 5220.947790522103, 5234.842954711346, 5271.4464981177, 4953.455119936804, 4998.2752500887345, 5003.424606170301, 5012.699238045729, 5030.57240134589, 4958.454569997298, 4981.950180498203, 5011.548960593527, 5033.798648054073, 5056.544910958013, 4521.0, 4531.886702832423, 4550.8862278442975, 4560.384348113029, 4569.177548041352, 6107.511399088063, 6210.596311239755, 6221.626702397871, 6223.346548612421, 6235.688215589203, 5756.510696590794, 5759.868848526072, 5788.623739456922, 5798.652080875161, 5812.8837423251725, 6525.314841941576, 6576.166517609553, 6627.0, 6649.60102393858, 6702.597844129368, 4953.2074868020945, 4957.3307603473795, 4962.255661650745, 4978.551930326288, 4993.90012199754, 6669.0, 6775.220849602359, 6847.589144651387, 6894.310568943066, 7065.57606543609, 4803.423589169322, 5035.77339019745, 5047.848564543069, 5100.668456550312, 5110.616703747234, 5075.289459475676, 5111.131107711698, 5132.204508301144, 5144.9742751286, 5160.664556803871, 5649.548036157097, 5726.169705392695, 5744.827655170351, 5747.9137812932, 5786.0, 6594.142761440982, 6598.43913267368, 6634.203895532471, 6698.22970358415, 6699.162604674416, 1782.3850771483817, 1799.8290162434548, 1802.1439816087516, 1803.9176494103433, 1807.8463330616783, 1197.8143387774908, 1247.25788156047, 1248.843894513186, 1266.3288457117742, 1268.2960956669137, 5618.410066942912, 5629.802956896571, 5669.165417291354, 5768.2501274834, 5775.711214439261, 6633.535652504278, 6711.798646040626, 6731.855584550638, 6750.966245168841, 6765.699662114905, 5717.742701578439, 5730.942690573124, 5731.283589551306, 5741.880333335043, 5746.482816546447, 3482.791032538056, 3485.4423292273113, 3520.559930008749, 3529.3294274087843, 3543.574771027493, 6079.422454866781, 6383.0744542042075, 6401.55189136756, 6460.806175814733, 6514.5114116441455, 6786.0, 6847.904335306327, 6852.725890964405, 6929.099217101744, 6941.201761797458, 6449.871002579922, 6475.417212450902, 6529.118568993125, 6538.738450462023, 6546.18172728409, 6706.429953454011, 6754.324567543206, 6756.594604323756, 6761.121054262914, 6798.592084474947, 6678.0, 6722.764703235363, 6737.27140508095, 6746.763863264762, 6750.561213521106, 6519.641419721961, 6524.151860258136, 6549.934500654933, 6584.670766461753, 6640.734370625121, 4979.626528010342, 5027.145385284583, 5041.571466425395, 5042.495750425075, 5052.974735126448, 6492.35076492347, 6723.562968407038, 6731.293214212437, 6759.932400675931, 6778.220504641832, 6305.337939516285, 6547.541672082908, 6571.010190363278, 6602.801915942529, 6622.701978410887, 6521.0, 6577.6053436793945, 6598.37315455031, 6617.569857959217, 6620.139381880324, 6408.14163892466, 6466.191726034246, 6502.252240992158, 6505.544611877123, 6507.707153178024, 6497.920665387031, 6504.959206526933, 6520.152380190545, 6626.271110177973, 6647.933520664731, 6341.621891790579, 6404.775832845828, 6416.903746443901, 6445.6454894981225, 6480.125183100221], "value": 5101.85725182502, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [889.244142478893, 903.5482258870564, 908.4413085952133, 910.2581396162127, 932.7341707613327, 18681.719774203382, 19139.0, 19190.712139317886, 19196.520086997833, 19284.0, 17999.0, 18107.547311317223, 18113.0, 18131.72802407965, 18158.909205453987, 934.4673536084417, 963.1476143612883, 967.3615413826876, 967.5500892085179, 969.5443141723379, 981.8232718110737, 984.320818635143, 984.9606015759362, 985.1232403161192, 989.7773001074772, 992.8659630949837, 1016.5425558498705, 1020.0462567499375, 1025.789713108811, 1026.2200727447118, 1015.8882522922472, 1017.7659138398172, 1019.4800651667703, 1019.6278358399205, 1021.7752094539189, 749.5577609210592, 750.0998801438296, 750.5009168902673, 751.2112282103824, 751.9924800752032, 18367.17347719356, 19041.14314855835, 19190.13644386006, 19213.615727685366, 19213.807861921483, 15897.0, 16314.428994985119, 16371.754423683655, 16380.344786208656, 16453.0, 17377.0, 17560.121993900382, 17969.281228750835, 18107.366242181593, 18131.4560563182, 3688.6495782900583, 3703.0742314421395, 3716.7026637869176, 3719.4048952167523, 3734.1971476132762, 111159.44420277847, 147252.26373868063, 148203.0, 149188.0, 149701.0, 17332.39336623212, 17592.208350624252, 17666.911665441847, 17820.19809108593, 17828.197731102133, 32048.839755801073, 32444.35111297761, 32532.83733581364, 32641.0, 32668.83665581657, 8727.432716873383, 8981.640734370683, 9045.095490450902, 9056.683016094537, 9084.500352480545, 4146.751194928314, 4201.13876655291, 4204.26425375558, 4207.368894665825, 4235.343521754133, 5201.739913004335, 5244.895102097937, 5265.578753699771, 5318.228856815817, 5353.652012619167, 1086.6088208245026, 1102.4873433853297, 1106.1151079136819, 1114.1449486604874, 1115.11905594581, 627.7583130494793, 645.9483241340681, 655.1973832055694, 666.8866292730247, 669.263809809206, 11629.069674426026, 12389.318587477595, 12707.301098439675, 13415.262160581262, 13429.0599658023, 687.9552829066095, 688.9931100688929, 699.9405050570657, 702.2345643248883, 706.6819931031057, 5829.271341082364, 5958.493528050078, 5987.622846745273, 6018.518518518508, 6038.245219347582, 118680.0, 119575.0, 120459.0, 121899.39050304692, 122664.0, 101884.0, 102363.4881825601, 104376.0, 106334.0, 107456.0, 90271.54864225637, 91390.0, 92136.53931730433, 92163.53918230366, 92844.0, 10877.891221087688, 10966.945165274123, 11045.558177672803, 11049.779004420026, 11419.200655954335, 43899.56100438955, 44278.33582496201, 45304.32043519416, 45384.092318154115, 45746.085078298915, 56356.43643563676, 57420.0, 57843.13235301558, 59067.40932590619, 59278.40721592729, 15619.375224990874, 15633.0, 16125.75811362807, 16126.435574754827, 16201.513954581382, 16828.411005614744, 17116.82883171202, 17130.057846818047, 17319.134043297534, 17323.7401438976, 16287.59281017984, 16449.83550164483, 16611.0, 16714.4149954751, 16852.831471685127, 16741.1629418531, 16743.581410464834, 16805.495835124966, 16928.915355423145, 16970.0, 16268.430604928772, 16532.669346612758, 16609.16954152312, 16662.333506659597, 16812.495625131265, 11173.776524469627, 11455.484503197209, 11556.768864622492, 11675.0, 11769.17615766888, 12924.353782311035, 13015.349232538525, 13037.47850085986, 13039.413226404646, 13064.738705226033], "value": 8951.570910305018, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1406.9, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 106.0, 108.0, 106.0, 107.0, 110.0, 108.0, 111.0, 110.0, 107.0], "value": 108.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 127.0, 121.0, 118.0, 120.0, 119.0, 117.0, 116.0, 144.0, 118.0], "value": 121.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 162.0, 162.0, 162.0, 162.0, 164.0, 161.0, 164.0, 165.0, 164.0], "value": 162.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 73.0, 74.0, 72.0, 74.0, 75.0, 75.0, 74.0, 74.0, 73.0], "value": 73.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 79.0, 78.0, 78.0, 79.0, 81.0, 80.0, 87.0, 77.0, 78.0], "value": 79.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 113.0, 110.0, 114.0, 114.0, 113.0, 114.0, 114.0, 114.0, 114.0], "value": 113.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 148.0, 147.0, 147.0, 150.0, 149.0, 148.0, 149.0, 148.0, 147.0], "value": 148.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 95.0, 94.0, 94.0, 94.0, 94.0, 94.0, 94.0, 94.0], "value": 94.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 53.0, 50.0, 51.0, 52.0, 51.0, 52.0, 55.0, 51.0, 51.0], "value": 52.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [37.0, 36.0, 37.0, 37.0, 37.0, 37.0, 37.0, 38.0, 38.0, 38.0], "value": 37.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 74.0, 72.0, 71.0, 75.0, 74.0, 72.0, 74.0, 73.0, 73.0], "value": 73.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 107.0, 112.0, 112.0, 114.0, 116.0, 117.0, 112.0, 111.0, 116.0], "value": 112.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 164.0, 170.0, 167.0, 169.0, 167.0, 165.0, 161.0, 169.0, 161.0], "value": 166.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 62.0, 63.0, 64.0, 63.0, 63.0, 63.0, 64.0, 65.0, 64.0], "value": 63.7, "unit": "score"}]}]} 04:06:51 INFO - Return code: 0 04:06:51 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:06:51 INFO - # TBPL SUCCESS # 04:06:51 INFO - Running post-action listener: _package_coverage_data 04:06:51 INFO - Running post-action listener: _resource_record_post_action 04:06:51 INFO - [mozharness: 2017-09-28 11:06:51.247787Z] Finished run-tests step (success) 04:06:51 INFO - Running post-run listener: _resource_record_post_run 04:06:51 INFO - Total resource usage - Wall time: 1189s; CPU: 13.0%; Read bytes: 475136; Write bytes: 337870848; Read time: 448; Write time: 476888 04:06:51 INFO - TinderboxPrint: CPU usage
13.1% 04:06:51 INFO - TinderboxPrint: I/O read bytes / time
475,136 / 448 04:06:51 INFO - TinderboxPrint: I/O write bytes / time
337,870,848 / 476,888 04:06:51 INFO - TinderboxPrint: CPU idle
8,231.5 (86.8%) 04:06:51 INFO - TinderboxPrint: CPU user
1,192.7 (12.6%) 04:06:51 INFO - TinderboxPrint: Swap in / out
0 / 0 04:06:51 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 13230080; Read time: 0; Write time: 3788 04:06:51 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 04:06:51 INFO - run-tests - Wall time: 1171s; CPU: 13.0%; Read bytes: 471040; Write bytes: 324640768; Read time: 440; Write time: 473100 04:06:51 INFO - Running post-run listener: _upload_blobber_files 04:06:51 INFO - Blob upload gear active. 04:06:51 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:06:51 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 04:06:51 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'] 04:06:51 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 04:06:52 INFO - (blobuploader) - INFO - Open directory for files ... 04:06:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 04:06:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:06:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:06:53 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 04:06:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:06:53 INFO - (blobuploader) - INFO - Done attempting. 04:06:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 04:06:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:06:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:06:54 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 04:06:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:06:54 INFO - (blobuploader) - INFO - Done attempting. 04:06:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 04:06:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:06:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:06:55 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 04:06:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:06:55 INFO - (blobuploader) - INFO - Done attempting. 04:06:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:06:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:06:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:06:59 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:06:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:06:59 INFO - (blobuploader) - INFO - Done attempting. 04:06:59 INFO - (blobuploader) - INFO - Iteration through files over. 04:06:59 INFO - Return code: 0 04:06:59 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:06:59 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:06:59 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/25b23a749f2cfab8b02c2a8c4a6e8a68960630aa3a0df72cdebd7d107c23a8f4a14da441ac3ef453e7640e9f38758607267b41f50414095d07900fa7b2d56df0", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b757f2f36dcc0920854e082a919f3d53d56b204dba61cef9c1e2843b442f301f4d4ecfbafdc0d1003695774cb192eb01eed9b901fef580c40ea549c36080b4bb", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/56c0b8351d07935472adc9a3aaae429b187730b5c6cded7e5697390656f70c10255901d0a7615d5fcb9bb6d652641d0fe46ad1ca9fcb5255ae70159c8f3c1d64", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/1f494f80021f7b612d14b8325f0f994e7e6bf88ab00adcf5e1203b01bf396cdc4de9b541e0ba83d87da975e238c88207b16ff90fe152121b8d1d1f2c2bef4d1c"} 04:06:59 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:06:59 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:06:59 INFO - Contents: 04:06:59 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/25b23a749f2cfab8b02c2a8c4a6e8a68960630aa3a0df72cdebd7d107c23a8f4a14da441ac3ef453e7640e9f38758607267b41f50414095d07900fa7b2d56df0", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b757f2f36dcc0920854e082a919f3d53d56b204dba61cef9c1e2843b442f301f4d4ecfbafdc0d1003695774cb192eb01eed9b901fef580c40ea549c36080b4bb", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/56c0b8351d07935472adc9a3aaae429b187730b5c6cded7e5697390656f70c10255901d0a7615d5fcb9bb6d652641d0fe46ad1ca9fcb5255ae70159c8f3c1d64", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/1f494f80021f7b612d14b8325f0f994e7e6bf88ab00adcf5e1203b01bf396cdc4de9b541e0ba83d87da975e238c88207b16ff90fe152121b8d1d1f2c2bef4d1c"} 04:06:59 INFO - Running post-run listener: copy_logs_to_upload_dir 04:06:59 INFO - Copying logs to upload dir... 04:06:59 INFO - mkdir: /builds/slave/test/build/upload/logs 04:06:59 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1242.456253 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 42 secs) (at 2017-09-28 04:06:59.336995) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-28 04:06:59.341546) ========= 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=ba164b0a7b0ec7655860e956000001c1-1506595574.805415-722265750 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/25b23a749f2cfab8b02c2a8c4a6e8a68960630aa3a0df72cdebd7d107c23a8f4a14da441ac3ef453e7640e9f38758607267b41f50414095d07900fa7b2d56df0", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b757f2f36dcc0920854e082a919f3d53d56b204dba61cef9c1e2843b442f301f4d4ecfbafdc0d1003695774cb192eb01eed9b901fef580c40ea549c36080b4bb", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/56c0b8351d07935472adc9a3aaae429b187730b5c6cded7e5697390656f70c10255901d0a7615d5fcb9bb6d652641d0fe46ad1ca9fcb5255ae70159c8f3c1d64", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/1f494f80021f7b612d14b8325f0f994e7e6bf88ab00adcf5e1203b01bf396cdc4de9b541e0ba83d87da975e238c88207b16ff90fe152121b8d1d1f2c2bef4d1c"} build_url:https://queue.taskcluster.net/v1/task/RdxJmsEUSFuRZWELzxBWSw/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011338 build_url: 'https://queue.taskcluster.net/v1/task/RdxJmsEUSFuRZWELzxBWSw/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/25b23a749f2cfab8b02c2a8c4a6e8a68960630aa3a0df72cdebd7d107c23a8f4a14da441ac3ef453e7640e9f38758607267b41f50414095d07900fa7b2d56df0", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b757f2f36dcc0920854e082a919f3d53d56b204dba61cef9c1e2843b442f301f4d4ecfbafdc0d1003695774cb192eb01eed9b901fef580c40ea549c36080b4bb", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/56c0b8351d07935472adc9a3aaae429b187730b5c6cded7e5697390656f70c10255901d0a7615d5fcb9bb6d652641d0fe46ad1ca9fcb5255ae70159c8f3c1d64", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/1f494f80021f7b612d14b8325f0f994e7e6bf88ab00adcf5e1203b01bf396cdc4de9b541e0ba83d87da975e238c88207b16ff90fe152121b8d1d1f2c2bef4d1c"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-28 04:06:59.377223) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-28 04:06:59.377561) ========= 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=ba164b0a7b0ec7655860e956000001c1-1506595574.805415-722265750 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004554 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-28 04:06:59.459961) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-28 04:06:59.460274) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-28 04:06:59.460586) ========= ========= Total master_lag: 0.23 =========