builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-054 starttime: 1507067718.48 results: success (0) revision: 65a5054a1f922b83929c80658062f441ca3da6a0 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-10-03 14:55:18.484400) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-10-03 14:55:18.484779) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-10-03 14:55:18.485054) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-10-03 14:55:18.542506) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-10-03 14:55:18.542784) ========= 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=cbe409c6de289db6ac707de3000001d4-1507066478.500385-18170245 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-10-03 14:55:18-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 31.7M=0s 2017-10-03 14:55:18 (31.7 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.165267 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-10-03 14:55:18.737688) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-10-03 14:55:18.737999) ========= 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=cbe409c6de289db6ac707de3000001d4-1507066478.500385-18170245 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.021566 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-10-03 14:55:18.787456) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-10-03 14:55:18.787759) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 65a5054a1f922b83929c80658062f441ca3da6a0 --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 65a5054a1f922b83929c80658062f441ca3da6a0 --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=cbe409c6de289db6ac707de3000001d4-1507066478.500385-18170245 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-10-03 14:55:18,891 truncating revision to first 12 chars 2017-10-03 14:55:18,891 Setting DEBUG logging. 2017-10-03 14:55:18,891 attempt 1/10 2017-10-03 14:55:18,891 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/65a5054a1f92?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-10-03 14:55:19,614 unpacking tar archive at: mozilla-central-65a5054a1f92/testing/mozharness/ program finished with exit code 0 elapsedTime=1.003798 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-10-03 14:55:19.806428) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-10-03 14:55:19.806753) ========= script_repo_revision: 65a5054a1f922b83929c80658062f441ca3da6a0 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-10-03 14:55:19.807140) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-10-03 14:55:19.807398) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-10-03 14:55:19.820561) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 54 secs) (at 2017-10-03 14:55:19.820875) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=cbe409c6de289db6ac707de3000001d4-1507066478.500385-18170245 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 14:55:20 INFO - MultiFileLogger online at 20171003 14:55:20 in /builds/slave/test 14:55:20 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 14:55:20 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 14:55:20 INFO - {'append_to_log': False, 14:55:20 INFO - 'base_work_dir': '/builds/slave/test', 14:55:20 INFO - 'blob_upload_branch': 'Firefox', 14:55:20 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 14:55:20 INFO - 'branch': 'Firefox', 14:55:20 INFO - 'buildbot_json_path': 'buildprops.json', 14:55:20 INFO - 'code_coverage': False, 14:55:20 INFO - 'config_files': ('talos/linux_config.py',), 14:55:20 INFO - 'default_actions': ('clobber', 14:55:20 INFO - 'read-buildbot-config', 14:55:20 INFO - 'download-and-extract', 14:55:20 INFO - 'populate-webroot', 14:55:20 INFO - 'create-virtualenv', 14:55:20 INFO - 'install', 14:55:20 INFO - 'setup-mitmproxy', 14:55:20 INFO - 'run-tests'), 14:55:20 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:55:20 INFO - 'disable_ccov_upload': False, 14:55:20 INFO - 'disable_stylo': False, 14:55:20 INFO - 'download_minidump_stackwalk': True, 14:55:20 INFO - 'download_symbols': 'ondemand', 14:55:20 INFO - 'enable_stylo': False, 14:55:20 INFO - 'enable_webrender': False, 14:55:20 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 14:55:20 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:55:20 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:55:20 INFO - 'gecko_profile': False, 14:55:20 INFO - 'gecko_profile_interval': 0, 14:55:20 INFO - 'installer_path': 'installer.exe', 14:55:20 INFO - 'log_level': 'info', 14:55:20 INFO - 'log_name': 'talos', 14:55:20 INFO - 'log_to_console': True, 14:55:20 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 14:55:20 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 14:55:20 INFO - 'opt_config_files': (), 14:55:20 INFO - 'pip_index': False, 14:55:20 INFO - 'suite': 'dromaeojs-e10s', 14:55:20 INFO - 'system_bits': '32', 14:55:20 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:55:20 INFO - 'title': 'talos-linux64-ix-054', 14:55:20 INFO - 'tooltool_cache': '/builds/tooltool_cache', 14:55:20 INFO - 'use_talos_json': True, 14:55:20 INFO - 'verify': 'False', 14:55:20 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 14:55:20 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:55:20 INFO - 'work_dir': 'build'} 14:55:20 INFO - [mozharness: 2017-10-03 21:55:20.195667Z] Running clobber step. 14:55:20 INFO - Running pre-action listener: _resource_record_pre_action 14:55:20 INFO - Running main action method: clobber 14:55:20 INFO - rmtree: /builds/slave/test/build 14:55:20 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 14:55:21 INFO - Running post-action listener: _resource_record_post_action 14:55:21 INFO - [mozharness: 2017-10-03 21:55:21.145335Z] Finished clobber step (success) 14:55:21 INFO - [mozharness: 2017-10-03 21:55:21.145435Z] Running read-buildbot-config step. 14:55:21 INFO - Running pre-action listener: _resource_record_pre_action 14:55:21 INFO - Running main action method: read_buildbot_config 14:55:21 INFO - Using buildbot properties: 14:55:21 INFO - { 14:55:21 INFO - "project": "", 14:55:21 INFO - "product": "firefox", 14:55:21 INFO - "who": "kwierso@gmail.com", 14:55:21 INFO - "installer_path": "public/build/target.tar.bz2", 14:55:21 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 14:55:21 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 14:55:21 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs-e10s", 14:55:21 INFO - "stage_platform": "linux64-pgo", 14:55:21 INFO - "basedir": "/builds/slave/test", 14:55:21 INFO - "buildnumber": 12, 14:55:21 INFO - "platform": "ubuntu64_hw", 14:55:21 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 14:55:21 INFO - "slavebuilddir": "test", 14:55:21 INFO - "taskId": "Vry--pSYSn2ahmQKqaMOlg", 14:55:21 INFO - "branch": "mozilla-central", 14:55:21 INFO - "script_repo_revision": "production", 14:55:21 INFO - "revision": "65a5054a1f922b83929c80658062f441ca3da6a0", 14:55:21 INFO - "slavename": "talos-linux64-ix-054", 14:55:21 INFO - "repo_path": "mozilla-central" 14:55:21 INFO - } 14:55:21 INFO - Finding installer, test and symbols from parent task. 14:55:21 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Vry--pSYSn2ahmQKqaMOlg'}, attempt #1 14:55:21 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Vry--pSYSn2ahmQKqaMOlg'}, attempt #1 14:55:22 INFO - Task dependencies: CDkVOCj0R22Mi5t4EKDL4Q 14:55:22 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CDkVOCj0R22Mi5t4EKDL4Q'}, attempt #1 14:55:22 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/CDkVOCj0R22Mi5t4EKDL4Q/artifacts/public/build/target.tar.bz2 14:55:22 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/CDkVOCj0R22Mi5t4EKDL4Q/artifacts/public/build/target.test_packages.json 14:55:22 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/CDkVOCj0R22Mi5t4EKDL4Q/artifacts/public/build/target.crashreporter-symbols.zip 14:55:22 INFO - Running post-action listener: _resource_record_post_action 14:55:22 INFO - [mozharness: 2017-10-03 21:55:22.454078Z] Finished read-buildbot-config step (success) 14:55:22 INFO - [mozharness: 2017-10-03 21:55:22.454298Z] Running download-and-extract step. 14:55:22 INFO - Running pre-action listener: _resource_record_pre_action 14:55:22 INFO - Running main action method: download_and_extract 14:55:22 INFO - mkdir: /builds/slave/test/build/tests 14:55:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:55:22 INFO - trying https://queue.taskcluster.net/v1/task/CDkVOCj0R22Mi5t4EKDL4Q/artifacts/public/build/target.test_packages.json 14:55:22 INFO - Downloading https://queue.taskcluster.net/v1/task/CDkVOCj0R22Mi5t4EKDL4Q/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 14:55:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CDkVOCj0R22Mi5t4EKDL4Q/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 14:55:23 INFO - Downloaded 989 bytes. 14:55:23 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 14:55:23 INFO - Using the following test package requirements: 14:55:23 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 14:55:23 INFO - u'common': [u'target.common.tests.zip'], 14:55:23 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 14:55:23 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 14:55:23 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 14:55:23 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 14:55:23 INFO - u'mozbase': [u'target.common.tests.zip'], 14:55:23 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 14:55:23 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 14:55:23 INFO - u'web-platform': [u'target.common.tests.zip', 14:55:23 INFO - u'target.web-platform.tests.tar.gz'], 14:55:23 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 14:55:23 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 14:55:23 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CDkVOCj0R22Mi5t4EKDL4Q/artifacts/public/build/target.common.tests.zip 14:55:23 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CDkVOCj0R22Mi5t4EKDL4Q/artifacts/public/build/target.common.tests.zip'}, attempt #1 14:55:23 INFO - Fetch https://queue.taskcluster.net/v1/task/CDkVOCj0R22Mi5t4EKDL4Q/artifacts/public/build/target.common.tests.zip into memory 14:55:24 INFO - Content-Length response header: 55864691 14:55:24 INFO - Bytes received: 55864691 14:55:35 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CDkVOCj0R22Mi5t4EKDL4Q/artifacts/public/build/target.talos.tests.zip 14:55:35 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CDkVOCj0R22Mi5t4EKDL4Q/artifacts/public/build/target.talos.tests.zip'}, attempt #1 14:55:35 INFO - Fetch https://queue.taskcluster.net/v1/task/CDkVOCj0R22Mi5t4EKDL4Q/artifacts/public/build/target.talos.tests.zip into memory 14:55:36 INFO - Content-Length response header: 14533844 14:55:36 INFO - Bytes received: 14533844 14:55:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:55:36 INFO - trying https://queue.taskcluster.net/v1/task/CDkVOCj0R22Mi5t4EKDL4Q/artifacts/public/build/target.tar.bz2 14:55:36 INFO - Downloading https://queue.taskcluster.net/v1/task/CDkVOCj0R22Mi5t4EKDL4Q/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 14:55:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CDkVOCj0R22Mi5t4EKDL4Q/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 14:55:37 INFO - Downloaded 62288580 bytes. 14:55:37 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/CDkVOCj0R22Mi5t4EKDL4Q/artifacts/public/build/target.tar.bz2 14:55:37 INFO - mkdir: /builds/slave/test/properties 14:55:37 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 14:55:37 INFO - Writing to file /builds/slave/test/properties/build_url 14:55:37 INFO - Contents: 14:55:37 INFO - build_url:https://queue.taskcluster.net/v1/task/CDkVOCj0R22Mi5t4EKDL4Q/artifacts/public/build/target.tar.bz2 14:55:37 INFO - Running post-action listener: _resource_record_post_action 14:55:37 INFO - Running post-action listener: find_tests_for_verification 14:55:37 INFO - Running post-action listener: set_extra_try_arguments 14:55:37 INFO - [mozharness: 2017-10-03 21:55:37.769437Z] Finished download-and-extract step (success) 14:55:37 INFO - [mozharness: 2017-10-03 21:55:37.769542Z] Running populate-webroot step. 14:55:37 INFO - Running pre-action listener: _resource_record_pre_action 14:55:37 INFO - Running main action method: populate_webroot 14:55:37 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 14:55:37 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 14:55:37 INFO - 'tests': ['tresize', 14:55:37 INFO - 'tcanvasmark']}, 14:55:37 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 14:55:37 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 14:55:37 INFO - 'tests': ['dromaeo_css', 14:55:37 INFO - 'kraken']}, 14:55:37 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 14:55:37 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:55:37 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 14:55:37 INFO - 'talos_options': ['--disable-stylo'], 14:55:37 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:55:37 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 14:55:37 INFO - 'tests': ['damp', 'tps']}, 14:55:37 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 14:55:37 INFO - 'talos_options': ['--disable-stylo'], 14:55:37 INFO - 'tests': ['damp', 'tps']}, 14:55:37 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 14:55:37 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 14:55:37 INFO - 'tests': ['dromaeo_dom']}, 14:55:37 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 14:55:37 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 14:55:37 INFO - 'tests': ['basic_compositor_video', 14:55:37 INFO - 'glvideo']}, 14:55:37 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 14:55:37 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 14:55:37 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 14:55:37 INFO - 'talos_options': ['--disable-stylo'], 14:55:37 INFO - 'tests': ['ts_paint_webext', 14:55:37 INFO - 'tp5o_webext']}, 14:55:37 INFO - 'other-e10s': {'tests': ['a11yr', 14:55:37 INFO - 'ts_paint', 14:55:37 INFO - 'tpaint', 14:55:37 INFO - 'sessionrestore', 14:55:37 INFO - 'sessionrestore_many_windows', 14:55:37 INFO - 'sessionrestore_no_auto_restore', 14:55:37 INFO - 'tabpaint', 14:55:37 INFO - 'cpstartup']}, 14:55:37 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 14:55:37 INFO - 'tests': ['a11yr', 14:55:37 INFO - 'ts_paint', 14:55:37 INFO - 'tpaint', 14:55:37 INFO - 'sessionrestore', 14:55:37 INFO - 'sessionrestore_many_windows', 14:55:37 INFO - 'sessionrestore_no_auto_restore', 14:55:37 INFO - 'tabpaint', 14:55:37 INFO - 'cpstartup']}, 14:55:37 INFO - 'perf-reftest-e10s': {'tests': ['perf_reftest']}, 14:55:37 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']}, 14:55:37 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 14:55:37 INFO - 'tests': ['perf_reftest_singletons']}, 14:55:37 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 14:55:37 INFO - 'tests': ['perf_reftest']}, 14:55:37 INFO - 'svgr-e10s': {'tests': ['tsvgx', 14:55:37 INFO - 'tsvgr_opacity', 14:55:37 INFO - 'tart', 14:55:37 INFO - 'tscrollx', 14:55:37 INFO - 'tsvg_static']}, 14:55:37 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 14:55:37 INFO - 'tests': ['tsvgx', 14:55:37 INFO - 'tsvgr_opacity', 14:55:37 INFO - 'tart', 14:55:37 INFO - 'tscrollx', 14:55:37 INFO - 'tsvg_static']}, 14:55:37 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 14:55:37 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 14:55:37 INFO - 'talos_options': ['--disable-stylo'], 14:55:37 INFO - 'tests': ['tp5o']}, 14:55:37 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 14:55:37 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 14:55:37 INFO - 'talos_options': ['--mitmproxy', 14:55:37 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 14:55:37 INFO - '--firstNonBlankPaint'], 14:55:37 INFO - 'tests': ['tp6_google', 14:55:37 INFO - 'tp6_youtube', 14:55:37 INFO - 'tp6_amazon', 14:55:37 INFO - 'tp6_facebook']}, 14:55:37 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 14:55:37 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 14:55:37 INFO - 'talos_options': ['--disable-stylo', 14:55:37 INFO - '--mitmproxy', 14:55:37 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 14:55:37 INFO - '--firstNonBlankPaint'], 14:55:37 INFO - 'tests': ['tp6_google', 14:55:37 INFO - 'tp6_youtube', 14:55:37 INFO - 'tp6_amazon', 14:55:37 INFO - 'tp6_facebook']}, 14:55:37 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 14:55:37 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 14:55:37 INFO - 'talos_options': ['--stylo-threads=1', 14:55:37 INFO - '--mitmproxy', 14:55:37 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 14:55:37 INFO - '--firstNonBlankPaint'], 14:55:37 INFO - 'tests': ['tp6_google', 14:55:37 INFO - 'tp6_youtube', 14:55:37 INFO - 'tp6_amazon', 14:55:37 INFO - 'tp6_facebook']}, 14:55:37 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 14:55:37 INFO - 'talos_options': ['--xperf_path', 14:55:37 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:55:37 INFO - 'tests': ['tp5n']}, 14:55:37 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 14:55:37 INFO - 'talos_options': ['--disable-stylo', 14:55:37 INFO - '--xperf_path', 14:55:37 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:55:37 INFO - 'tests': ['tp5n']}}} 14:55:37 INFO - Running post-action listener: _resource_record_post_action 14:55:37 INFO - [mozharness: 2017-10-03 21:55:37.785098Z] Finished populate-webroot step (success) 14:55:37 INFO - [mozharness: 2017-10-03 21:55:37.785187Z] Running create-virtualenv step. 14:55:37 INFO - Running pre-action listener: _resource_record_pre_action 14:55:37 INFO - Running main action method: create_virtualenv 14:55:37 INFO - Creating virtualenv /builds/slave/test/build/venv 14:55:37 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 14:55:37 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 14:55:37 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 14:55:37 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 14:55:37 INFO - New python executable in /builds/slave/test/build/venv/bin/python 14:55:38 INFO - Installing distribute.............................................................................................................................................................................................done. 14:55:38 INFO - Installing pip...............done. 14:55:38 INFO - Return code: 0 14:55:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 14:55:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 14:55:39 INFO - Reading from file tmpfile_stdout 14:55:39 INFO - Output received: 14:55:39 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 14:55:39 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 14:55:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:55:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:55:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:55:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:55:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bda1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1da2b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f45df0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f49440>, '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 0x1f4a130>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f44320>, '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': 'cbe409c6de289db6ac707de3000001d4-1507066478.500385-18170245', '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 14:55:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 14:55:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 14:55:39 INFO - Using env: {'DISPLAY': ':0', 14:55:39 INFO - 'HOME': '/home/cltbld', 14:55:39 INFO - 'LANG': 'en_US.UTF-8', 14:55:39 INFO - 'LANGUAGE': 'en_US:en', 14:55:39 INFO - 'LOGNAME': 'cltbld', 14:55:39 INFO - 'MAIL': '/var/mail/cltbld', 14:55:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:55:39 INFO - 'MOZ_NO_REMOTE': '1', 14:55:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:55:39 INFO - 'NO_EM_RESTART': '1', 14:55:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:55:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:55:39 INFO - 'PWD': '/builds/slave/test', 14:55:39 INFO - 'SHELL': '/bin/bash', 14:55:39 INFO - 'SHLVL': '1', 14:55:39 INFO - 'TERM': 'linux', 14:55:39 INFO - 'TMOUT': '86400', 14:55:39 INFO - 'USER': 'cltbld', 14:55:39 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1507066478.500385-18170245', 14:55:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:55:39 INFO - '_': '/tools/buildbot/bin/python'} 14:55:41 INFO - Ignoring indexes: http://pypi.python.org/simple/ 14:55:41 INFO - Downloading/unpacking pip>=1.5 14:55:41 INFO - Running setup.py egg_info for package pip 14:55:41 INFO - warning: no files found matching 'pip/cacert.pem' 14:55:41 INFO - warning: no files found matching '*.html' under directory 'docs' 14:55:41 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 14:55:41 INFO - no previously-included directories found matching 'docs/_build/_sources' 14:55:41 INFO - Installing collected packages: pip 14:55:41 INFO - Found existing installation: pip 1.1 14:55:41 INFO - Uninstalling pip: 14:55:41 INFO - Successfully uninstalled pip 14:55:41 INFO - Running setup.py install for pip 14:55:41 INFO - warning: no files found matching 'pip/cacert.pem' 14:55:41 INFO - warning: no files found matching '*.html' under directory 'docs' 14:55:41 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 14:55:41 INFO - no previously-included directories found matching 'docs/_build/_sources' 14:55:41 INFO - Installing pip script to /builds/slave/test/build/venv/bin 14:55:41 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 14:55:41 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 14:55:41 INFO - Successfully installed pip 14:55:41 INFO - Cleaning up... 14:55:41 INFO - Return code: 0 14:55:41 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 14:55:41 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')]} 14:55:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:55:41 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')]} 14:55:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:55:41 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 0x1bda1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1da2b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f45df0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f49440>, '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 0x1f4a130>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f44320>, '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': 'cbe409c6de289db6ac707de3000001d4-1507066478.500385-18170245', '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 14:55:41 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 14:55:41 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 14:55:41 INFO - Using env: {'DISPLAY': ':0', 14:55:41 INFO - 'HOME': '/home/cltbld', 14:55:41 INFO - 'LANG': 'en_US.UTF-8', 14:55:41 INFO - 'LANGUAGE': 'en_US:en', 14:55:41 INFO - 'LOGNAME': 'cltbld', 14:55:41 INFO - 'MAIL': '/var/mail/cltbld', 14:55:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:55:41 INFO - 'MOZ_NO_REMOTE': '1', 14:55:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:55:41 INFO - 'NO_EM_RESTART': '1', 14:55:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:55:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:55:41 INFO - 'PWD': '/builds/slave/test', 14:55:41 INFO - 'SHELL': '/bin/bash', 14:55:41 INFO - 'SHLVL': '1', 14:55:41 INFO - 'TERM': 'linux', 14:55:41 INFO - 'TMOUT': '86400', 14:55:41 INFO - 'USER': 'cltbld', 14:55:41 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1507066478.500385-18170245', 14:55:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:55:41 INFO - '_': '/tools/buildbot/bin/python'} 14:55:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:55:42 INFO - Downloading/unpacking psutil>=3.1.1 14:55:42 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 14:55:42 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 14:55:42 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 14:55:42 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 14:55:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 14:55:43 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:55:43 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:55:43 INFO - Installing collected packages: psutil 14:55:43 INFO - Running setup.py install for psutil 14:55:43 INFO - building 'psutil._psutil_linux' extension 14:55:43 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 14:55:44 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 14:55:44 INFO - building 'psutil._psutil_posix' extension 14:55:44 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 14:55:45 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 14:55:45 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:55:45 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:55:45 INFO - Successfully installed psutil 14:55:45 INFO - Cleaning up... 14:55:45 INFO - Return code: 0 14:55:45 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 14:55:45 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')]} 14:55:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:55:45 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')]} 14:55:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:55:45 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 0x1bda1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1da2b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f45df0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f49440>, '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 0x1f4a130>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f44320>, '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': 'cbe409c6de289db6ac707de3000001d4-1507066478.500385-18170245', '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 14:55:45 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 14:55:45 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 14:55:45 INFO - Using env: {'DISPLAY': ':0', 14:55:45 INFO - 'HOME': '/home/cltbld', 14:55:45 INFO - 'LANG': 'en_US.UTF-8', 14:55:45 INFO - 'LANGUAGE': 'en_US:en', 14:55:45 INFO - 'LOGNAME': 'cltbld', 14:55:45 INFO - 'MAIL': '/var/mail/cltbld', 14:55:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:55:45 INFO - 'MOZ_NO_REMOTE': '1', 14:55:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:55:45 INFO - 'NO_EM_RESTART': '1', 14:55:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:55:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:55:45 INFO - 'PWD': '/builds/slave/test', 14:55:45 INFO - 'SHELL': '/bin/bash', 14:55:45 INFO - 'SHLVL': '1', 14:55:45 INFO - 'TERM': 'linux', 14:55:45 INFO - 'TMOUT': '86400', 14:55:45 INFO - 'USER': 'cltbld', 14:55:45 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1507066478.500385-18170245', 14:55:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:55:45 INFO - '_': '/tools/buildbot/bin/python'} 14:55:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:55:45 INFO - Downloading/unpacking mozsystemmonitor==0.3 14:55:45 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 14:55:45 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 14:55:45 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 14:55:45 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 14:55:46 INFO - Downloading mozsystemmonitor-0.3.tar.gz 14:55:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:55:46 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 14:55:46 INFO - Installing collected packages: mozsystemmonitor 14:55:46 INFO - Running setup.py install for mozsystemmonitor 14:55:46 INFO - Successfully installed mozsystemmonitor 14:55:46 INFO - Cleaning up... 14:55:46 INFO - Return code: 0 14:55:46 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 14:55:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:55:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:55:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:55:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:55:46 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 0x1bda1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1da2b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f45df0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f49440>, '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 0x1f4a130>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f44320>, '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': 'cbe409c6de289db6ac707de3000001d4-1507066478.500385-18170245', '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 14:55:46 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 14:55:46 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 14:55:46 INFO - Using env: {'DISPLAY': ':0', 14:55:46 INFO - 'HOME': '/home/cltbld', 14:55:46 INFO - 'LANG': 'en_US.UTF-8', 14:55:46 INFO - 'LANGUAGE': 'en_US:en', 14:55:46 INFO - 'LOGNAME': 'cltbld', 14:55:46 INFO - 'MAIL': '/var/mail/cltbld', 14:55:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:55:46 INFO - 'MOZ_NO_REMOTE': '1', 14:55:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:55:46 INFO - 'NO_EM_RESTART': '1', 14:55:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:55:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:55:46 INFO - 'PWD': '/builds/slave/test', 14:55:46 INFO - 'SHELL': '/bin/bash', 14:55:46 INFO - 'SHLVL': '1', 14:55:46 INFO - 'TERM': 'linux', 14:55:46 INFO - 'TMOUT': '86400', 14:55:46 INFO - 'USER': 'cltbld', 14:55:46 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1507066478.500385-18170245', 14:55:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:55:46 INFO - '_': '/tools/buildbot/bin/python'} 14:55:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:55:46 INFO - Downloading/unpacking jsonschema==2.5.1 14:55:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:55:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:55:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:55:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:55:47 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:55:48 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 14:55: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 14:55: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 14:55: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 14:55: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 14:55:48 INFO - Downloading functools32-3.2.3-2.tar.gz 14:55:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 14:55:48 INFO - warning: no files found matching '*.txt' 14:55:48 INFO - no previously-included directories found matching 'build' 14:55:48 INFO - no previously-included directories found matching 'dist' 14:55:48 INFO - no previously-included directories found matching '.git*' 14:55:48 INFO - Installing collected packages: jsonschema, functools32 14:55:48 INFO - Running setup.py install for functools32 14:55:48 INFO - warning: no files found matching '*.txt' 14:55:48 INFO - no previously-included directories found matching 'build' 14:55:48 INFO - no previously-included directories found matching 'dist' 14:55:48 INFO - no previously-included directories found matching '.git*' 14:55:48 INFO - Successfully installed jsonschema functools32 14:55:48 INFO - Cleaning up... 14:55:48 INFO - Return code: 0 14:55:48 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 14:55:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:55:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:55:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:55:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:55:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1bda1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1da2b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f45df0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f49440>, '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 0x1f4a130>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f44320>, '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': 'cbe409c6de289db6ac707de3000001d4-1507066478.500385-18170245', '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 14:55:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 14:55:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 14:55:48 INFO - Using env: {'DISPLAY': ':0', 14:55:48 INFO - 'HOME': '/home/cltbld', 14:55:48 INFO - 'LANG': 'en_US.UTF-8', 14:55:48 INFO - 'LANGUAGE': 'en_US:en', 14:55:48 INFO - 'LOGNAME': 'cltbld', 14:55:48 INFO - 'MAIL': '/var/mail/cltbld', 14:55:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:55:48 INFO - 'MOZ_NO_REMOTE': '1', 14:55:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:55:48 INFO - 'NO_EM_RESTART': '1', 14:55:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:55:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:55:48 INFO - 'PWD': '/builds/slave/test', 14:55:48 INFO - 'SHELL': '/bin/bash', 14:55:48 INFO - 'SHLVL': '1', 14:55:48 INFO - 'TERM': 'linux', 14:55:48 INFO - 'TMOUT': '86400', 14:55:48 INFO - 'USER': 'cltbld', 14:55:48 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1507066478.500385-18170245', 14:55:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:55:48 INFO - '_': '/tools/buildbot/bin/python'} 14:55:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:55:48 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 14:55:48 INFO - Cleaning up... 14:55:48 INFO - Return code: 0 14:55:48 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 14:55:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:55:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:55:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:55:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:55:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1bda1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1da2b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f45df0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f49440>, '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 0x1f4a130>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f44320>, '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': 'cbe409c6de289db6ac707de3000001d4-1507066478.500385-18170245', '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 14:55:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 14:55:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 14:55:48 INFO - Using env: {'DISPLAY': ':0', 14:55:48 INFO - 'HOME': '/home/cltbld', 14:55:48 INFO - 'LANG': 'en_US.UTF-8', 14:55:48 INFO - 'LANGUAGE': 'en_US:en', 14:55:48 INFO - 'LOGNAME': 'cltbld', 14:55:48 INFO - 'MAIL': '/var/mail/cltbld', 14:55:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:55:48 INFO - 'MOZ_NO_REMOTE': '1', 14:55:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:55:48 INFO - 'NO_EM_RESTART': '1', 14:55:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:55:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:55:48 INFO - 'PWD': '/builds/slave/test', 14:55:48 INFO - 'SHELL': '/bin/bash', 14:55:48 INFO - 'SHLVL': '1', 14:55:48 INFO - 'TERM': 'linux', 14:55:48 INFO - 'TMOUT': '86400', 14:55:48 INFO - 'USER': 'cltbld', 14:55:48 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1507066478.500385-18170245', 14:55:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:55:48 INFO - '_': '/tools/buildbot/bin/python'} 14:55:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:55:48 INFO - Downloading/unpacking blobuploader==1.2.4 14:55: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 14:55: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 14:55: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 14:55: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 14:55:49 INFO - Downloading blobuploader-1.2.4.tar.gz 14:55:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 14:55:50 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:55: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 14:55: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 14:55: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 14:55: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 14:55:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 14:55:50 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:55: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 14:55: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 14:55: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 14:55: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 14:55:50 INFO - Downloading docopt-0.6.1.tar.gz 14:55:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 14:55:50 INFO - Installing collected packages: blobuploader, requests, docopt 14:55:50 INFO - Running setup.py install for blobuploader 14:55:50 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 14:55:50 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 14:55:50 INFO - Running setup.py install for requests 14:55:51 INFO - Running setup.py install for docopt 14:55:51 INFO - Successfully installed blobuploader requests docopt 14:55:51 INFO - Cleaning up... 14:55:51 INFO - Return code: 0 14:55:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:55:51 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')]} 14:55:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:55:51 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')]} 14:55:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:55:51 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 0x1bda1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1da2b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f45df0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f49440>, '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 0x1f4a130>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f44320>, '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': 'cbe409c6de289db6ac707de3000001d4-1507066478.500385-18170245', '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 14:55:51 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 14:55:51 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 14:55:51 INFO - Using env: {'DISPLAY': ':0', 14:55:51 INFO - 'HOME': '/home/cltbld', 14:55:51 INFO - 'LANG': 'en_US.UTF-8', 14:55:51 INFO - 'LANGUAGE': 'en_US:en', 14:55:51 INFO - 'LOGNAME': 'cltbld', 14:55:51 INFO - 'MAIL': '/var/mail/cltbld', 14:55:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:55:51 INFO - 'MOZ_NO_REMOTE': '1', 14:55:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:55:51 INFO - 'NO_EM_RESTART': '1', 14:55:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:55:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:55:51 INFO - 'PWD': '/builds/slave/test', 14:55:51 INFO - 'SHELL': '/bin/bash', 14:55:51 INFO - 'SHLVL': '1', 14:55:51 INFO - 'TERM': 'linux', 14:55:51 INFO - 'TMOUT': '86400', 14:55:51 INFO - 'USER': 'cltbld', 14:55:51 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1507066478.500385-18170245', 14:55:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:55:51 INFO - '_': '/tools/buildbot/bin/python'} 14:55:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:55:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:55:51 INFO - Running setup.py (path:/tmp/pip-UjxbqK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:55:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:55:51 INFO - Running setup.py (path:/tmp/pip-GPuGuv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:55:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:55:51 INFO - Running setup.py (path:/tmp/pip-fF1Zwa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:55:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:55:51 INFO - Running setup.py (path:/tmp/pip-iUlohF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:55:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:55:51 INFO - Running setup.py (path:/tmp/pip-mt6ilq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:55:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:55:51 INFO - Running setup.py (path:/tmp/pip-qwxBGd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:55:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:55:51 INFO - Running setup.py (path:/tmp/pip-FXE7s0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:55:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:55:51 INFO - Running setup.py (path:/tmp/pip-4up8Mx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:55:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:55:51 INFO - Running setup.py (path:/tmp/pip-e9LIOq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:55:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:55:51 INFO - Running setup.py (path:/tmp/pip-ev7f3o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:55:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:55:52 INFO - Running setup.py (path:/tmp/pip-XRvRYT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:55:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:55:52 INFO - Running setup.py (path:/tmp/pip-R2Fgpb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:55:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:55:52 INFO - Running setup.py (path:/tmp/pip-tot8Fn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:55:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:55:52 INFO - Running setup.py (path:/tmp/pip-I_TlKv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:55:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:55:52 INFO - Running setup.py (path:/tmp/pip-kvCEVm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:55:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:55:52 INFO - Running setup.py (path:/tmp/pip-tzXh3j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:55:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:55:52 INFO - Running setup.py (path:/tmp/pip-B9DtzV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:55:52 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:55:52 INFO - Running setup.py install for manifestparser 14:55:52 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 14:55:52 INFO - Running setup.py install for mozcrash 14:55:52 INFO - Running setup.py install for mozdebug 14:55:52 INFO - Running setup.py install for mozdevice 14:55:52 INFO - Installing dm script to /builds/slave/test/build/venv/bin 14:55:52 INFO - Running setup.py install for mozfile 14:55:53 INFO - Running setup.py install for mozhttpd 14:55:53 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 14:55:53 INFO - Running setup.py install for mozinfo 14:55:53 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 14:55:53 INFO - Running setup.py install for mozInstall 14:55:53 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 14:55:53 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 14:55:53 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 14:55:53 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 14:55:53 INFO - Running setup.py install for mozleak 14:55:53 INFO - Running setup.py install for mozlog 14:55:53 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 14:55:53 INFO - Running setup.py install for moznetwork 14:55:53 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 14:55:53 INFO - Running setup.py install for mozprocess 14:55:53 INFO - Running setup.py install for mozprofile 14:55:53 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 14:55:53 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 14:55:53 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 14:55:53 INFO - Running setup.py install for mozrunner 14:55:54 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 14:55:54 INFO - Running setup.py install for mozscreenshot 14:55:54 INFO - Running setup.py install for moztest 14:55:54 INFO - Running setup.py install for mozversion 14:55:54 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 14:55:54 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:55:54 INFO - Cleaning up... 14:55:54 INFO - Return code: 0 14:55:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:55:54 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')]} 14:55:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:55:54 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')]} 14:55:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:55:54 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 0x1bda1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1da2b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f45df0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f49440>, '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 0x1f4a130>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f44320>, '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': 'cbe409c6de289db6ac707de3000001d4-1507066478.500385-18170245', '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 14:55:54 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 14:55:54 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 14:55:54 INFO - Using env: {'DISPLAY': ':0', 14:55:54 INFO - 'HOME': '/home/cltbld', 14:55:54 INFO - 'LANG': 'en_US.UTF-8', 14:55:54 INFO - 'LANGUAGE': 'en_US:en', 14:55:54 INFO - 'LOGNAME': 'cltbld', 14:55:54 INFO - 'MAIL': '/var/mail/cltbld', 14:55:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:55:54 INFO - 'MOZ_NO_REMOTE': '1', 14:55:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:55:54 INFO - 'NO_EM_RESTART': '1', 14:55:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:55:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:55:54 INFO - 'PWD': '/builds/slave/test', 14:55:54 INFO - 'SHELL': '/bin/bash', 14:55:54 INFO - 'SHLVL': '1', 14:55:54 INFO - 'TERM': 'linux', 14:55:54 INFO - 'TMOUT': '86400', 14:55:54 INFO - 'USER': 'cltbld', 14:55:54 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1507066478.500385-18170245', 14:55:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:55:54 INFO - '_': '/tools/buildbot/bin/python'} 14:55:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:55:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:55:54 INFO - Running setup.py (path:/tmp/pip-n1JWRI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:55:54 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)) 14:55:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:55:54 INFO - Running setup.py (path:/tmp/pip-uH7c7o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:55:54 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)) 14:55:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:55:54 INFO - Running setup.py (path:/tmp/pip-jQwRkn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:55:54 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)) 14:55:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:55:54 INFO - Running setup.py (path:/tmp/pip-52H19J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:55:54 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)) 14:55:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:55:54 INFO - Running setup.py (path:/tmp/pip-oWteqw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:55:54 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)) 14:55:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:55:54 INFO - Running setup.py (path:/tmp/pip-hHeAI3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:55:54 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)) 14:55:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:55:54 INFO - Running setup.py (path:/tmp/pip-ZRWAJP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:55: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)) 14:55:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:55:55 INFO - Running setup.py (path:/tmp/pip-PMJ_9U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:55:55 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)) 14:55:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:55:55 INFO - Running setup.py (path:/tmp/pip-LOZulH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:55:55 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)) 14:55:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:55:55 INFO - Running setup.py (path:/tmp/pip-DEk8Pd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:55:55 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)) 14:55:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:55:55 INFO - Running setup.py (path:/tmp/pip-nn1Wo0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:55:55 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)) 14:55:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:55:55 INFO - Running setup.py (path:/tmp/pip-sArTtN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:55:55 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)) 14:55:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:55:55 INFO - Running setup.py (path:/tmp/pip-019MQP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:55:55 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)) 14:55:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:55:55 INFO - Running setup.py (path:/tmp/pip-AxF3gX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:55:55 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)) 14:55:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:55:55 INFO - Running setup.py (path:/tmp/pip-xCMVay-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:55:55 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)) 14:55:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:55:55 INFO - Running setup.py (path:/tmp/pip-5zNEkM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:55:55 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)) 14:55:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:55:55 INFO - Running setup.py (path:/tmp/pip-rPNwrC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:55:55 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)) 14:55:55 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)) 14:55:55 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)) 14:55:55 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)) 14:55:55 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)) 14:55:55 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)) 14:55:55 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)) 14:55:55 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:55:55 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 14:55:55 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 14:55:55 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 14:55:55 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 14:55:56 INFO - Downloading blessings-1.6.tar.gz 14:55:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 14:55:57 INFO - Installing collected packages: blessings 14:55:57 INFO - Running setup.py install for blessings 14:55:57 INFO - Successfully installed blessings 14:55:57 INFO - Cleaning up... 14:55:57 INFO - Return code: 0 14:55:57 INFO - Done creating virtualenv /builds/slave/test/build/venv. 14:55:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:55:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:55:57 INFO - Reading from file tmpfile_stdout 14:55:57 INFO - Current package versions: 14:55:57 INFO - argparse == 1.2.1 14:55:57 INFO - blessings == 1.6 14:55:57 INFO - blobuploader == 1.2.4 14:55:57 INFO - docopt == 0.6.1 14:55:57 INFO - functools32 == 3.2.3-2 14:55:57 INFO - jsonschema == 2.5.1 14:55:57 INFO - manifestparser == 1.1 14:55:57 INFO - mozInstall == 1.13 14:55:57 INFO - mozcrash == 1.0 14:55:57 INFO - mozdebug == 0.1 14:55:57 INFO - mozdevice == 0.51 14:55:57 INFO - mozfile == 1.2 14:55:57 INFO - mozhttpd == 0.7 14:55:57 INFO - mozinfo == 0.10 14:55:57 INFO - mozleak == 0.1 14:55:57 INFO - mozlog == 3.5 14:55:57 INFO - moznetwork == 0.27 14:55:57 INFO - mozprocess == 0.25 14:55:57 INFO - mozprofile == 0.28 14:55:57 INFO - mozrunner == 6.13 14:55:57 INFO - mozscreenshot == 0.1 14:55:57 INFO - mozsystemmonitor == 0.3 14:55:57 INFO - moztest == 0.8 14:55:57 INFO - mozversion == 1.4 14:55:57 INFO - psutil == 3.1.1 14:55:57 INFO - requests == 1.2.3 14:55:57 INFO - wsgiref == 0.1.2 14:55:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:55:57 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')]} 14:55:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:55:57 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')]} 14:55:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:55:57 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 0x1bda1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1da2b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f45df0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f49440>, '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 0x1f4a130>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f44320>, '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': 'cbe409c6de289db6ac707de3000001d4-1507066478.500385-18170245', '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 14:55:57 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 14:55:57 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 14:55:57 INFO - Using env: {'DISPLAY': ':0', 14:55:57 INFO - 'HOME': '/home/cltbld', 14:55:57 INFO - 'LANG': 'en_US.UTF-8', 14:55:57 INFO - 'LANGUAGE': 'en_US:en', 14:55:57 INFO - 'LOGNAME': 'cltbld', 14:55:57 INFO - 'MAIL': '/var/mail/cltbld', 14:55:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:55:57 INFO - 'MOZ_NO_REMOTE': '1', 14:55:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:55:57 INFO - 'NO_EM_RESTART': '1', 14:55:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:55:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:55:57 INFO - 'PWD': '/builds/slave/test', 14:55:57 INFO - 'SHELL': '/bin/bash', 14:55:57 INFO - 'SHLVL': '1', 14:55:57 INFO - 'TERM': 'linux', 14:55:57 INFO - 'TMOUT': '86400', 14:55:57 INFO - 'USER': 'cltbld', 14:55:57 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1507066478.500385-18170245', 14:55:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:55:57 INFO - '_': '/tools/buildbot/bin/python'} 14:55:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:55:57 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)) 14:55:57 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)) 14:55:57 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)) 14:55:57 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)) 14:55:57 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)) 14:55:57 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)) 14:55:57 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)) 14:55:57 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)) 14:55:57 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)) 14:55:57 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 14:55:57 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 14:55:57 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 14:55:57 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 14:55:57 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 14:55:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 14:55:59 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)) 14:55:59 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)) 14:55:59 INFO - Installing collected packages: simplejson 14:55:59 INFO - Running setup.py install for simplejson 14:55:59 INFO - building 'simplejson._speedups' extension 14:55:59 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 14:56:00 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 14:56:00 INFO - Successfully installed simplejson 14:56:00 INFO - Cleaning up... 14:56:00 INFO - Return code: 0 14:56:00 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 14:56:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:56:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:56:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:56:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:56:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bda1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1da2b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f45df0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f49440>, '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 0x1f4a130>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f44320>, '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': 'cbe409c6de289db6ac707de3000001d4-1507066478.500385-18170245', '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 14:56:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 14:56:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 14:56:00 INFO - Using env: {'DISPLAY': ':0', 14:56:00 INFO - 'HOME': '/home/cltbld', 14:56:00 INFO - 'LANG': 'en_US.UTF-8', 14:56:00 INFO - 'LANGUAGE': 'en_US:en', 14:56:00 INFO - 'LOGNAME': 'cltbld', 14:56:00 INFO - 'MAIL': '/var/mail/cltbld', 14:56:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:56:00 INFO - 'MOZ_NO_REMOTE': '1', 14:56:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:56:00 INFO - 'NO_EM_RESTART': '1', 14:56:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:56:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:56:00 INFO - 'PWD': '/builds/slave/test', 14:56:00 INFO - 'SHELL': '/bin/bash', 14:56:00 INFO - 'SHLVL': '1', 14:56:00 INFO - 'TERM': 'linux', 14:56:00 INFO - 'TMOUT': '86400', 14:56:00 INFO - 'USER': 'cltbld', 14:56:00 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1507066478.500385-18170245', 14:56:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:56:00 INFO - '_': '/tools/buildbot/bin/python'} 14:56:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:56:01 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 14:56:01 INFO - Cleaning up... 14:56:01 INFO - Return code: 0 14:56:01 INFO - Running post-action listener: _resource_record_post_action 14:56:01 INFO - Running post-action listener: _start_resource_monitoring 14:56:01 INFO - Starting resource monitoring. 14:56:01 INFO - [mozharness: 2017-10-03 21:56:01.126917Z] Finished create-virtualenv step (success) 14:56:01 INFO - [mozharness: 2017-10-03 21:56:01.127866Z] Running install step. 14:56:01 INFO - Running pre-action listener: _resource_record_pre_action 14:56:01 INFO - Running main action method: install 14:56:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:56:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:56:01 INFO - Reading from file tmpfile_stdout 14:56:01 INFO - Detecting whether we're running mozinstall >=1.0... 14:56:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 14:56:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 14:56:01 INFO - Reading from file tmpfile_stdout 14:56:01 INFO - Output received: 14:56:01 INFO - Usage: mozinstall [options] installer 14:56:01 INFO - Options: 14:56:01 INFO - -h, --help show this help message and exit 14:56:01 INFO - -d DEST, --destination=DEST 14:56:01 INFO - Directory to install application into. [default: 14:56:01 INFO - "/builds/slave/test"] 14:56:01 INFO - --app=APP Application being installed. [default: firefox] 14:56:01 INFO - mkdir: /builds/slave/test/build/application 14:56:01 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'] 14:56:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 14:56:19 INFO - Reading from file tmpfile_stdout 14:56:19 INFO - Output received: 14:56:19 INFO - /builds/slave/test/build/application/firefox/firefox 14:56:19 INFO - Running post-action listener: _resource_record_post_action 14:56:19 INFO - [mozharness: 2017-10-03 21:56:19.093811Z] Finished install step (success) 14:56:19 INFO - [mozharness: 2017-10-03 21:56:19.094064Z] Running setup-mitmproxy step. 14:56:19 INFO - Running pre-action listener: _resource_record_pre_action 14:56:19 INFO - Running main action method: setup_mitmproxy 14:56:19 INFO - Skipping: mitmproxy is not required 14:56:19 INFO - Running post-action listener: _resource_record_post_action 14:56:19 INFO - [mozharness: 2017-10-03 21:56:19.095200Z] Finished setup-mitmproxy step (success) 14:56:19 INFO - [mozharness: 2017-10-03 21:56:19.095422Z] Running run-tests step. 14:56:19 INFO - Running pre-action listener: _resource_record_pre_action 14:56:19 INFO - Running pre-action listener: _set_gcov_prefix 14:56:19 INFO - Running main action method: run_tests 14:56:19 WARNING - Try message not found. 14:56:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 14:56:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 14:56:19 INFO - Python 2.7.3 14:56:19 INFO - Return code: 0 14:56:19 INFO - grabbing minidump binary from tooltool 14:56:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:56:19 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 0x1f49440>, '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 0x1f4a130>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f44320>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 14:56:19 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 14:56:19 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 14:56:19 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 14:56:19 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:56:19 INFO - Return code: 0 14:56:19 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 14:56:19 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 14:56:19 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 14:56:19 INFO - ENV: RUST_BACKTRACE is now full 14:56:19 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 14:56:19 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 14:56:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/CDkVOCj0R22Mi5t4EKDL4Q/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-054', '--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 14:56:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/CDkVOCj0R22Mi5t4EKDL4Q/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-054 --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 14:56:19 INFO - Using env: {'DISPLAY': ':0', 14:56:19 INFO - 'HOME': '/home/cltbld', 14:56:19 INFO - 'LANG': 'en_US.UTF-8', 14:56:19 INFO - 'LANGUAGE': 'en_US:en', 14:56:19 INFO - 'LOGNAME': 'cltbld', 14:56:19 INFO - 'MAIL': '/var/mail/cltbld', 14:56:19 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 14:56:19 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 14:56:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:56:19 INFO - 'MOZ_NO_REMOTE': '1', 14:56:19 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 14:56:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:56:19 INFO - 'NO_EM_RESTART': '1', 14:56:19 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:56:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:56:19 INFO - 'PWD': '/builds/slave/test', 14:56:19 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 14:56:19 INFO - 'RUST_BACKTRACE': 'full', 14:56:19 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 14:56:19 INFO - 'SHELL': '/bin/bash', 14:56:19 INFO - 'SHLVL': '1', 14:56:19 INFO - 'TERM': 'linux', 14:56:19 INFO - 'TMOUT': '86400', 14:56:19 INFO - 'USER': 'cltbld', 14:56:19 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1507066478.500385-18170245', 14:56:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:56:19 INFO - '_': '/tools/buildbot/bin/python'} 14:56:19 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/CDkVOCj0R22Mi5t4EKDL4Q/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-054', '--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 14:56:19 INFO - mozversion application_buildid: 20171003202555 14:56:19 INFO - mozversion application_changeset: 65a5054a1f922b83929c80658062f441ca3da6a0 14:56:19 INFO - mozversion application_display_name: Nightly 14:56:19 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:56:19 INFO - mozversion application_name: Firefox 14:56:19 INFO - mozversion application_remotingname: firefox 14:56:19 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 14:56:19 INFO - mozversion application_vendor: Mozilla 14:56:19 INFO - mozversion application_version: 58.0a1 14:56:19 INFO - mozversion platform_buildid: 20171003202555 14:56:19 INFO - mozversion platform_changeset: 65a5054a1f922b83929c80658062f441ca3da6a0 14:56:19 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 14:56:19 INFO - mozversion platform_version: 58.0a1 14:56:19 INFO - using testdate: 1507067779 14:56:19 INFO - actual date: 1507067779 14:56:19 INFO - starting webserver on 'localhost:55543' 14:56:19 INFO - SUITE-START | Running 2 tests 14:56:19 INFO - TEST-START | dromaeo_css 14:56:19 INFO - Initialising browser for dromaeo_css test... 14:56:19 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:55543/getInfo.html -profile /tmp/tmp_pjRcD/profile 14:56:19 INFO - TEST-INFO | started process 3663 (/builds/slave/test/build/application/firefox/firefox http://localhost:55543/getInfo.html) 14:56:26 INFO - TEST-INFO | 3663: exit 0 14:56:26 INFO - Browser initialized. 14:56:26 INFO - Running cycle 1/1 for dromaeo_css test... 14:56:26 INFO - Using env: {'DISPLAY': ':0', 14:56:26 INFO - 'HOME': '/home/cltbld', 14:56:26 INFO - 'JSGC_DISABLE_POISONING': '1', 14:56:26 INFO - 'LANG': 'en_US.UTF-8', 14:56:26 INFO - 'LANGUAGE': 'en_US:en', 14:56:26 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 14:56:27 INFO - 'LOGNAME': 'cltbld', 14:56:27 INFO - 'MAIL': '/var/mail/cltbld', 14:56:27 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 14:56:27 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 14:56:27 INFO - 'MOZ_CRASHREPORTER': '1', 14:56:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:56:27 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 14:56:27 INFO - 'MOZ_NO_REMOTE': '1', 14:56:27 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 14:56:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:56:27 INFO - 'NO_EM_RESTART': '1', 14:56:27 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:56:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:56:27 INFO - 'PWD': '/builds/slave/test', 14:56:27 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 14:56:27 INFO - 'RUST_BACKTRACE': 'full', 14:56:27 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 14:56:27 INFO - 'SHELL': '/bin/bash', 14:56:27 INFO - 'SHLVL': '1', 14:56:27 INFO - 'TERM': 'linux', 14:56:27 INFO - 'TMOUT': '86400', 14:56:27 INFO - 'USER': 'cltbld', 14:56:27 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1507066478.500385-18170245', 14:56:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:56:27 INFO - '_': '/tools/buildbot/bin/python'} 14:56:27 INFO - TEST-INFO | started process 3902 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp_pjRcD/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 14:56:27 INFO - PID 3902 | 14:56:27 INFO - PID 3902 | (/builds/slave/test/build/application/firefox/firefox:3951): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 14:56:27 INFO - PID 3902 | 14:56:38 INFO - PID 3902 | RSS: Main: 142831616 14:56:38 INFO - PID 3902 | 14:59:35 INFO - PID 3902 | Cycle 1(1): loaded http://localhost:55543/tests/dromaeo/cssquery-dojo.html (next: http://localhost:55543/tests/dromaeo/cssquery-ext.html) 14:59:36 INFO - PID 3902 | RSS: Main: 143237120 14:59:36 INFO - PID 3902 | 15:02:38 INFO - PID 3902 | Cycle 1(1): loaded http://localhost:55543/tests/dromaeo/cssquery-ext.html (next: http://localhost:55543/tests/dromaeo/cssquery-jquery.html) 15:02:39 INFO - PID 3902 | RSS: Main: 144486400 15:02:39 INFO - PID 3902 | 15:05:40 INFO - PID 3902 | Cycle 1(1): loaded http://localhost:55543/tests/dromaeo/cssquery-jquery.html (next: http://localhost:55543/tests/dromaeo/cssquery-mootools.html) 15:05:41 INFO - PID 3902 | RSS: Main: 145383424 15:05:41 INFO - PID 3902 | 15:08:43 INFO - PID 3902 | Cycle 1(1): loaded http://localhost:55543/tests/dromaeo/cssquery-mootools.html (next: http://localhost:55543/tests/dromaeo/cssquery-prototype.html) 15:08:43 INFO - PID 3902 | RSS: Main: 144556032 15:08:43 INFO - PID 3902 | 15:11:46 INFO - PID 3902 | Cycle 1(1): loaded http://localhost:55543/tests/dromaeo/cssquery-prototype.html (next: http://localhost:55543/tests/dromaeo/cssquery-yui.html) 15:11:47 INFO - PID 3902 | RSS: Main: 147632128 15:11:47 INFO - PID 3902 | 15:14:48 INFO - PID 3902 | Cycle 1(1): loaded http://localhost:55543/tests/dromaeo/cssquery-yui.html (next: http://localhost:55543/tests/dromaeo/cssquery-dojo.html) 15:14:49 INFO - PID 3902 | RSS: Main: 147816448 15:14:49 INFO - PID 3902 | 15:14:49 INFO - PID 3902 | __start_tp_report 15:14:49 INFO - PID 3902 | _x_x_mozilla_page_load 15:14:49 INFO - PID 3902 | _x_x_mozilla_page_load_details 15:14:49 INFO - PID 3902 | |i|pagename|runs| 15:14:49 INFO - PID 3902 | |0;dojo.html;3889.669378102861;3892.260470510601;3899.3760998240286;3907.492026036615;3912.061105334719;6095.268567771874;6111.694415279229;6260.499160067195;6264.84337891553;6325.9051114233325;6274.215723034621;6290.465310448617;6343.143675603792;6354.523410744189;6406.263279722822;5575.665460072409;5703.74333155009;5710.029295019835;5720.485156335929;5728;5488.1493368527945;5598.944010559903;5641.097424412095;5678.659280443166;5777.884442311151;5643.717814109299;5653.547716182737;5718.142278658234;5736.2256095427;5737.598368114233;5643.943560564424;5663.9716801415725;5709.571782116316;5735.627184233012;5741.770329186827;5577.024020796344;5651.7456714447535;5701.743421546041;5712.942870571283;5716.8856622867725;6159.26088869334;6190.84522886927;6197.752089916398;6249.968755155394;6311.495080393557;6749.696263668097;6808.761693340709;6819.454443644497;6831.521793474459;6842.965785171092;6782;6796.184457865088;6797.150356205474;6882;6887.793366199022;6206.06908963659;6306.400891915307;6306.905396419057;6360.236771587439;6414.069959855888;6969.4424446044195;7028.191757947901;7033.296670332926;7057.329553692391;7075.893861592081;6677.866442671119;6682.231543372477;6705.195376554844;6798.694058767367;6805.149356330458;6686.598804071771;6772.424343930821;6815.454763618898;6877.037214789933;6891.862162756717;6692.330766923269;6732.663366831639;6779;6781.932180678229;6798.184217893884;6683.465322774166;6719;6775.762848300285;6790.320967903269;6793.45652347811;5022.673526220857;5023.095842748267;5035.16919708244;5038.395392552844;5042.369703787027;4536.4102666653125;4545.159145558082;4553.977230113828;4565.383673204141;4567.383403240587;6224.06639004153;6270.0908368287255;6274.686265686789;6288.8427789304715;6291.1192433059405;6752.189737231563;6772.051912732221;6848.760293389708;6850.4862135340045;6864.862702745917;6099.359567245464;6148.631082135086;6179.660118693425;6276.372362763687;6288.9056664150075;5445.727713614304;5501.807436739775;5564.944350556523;5573.860653483695;5583.609147359727;7121.89317160243;7130.750423735144;7133.964330178316;7141.9642901785155;7145.964270178616;6739.966300168468;6746.932530674827;6757.560758550679;6810.659467026728;6821.4542836572955;6796.048553202654;6922.480813938874;7034.331738484836;7119.8932016020835;7131.286871313035;6689.732410703712;6749.088873002278;6749.5612785168805;6755.189377274562;6777.695003724943;6628.635425051669;6755.05429239897;6799.796006119825;6802.557833740791;6807.047013418027;6499.447546958561;6512.772052978123;6581.901271481028;6584.209894812653;6600.042993765973;6491.772787952399;6514.218293804774;6589.044588534732;6632;6640.037194606852;6385.297617262005;6455.935440645721;6471;6473.190851143607;6474.643894585654;6469.611823290617;6553.2136143663065;6617.040529123154;6633.1376920999965;6641.269460359454;6331.746730130743;6348.269948955744;6392.169018027626;6408.263049749152;6440.67796610177 15:14:49 INFO - PID 3902 | |1;ext.html;987.4963768478076;1012.0335079998603;1012.3520946594181;1014.6144465103268;1017.8218811707949;41029.17941641165;45946.54053459464;46177.769111154485;46184.307235391454;46311;42613.57386426142;42921.356179657254;42969.14061718772;43042.78478607602;43049.7847510762;7808.687652493908;7919.049714034324;7935.801604959865;7937.206279372045;7960.084590272106;7759.301662850342;7862.567558784264;7864.488808227476;7873.763787086396;7874;7807.609619519029;7854.253845884632;7878.094019187781;8314.70898518552;8592.05487396386;8435.578221088952;8526.275266602346;8535.189157030072;8536.829263414758;8546.40175187737;8285.088640249567;8334.66661333546;8441.282490988273;8461.576921153917;8522.659093636246;41197.176056479;41231;41395.1720965579;41538.37692434586;41697.166056679;3738.495303134069;3793.336166170909;3798.0504873781556;3799.031247031989;3801.543814742221;33895.661043389744;33918.660813392045;33963.66036339654;34159.82920085409;34297.65702342994;5599.300087489064;5627.296587926509;5627.409122042168;5628.943710562883;5636.323641163087;238238;238619;239087;239384;239600;47269.5273047272;47464.762676187085;52873.735631322364;53022.46977530175;53087.73456132694;55739.16391254134;55814.44185558174;55859;55887.16169257464;55958.160627590616;17209.483715488554;17220.569485762957;17307.826921730873;17310.82689173092;17398.56503587395;14005.649858753613;14095.929520352538;14114.50599229022;14179.220142892034;14185.858141418661;14615.561533154023;14750;14839.480618178311;14865.2567371631;14935.10389376619;5821.53427725781;5902.547009999199;5905.143754155623;5956.374580669054;6027.6684782336515;1075.6665433715416;1090.1278976818514;1105.9170562207867;1108.5233349659563;1122.927009744364;26230.475390492084;26303.079392221563;26307.473850523264;26349.868250658623;26511.86744066306;1182.1429463638965;1229.674136353867;1239.7148655809035;1243.6890777305673;1245.495574292413;13784.517541885985;13914.721705566035;13999.930000350138;14057.156570605797;14096.295185240902;304947;305085;307063;307206;307817;59403.40596594065;59626;59706.40293597009;59895.401045989856;59972;1533.4786172701336;1573.9213039347987;1578.921053947298;1583.1767480909862;1609.7343938250292;25825.870870645525;25990.740092598833;26133.869330653986;26144.60783088293;26328.21015369542;42195;42287.365689515296;42328.36507452329;42343.5765642352;42415.36376954286;248186;248208;248433;248629;250742;33833;35708.28583428369;41637.58362416337;44042.779786100866;44750.77624611856;55809.441905580425;55827.72086139706;55964.44035559592;56621;56799.43200567942;39245.2150956985;39343.22955467068;39462;39498;39682.603173969044;40284.79857600811;40529;40573.79713101534;40822.38766418447;40891.38662919999;47915.520844791106;47931.760341199464;48478.5152148474;49261;49361;38948.41577376285;39653.206935860544;39807.60192398155;40317.39523907202;40422;28052.298692532033;28393.43213135767;28657.570136447554;28863.278418039718;29044.27389315284 15:14:49 INFO - PID 3902 | |2;jquery.html;617.1606615003594;619.3651507205111;622.9221347331584;649.3895738006274;653.5196630476601;23795.167169149085;24158;24322.75677243227;24546.3863403415;24645.38386540335;26547.336316592096;27172.864135679294;27221.45557088857;27226;27278.863605681992;6755.054292399068;6863;6873.243943166248;6926.757563485279;6928.376446119848;6704.161979752531;6944.548604340727;6947.826304342381;6963.721451141948;6966.965165174167;6931.618760968145;6936.445084393236;6944.236134025253;6945.652717364137;6947.930520694779;6750.729970801162;6926.168859736838;7001;7006.929930700704;7009.684564194624;6516.3157868423605;6520.869582608369;6527.445167160797;6538.248101468344;6551.93448065518;30390;30704.2323941901;30800.537991930138;30888.845555772303;30912.53631195534;2317.4901521665206;2324.9186278480342;2328.2200462844967;2339.3683705399617;2344.859308441499;19899.303524376577;20830.687539686918;20912;20950.266740664003;20996.895015524977;2570.974290257111;2585.0564543941514;2593.1701855406277;2618.122928818848;2623.081921327539;141293;147845;166900.16549917293;167115;174713;15845.445409410615;16708.6658266834;16812.663746724997;16874.662506749795;17045.14774261282;26827.195184144508;26866.462670746867;26889.86555067251;26903;26962.056328028426;10503.369797812156;10722.73193170177;10757.731056723487;10833.83749243762;10960;9616.374935629161;9685.564149613285;9843.507824608741;9913.603455861685;9930.113278477067;9924.900750992398;9956.601735930482;10087.193024558017;10162.593496260066;10192.694219173436;2828.575713642929;2875.2093174377214;3045.7867949243782;3051;3075.7231849133686;1052.7420781908481;1064.7817197474653;1066.088494337346;1072.5495291977388;1073.4042606353394;20781.896090519753;21239.89380053121;21318.467038324165;21360;21906.014229359716;1133.0935251798528;1133.9149563782748;1148.6955956671488;1150.896419322265;1177.4230626992883;11425.31448113116;11682.299062056303;11828.88171118295;11856.762864742657;11925.224860384047;628873;640381;640520;647042;658901;136026.31986840002;138585.30707346398;138633.30683346518;139821.30089349693;141776;1141.5547936304847;1199.6161228407;1251.4055823483955;1256.2650849253107;1284.9807252891028;21533.106952484628;21658.241961531272;21887.67168492506;22134.35187586035;22320.21879234219;65528;66520.68320261224;67392;68461;68629;82499.5875020621;83412.58293708492;83850.16149838667;84534;84621;15672.138032408327;15691.215439228221;15707;15754.921225394259;15972.440964566184;24101.879490603136;24296;24372.26883193507;24513.142040028513;24791.752082478943;18860.339888103852;19472.123754431366;19710.310139145062;19987.800121998593;20044.29844955478;17042.65914681724;17496.12519374001;17613.11934403249;17751.46745597634;18007.459776206735;19031.71452428187;19931.501712457306;20372;20378.898105509972;20441;15361.769573456631;15473.303701333241;15664.138472383673;15694.9215253923;15708.214589270263;19359.612807744048;19402.4179274622;19466.61066778685;19513.804861951765;19514.414567562995 15:14:49 INFO - PID 3902 | |3;mootools.html;1490.1506141499344;1520.9695806083878;1529.5793656744402;1547.6749882524673;1564.8435156484345;16761.66476670466;17483.12584370783;17488.3004679813;17507.299708011695;17519.124043797823;16951.57621059474;17042.48872533823;17147.314107435686;17152.571185720364;17182.74225886613;2784.860756962154;2803.649543807019;2863.355744957378;2863.4416288823686;2863.914082577526;2770.4320614274025;2813.08574713219;2857.671367792699;2865.5988161657383;2869.3257084585107;2783.8747256373513;2880.9423811523757;2882.9423411531757;2885.0623547347195;2892.811967222134;2828.2081017315186;2862.6421697287838;2873.841938693371;2878.510653188963;2926.561015847619;2648.139354709727;2696.3933115049153;2713.1589207345587;2717.8097533172686;2719.9184024479296;16483.6703265934;16689;16723.91638041814;16748.58128546796;16771.2452939618;8731.60707768152;9013.143751343612;9015.098490150998;9015.954920225422;9023.458592484407;5540.113581826889;5700.429957004308;5726.742296596663;5779.133130030487;5826.854328641775;2133.626615342309;2137.722096127509;2177.0638625391075;2211.8562293450873;2227.365200917731;31607.84196079005;31979.200519986953;32178.195545111324;32292.19269518281;33066.66933330684;7214.49498535108;7281.5995120267835;7343.3390994810725;7603.163651998276;7691.5385076894445;20480;20555.38333849987;20642.58714825695;20795.376138715863;20829.270975516087;8425.241728244488;8472.872906906401;8517.148285171434;8520.19058189471;8564.314854811724;7759.922400776033;7807.219278072261;7865.960670196612;7939.523628582304;8153.714619988272;5602.1036634138345;5788.768449261982;5826.096954971986;5892.823215303548;5910.261217347832;1721.4491362764045;1752.9386471473692;1763.9823601764074;1778.7420823980708;1785.61609254011;991.1674193677202;1003.8042581696645;1004.9648262310784;1033.256055639953;1041.953112109957;8699.13008699125;8891.19979201875;8895.688650897187;8950.328725345626;9040.367174297868;795.1253621016835;803.2971150243537;804.1355542791523;817.4468509831249;818.3453237410167;4792.113459010095;4995.300657907896;5389.7035663038905;5451.836444906659;5509.807156749494;24332.9477924461;25187.87406062958;25398.238052858444;26036;26389.208323750318;25261;25368.619470707956;25502.23493295204;26151.869240653676;26182.08362707296;10318.690439286833;11660.941695291638;12718.427670754838;12857.485700571873;13098.803517947239;10178.491075446345;10478.528466219192;10499.68500944973;10513.369197848153;10754.300970436896;21944.231951881608;21979.560408792055;21994.560108798054;22523.32430027102;22771.658425123303;22187.88906055524;22460.66309005399;22897.198598048988;23017;23161.76838231596;12693.555725549932;13138.540151094665;13294.734105318033;13304.600861974157;13313.46746130144;15404.306806193525;15647.374105035671;15724.135172565619;15791.28939197716;15829.604259893595;12611.621651350475;12817.871821281668;13136.211827290394;13186.868131318564;13232.735345293233;12162.087843411957;12283.877161228631;12619.305938173122;12665.240085594894;12737.617871463872;10733.838992414963;10754.623588174376;10785.892141078803;10819.080378167944;10927.45362731876;7944.44388892772;8134.186581341818;8249.05135909378;8278.213569710868;8302.045264794626;8267.83464330698;8313.12712165214;8332.750017499484;8357.206065423776;8434.95782521108 15:14:49 INFO - PID 3902 | |4;prototype.html;538.6498775795732;562.7298896529666;568.3605943313772;578.9189513468115;590.4479311843427;6136.251168417002;6137.907931381022;6154.69226538673;6198.729260501597;6205.720742566584;6084.543659225565;6154.81535553933;6158.907616385758;6160.597367026863;6178.660173690445;5005.499450054984;5036.219385995177;5039.09296326661;5039.546440820325;5091.872703182432;4870.561649451549;4992.925106123411;5001.399832020163;5014.573761230282;5070.847874563769;4966.925496117561;4978.278149668296;4986.601071914256;4991.600671946236;5011.523905229015;4950.727709975949;4960.925586116211;4969;4988.351514303117;5008.348914641091;4465.218586747306;4526.687260694394;4539.54734484965;4543.091381723635;4562.726236425792;6016.608920420159;6045.45590896817;6049.425304596057;6091.2995005574485;6116.847078823065;5732.914006289909;5740.052891272935;5750.223719797816;5810.099434587603;5812.709364531756;6545.836354091138;6553.279139294674;6563.245226798884;6579.835504112388;6610.338966103399;4849.102915960559;5029;5059.6458247922665;5094.7197904115255;5112.667676601009;6591.373819487189;6676.998450232455;6694.326418395402;6716.83207919801;6792.592444453299;5214.687118772886;5226.634135610547;5238.345206849144;5241.842744717665;5248.396434410015;5292.100342941709;5309.7079660618265;5314.548263397577;5317.813876514303;5326.866828329245;5774.566907481955;5792.391798861059;5822.155787410802;5840.912386314209;5847.210626565445;6493.837654058591;6538.248101468297;6540.640264785482;6596.746618142538;6626.734930602818;1795.165248159612;1803.2426380920044;1803.603207294393;1817.736428217901;1829.0611951331632;1260.8171815086944;1313.4089659653193;1315.6908126590245;1322.1604281281263;1331.8401791784854;5859.414058594107;5879.2944846618675;5959.165716799652;5988.850278743066;6030.095485677096;6364.172657554581;6428.6785660717715;6451.387118223761;6475.701126100261;6530.444912182517;5838.9124163137585;5859.970700146472;5866.298773355765;5907.852303692442;6002.61939753859;3398.5411969383817;3442.225499262646;3457;3484.0244731475227;3485.390056740111;6690.933090669128;6714.563553369016;6718.798436046926;6781.220159681699;6832.350926661959;6635.303293154149;6649.60102393858;6692.431143352772;6737.764178253738;6805.863882722417;6673.496414382488;6698.5980841149685;6795.558288711218;6822.522423430312;6874.312568743086;6399;6442.291347951628;6454.870902582016;6512.902306465501;6519.771807986698;6539.378759017885;6549.639769812516;6571.638559879252;6575.309592492719;6610.140681711347;6720.932790672031;6759.357861003196;6811.761588344582;6818.943063825115;6866.210385805696;5159.948400515947;5172.456892026434;5179.741012949413;5186.533212010937;5234.214867769865;6358.205224346957;6455.7740479083;6535.57518761279;6563.571478552966;6604.603723776589;6183.505319574333;6439.839004024937;6478.218489915311;6497.25281592604;6498.772542961163;6303.590266632654;6337.176167098248;6404.295527491879;6432.35676432353;6456.967715161394;6161.336439161311;6174.722137503733;6203.65879876593;6233.563650544418;6321.1782468278825;6380.9042864358;6407.475888261531;6414.5189110815945;6502.317256687979;6508.576942498722;6192.142357292704;6227.750889964351;6293.433590976834;6330.715117819801;6339.080833279241 15:14:49 INFO - PID 3902 | |5;yui.html;969.0793745941353;986.8125056239314;988.1008282462959;992.0377234082941;1006.3106771861271;20753.896230518843;21237.150513979443;21355.145794168253;21359.67960480594;21383.35849924501;18621.90689046553;18704.71942920854;18763.249470021183;18773.530661733428;18775.90612046938;1057.2599180573573;1067.3542506783401;1082.6427278997912;1085.6091806949491;1088.3198001249218;1084.5444913136503;1084.9457527123611;1087.6845714742715;1087.6900083476212;1091.0834898685102;1085.696005118564;1127.4644543841691;1129.8418221449003;1130.530829705671;1131.9377434241114;1119.11030730569;1120.2270433400927;1124.983125253122;1127.6786115956954;1129.1926272714966;792.6195426195401;822.5599304372132;828.556722153651;828.5857071464268;828.9046759622661;19853;19933;19964;20053.699194512094;20143.093560789654;23509.647355289686;23688.881555592285;24434.633480497807;24616.138435154684;24637.5072498549;18840.152193151203;18888.544489216452;18988.5252868678;19122;19131.61736765257;4064.126212864219;4083.591640835922;4086.162336720965;4086.2031903778775;4091.8747344480207;147353;171503;172322;172960;175292;19702.11340489653;19724.506887327643;19811.801881980995;19851.900740496494;19966.50083747918;34490.31019379598;36015;36084.81957590195;36180.27639447196;36237.45643815345;10029.69910902674;10112.191024718006;10127.240456965757;10127.341722787995;10201.795964080826;4784.162771514971;4858.222684370484;4859.1010663027455;4862.100511405401;4873.975630121827;6188.009918413032;6197.163382943245;6258;6329.810105696836;6374.776882809172;1198.8670706182575;1208.8904861017581;1230.9815352769715;1234.7715672600598;1236.3323805145121;727.8435136445689;730.9122905251404;754.1478129713498;754.4567911103996;760.8706519891632;13499.325033748273;13742.450301987808;13884.375203115884;14281.78577321341;14499.92750036243;742.0909386002212;743.4089898530681;747.0736287004075;747.3274053352027;753.0248328414716;6171.907421388682;6225.097360882647;6242.531810114168;6272.560920735505;6370.235571731422;162446;162574;163136;167010.16494917448;167834;141826;142482.2875885614;143951;144253;148040;118192.40903795598;119579.40210298893;120607.39696301287;120698.39650801866;123745.38127309305;11685.357305348287;11862.22895511789;11889;11909.975911289896;11935.224210426295;57033.714831425576;57856.71071644615;58043.12935306058;58093.4190658088;59583.843329252675;74434;74953.62523187349;75436.62281688556;78309;79228.20771792208;17737.645247095243;17754.02352870553;17853.910730446263;18040.81959180444;18053;19358.903205483883;19489.70765438491;19575.70636440483;19684.80315196887;19693.704594431383;17828;18063.638727225643;18278.634427311645;18486.260549578405;18523.44429667112;18750.53123671919;18877.811221887605;18902.52743681419;18929.810701892806;19087.52281192981;18516.2593496264;18592.44222673322;18672.25310987545;18700.7194892074;18780.248790048245;13428.261445620585;13658.863411366157;13677.384517696528;13835.308234588432;13910.095843770527;13575.86424135746;13741.587752367444;13840;13841.86158138446;13846.653833653836 15:14:49 INFO - PID 3902 | __end_tp_report 15:14:49 INFO - PID 3902 | __start_cc_report 15:14:49 INFO - PID 3902 | _x_x_mozilla_cycle_collect,288 15:14:49 INFO - PID 3902 | __end_cc_report 15:14:49 INFO - PID 3902 | __startTimestamp1507068889015__endTimestamp 15:14:49 INFO - PID 3902 | 15:14:49 INFO - PID 3902 | ------- Summary: start ------- 15:14:49 INFO - PID 3902 | Number of tests: 6 15:14:49 INFO - PID 3902 | 15:14:49 INFO - PID 3902 | [#0] dojo.html Cycles:165 Average:6256.72 Median:6469.61 stddev:725.77 (11.2%) stddev-sans-first:703.84 15:14:49 INFO - PID 3902 | Values: 3889.7 3892.3 3899.4 3907.5 3912.1 6095.3 6111.7 6260.5 6264.8 6325.9 6274.2 6290.5 6343.1 6354.5 6406.3 5575.7 5703.7 5710.0 5720.5 5728.0 5488.1 5598.9 5641.1 5678.7 5777.9 5643.7 5653.5 5718.1 5736.2 5737.6 5643.9 5664.0 5709.6 5735.6 5741.8 5577.0 5651.7 5701.7 5712.9 5716.9 6159.3 6190.8 6197.8 6250.0 6311.5 6749.7 6808.8 6819.5 6831.5 6843.0 6782.0 6796.2 6797.2 6882.0 6887.8 6206.1 6306.4 6306.9 6360.2 6414.1 6969.4 7028.2 7033.3 7057.3 7075.9 6677.9 6682.2 6705.2 6798.7 6805.1 6686.6 6772.4 6815.5 6877.0 6891.9 6692.3 6732.7 6779.0 6781.9 6798.2 6683.5 6719.0 6775.8 6790.3 6793.5 5022.7 5023.1 5035.2 5038.4 5042.4 4536.4 4545.2 4554.0 4565.4 4567.4 6224.1 6270.1 6274.7 6288.8 6291.1 6752.2 6772.1 6848.8 6850.5 6864.9 6099.4 6148.6 6179.7 6276.4 6288.9 5445.7 5501.8 5564.9 5573.9 5583.6 7121.9 7130.8 7134.0 7142.0 7146.0 6740.0 6746.9 6757.6 6810.7 6821.5 6796.0 6922.5 7034.3 7119.9 7131.3 6689.7 6749.1 6749.6 6755.2 6777.7 6628.6 6755.1 6799.8 6802.6 6807.0 6499.4 6512.8 6581.9 6584.2 6600.0 6491.8 6514.2 6589.0 6632.0 6640.0 6385.3 6455.9 6471.0 6473.2 6474.6 6469.6 6553.2 6617.0 6633.1 6641.3 6331.7 6348.3 6392.2 6408.3 6440.7 15:14:49 INFO - PID 3902 | 15:14:49 INFO - PID 3902 | [#1] ext.html Cycles:180 Average:45369.32 Median:40369.70 stddev:69335.54 (171.8%) stddev-sans-first:69449.96 15:14:49 INFO - PID 3902 | Values: 987.5 1012.0 1012.4 1014.6 1017.8 41029.2 45946.5 46177.8 46184.3 46311.0 42613.6 42921.4 42969.1 43042.8 43049.8 7808.7 7919.0 7935.8 7937.2 7960.1 7759.3 7862.6 7864.5 7873.8 7874.0 7807.6 7854.3 7878.1 8314.7 8592.1 8435.6 8526.3 8535.2 8536.8 8546.4 8285.1 8334.7 8441.3 8461.6 8522.7 41197.2 41231.0 41395.2 41538.4 41697.2 3738.5 3793.3 3798.1 3799.0 3801.5 33895.7 33918.7 33963.7 34159.8 34297.7 5599.3 5627.3 5627.4 5628.9 5636.3 238238.0 238619.0 239087.0 239384.0 239600.0 47269.5 47464.8 52873.7 53022.5 53087.7 55739.2 55814.4 55859.0 55887.2 55958.2 17209.5 17220.6 17307.8 17310.8 17398.6 14005.6 14095.9 14114.5 14179.2 14185.9 14615.6 14750.0 14839.5 14865.3 14935.1 5821.5 5902.5 5905.1 5956.4 6027.7 1075.7 1090.1 1105.9 1108.5 1122.9 26230.5 26303.1 26307.5 26349.9 26511.9 1182.1 1229.7 1239.7 1243.7 1245.5 13784.5 13914.7 13999.9 14057.2 14096.3 304947.0 305085.0 307063.0 307206.0 307817.0 59403.4 59626.0 59706.4 59895.4 59972.0 1533.5 1573.9 1578.9 1583.2 1609.7 25825.9 25990.7 26133.9 26144.6 26328.2 42195.0 42287.4 42328.4 42343.6 42415.4 248186.0 248208.0 248433.0 248629.0 250742.0 33833.0 35708.3 41637.6 44042.8 44750.8 55809.4 55827.7 55964.4 56621.0 56799.4 39245.2 39343.2 39462.0 39498.0 39682.6 40284.8 40529.0 40573.8 40822.4 40891.4 47915.5 47931.8 48478.5 49261.0 49361.0 38948.4 39653.2 39807.6 40317.4 40422.0 28052.3 28393.4 28657.6 28863.3 29044.3 15:14:49 INFO - PID 3902 | 15:14:49 INFO - PID 3902 | [#2] jquery.html Cycles:180 Average:41759.72 Median:2333.79 stddev:107712.27 (4615.3%) stddev-sans-first:107970.14 15:14:49 INFO - PID 3902 | Values: 617.2 619.4 622.9 649.4 653.5 23795.2 24158.0 24322.8 24546.4 24645.4 26547.3 27172.9 27221.5 27226.0 27278.9 6755.1 6863.0 6873.2 6926.8 6928.4 6704.2 6944.5 6947.8 6963.7 6967.0 6931.6 6936.4 6944.2 6945.7 6947.9 6750.7 6926.2 7001.0 7006.9 7009.7 6516.3 6520.9 6527.4 6538.2 6551.9 30390.0 30704.2 30800.5 30888.8 30912.5 2317.5 2324.9 2328.2 2339.4 2344.9 19899.3 20830.7 20912.0 20950.3 20996.9 2571.0 2585.1 2593.2 2618.1 2623.1 141293.0 147845.0 166900.2 167115.0 174713.0 15845.4 16708.7 16812.7 16874.7 17045.1 26827.2 26866.5 26889.9 26903.0 26962.1 10503.4 10722.7 10757.7 10833.8 10960.0 9616.4 9685.6 9843.5 9913.6 9930.1 9924.9 9956.6 10087.2 10162.6 10192.7 2828.6 2875.2 3045.8 3051.0 3075.7 1052.7 1064.8 1066.1 1072.5 1073.4 20781.9 21239.9 21318.5 21360.0 21906.0 1133.1 1133.9 1148.7 1150.9 1177.4 11425.3 11682.3 11828.9 11856.8 11925.2 628873.0 640381.0 640520.0 647042.0 658901.0 136026.3 138585.3 138633.3 139821.3 141776.0 1141.6 1199.6 1251.4 1256.3 1285.0 21533.1 21658.2 21887.7 22134.4 22320.2 65528.0 66520.7 67392.0 68461.0 68629.0 82499.6 83412.6 83850.2 84534.0 84621.0 15672.1 15691.2 15707.0 15754.9 15972.4 24101.9 24296.0 24372.3 24513.1 24791.8 18860.3 19472.1 19710.3 19987.8 20044.3 17042.7 17496.1 17613.1 17751.5 18007.5 19031.7 19931.5 20372.0 20378.9 20441.0 15361.8 15473.3 15664.1 15694.9 15708.2 19359.6 19402.4 19466.6 19513.8 19514.4 15:14:49 INFO - PID 3902 | 15:14:49 INFO - PID 3902 | [#3] mootools.html Cycles:180 Average:10673.93 Median:26093.93 stddev:8028.91 (30.8%) stddev-sans-first:8021.79 15:14:49 INFO - PID 3902 | Values: 1490.2 1521.0 1529.6 1547.7 1564.8 16761.7 17483.1 17488.3 17507.3 17519.1 16951.6 17042.5 17147.3 17152.6 17182.7 2784.9 2803.6 2863.4 2863.4 2863.9 2770.4 2813.1 2857.7 2865.6 2869.3 2783.9 2880.9 2882.9 2885.1 2892.8 2828.2 2862.6 2873.8 2878.5 2926.6 2648.1 2696.4 2713.2 2717.8 2719.9 16483.7 16689.0 16723.9 16748.6 16771.2 8731.6 9013.1 9015.1 9016.0 9023.5 5540.1 5700.4 5726.7 5779.1 5826.9 2133.6 2137.7 2177.1 2211.9 2227.4 31607.8 31979.2 32178.2 32292.2 33066.7 7214.5 7281.6 7343.3 7603.2 7691.5 20480.0 20555.4 20642.6 20795.4 20829.3 8425.2 8472.9 8517.1 8520.2 8564.3 7759.9 7807.2 7866.0 7939.5 8153.7 5602.1 5788.8 5826.1 5892.8 5910.3 1721.4 1752.9 1764.0 1778.7 1785.6 991.2 1003.8 1005.0 1033.3 1042.0 8699.1 8891.2 8895.7 8950.3 9040.4 795.1 803.3 804.1 817.4 818.3 4792.1 4995.3 5389.7 5451.8 5509.8 24332.9 25187.9 25398.2 26036.0 26389.2 25261.0 25368.6 25502.2 26151.9 26182.1 10318.7 11660.9 12718.4 12857.5 13098.8 10178.5 10478.5 10499.7 10513.4 10754.3 21944.2 21979.6 21994.6 22523.3 22771.7 22187.9 22460.7 22897.2 23017.0 23161.8 12693.6 13138.5 13294.7 13304.6 13313.5 15404.3 15647.4 15724.1 15791.3 15829.6 12611.6 12817.9 13136.2 13186.9 13232.7 12162.1 12283.9 12619.3 12665.2 12737.6 10733.8 10754.6 10785.9 10819.1 10927.5 7944.4 8134.2 8249.1 8278.2 8302.0 8267.8 8313.1 8332.8 8357.2 8435.0 15:14:49 INFO - PID 3902 | 15:14:49 INFO - PID 3902 | [#4] prototype.html Cycles:180 Average:5522.30 Median:6126.55 stddev:1508.70 (24.6%) stddev-sans-first:1465.83 15:14:49 INFO - PID 3902 | Values: 538.6 562.7 568.4 578.9 590.4 6136.3 6137.9 6154.7 6198.7 6205.7 6084.5 6154.8 6158.9 6160.6 6178.7 5005.5 5036.2 5039.1 5039.5 5091.9 4870.6 4992.9 5001.4 5014.6 5070.8 4966.9 4978.3 4986.6 4991.6 5011.5 4950.7 4960.9 4969.0 4988.4 5008.3 4465.2 4526.7 4539.5 4543.1 4562.7 6016.6 6045.5 6049.4 6091.3 6116.8 5732.9 5740.1 5750.2 5810.1 5812.7 6545.8 6553.3 6563.2 6579.8 6610.3 4849.1 5029.0 5059.6 5094.7 5112.7 6591.4 6677.0 6694.3 6716.8 6792.6 5214.7 5226.6 5238.3 5241.8 5248.4 5292.1 5309.7 5314.5 5317.8 5326.9 5774.6 5792.4 5822.2 5840.9 5847.2 6493.8 6538.2 6540.6 6596.7 6626.7 1795.2 1803.2 1803.6 1817.7 1829.1 1260.8 1313.4 1315.7 1322.2 1331.8 5859.4 5879.3 5959.2 5988.9 6030.1 6364.2 6428.7 6451.4 6475.7 6530.4 5838.9 5860.0 5866.3 5907.9 6002.6 3398.5 3442.2 3457.0 3484.0 3485.4 6690.9 6714.6 6718.8 6781.2 6832.4 6635.3 6649.6 6692.4 6737.8 6805.9 6673.5 6698.6 6795.6 6822.5 6874.3 6399.0 6442.3 6454.9 6512.9 6519.8 6539.4 6549.6 6571.6 6575.3 6610.1 6720.9 6759.4 6811.8 6818.9 6866.2 5159.9 5172.5 5179.7 5186.5 5234.2 6358.2 6455.8 6535.6 6563.6 6604.6 6183.5 6439.8 6478.2 6497.3 6498.8 6303.6 6337.2 6404.3 6432.4 6457.0 6161.3 6174.7 6203.7 6233.6 6321.2 6380.9 6407.5 6414.5 6502.3 6508.6 6192.1 6227.8 6293.4 6330.7 6339.1 15:14:49 INFO - PID 3902 | 15:14:49 INFO - PID 3902 | [#5] yui.html Cycles:180 Average:29931.61 Median:18778.08 stddev:45521.93 (242.4%) stddev-sans-first:45597.69 15:14:49 INFO - PID 3902 | Values: 969.1 986.8 988.1 992.0 1006.3 20753.9 21237.2 21355.1 21359.7 21383.4 18621.9 18704.7 18763.2 18773.5 18775.9 1057.3 1067.4 1082.6 1085.6 1088.3 1084.5 1084.9 1087.7 1087.7 1091.1 1085.7 1127.5 1129.8 1130.5 1131.9 1119.1 1120.2 1125.0 1127.7 1129.2 792.6 822.6 828.6 828.6 828.9 19853.0 19933.0 19964.0 20053.7 20143.1 23509.6 23688.9 24434.6 24616.1 24637.5 18840.2 18888.5 18988.5 19122.0 19131.6 4064.1 4083.6 4086.2 4086.2 4091.9 147353.0 171503.0 172322.0 172960.0 175292.0 19702.1 19724.5 19811.8 19851.9 19966.5 34490.3 36015.0 36084.8 36180.3 36237.5 10029.7 10112.2 10127.2 10127.3 10201.8 4784.2 4858.2 4859.1 4862.1 4874.0 6188.0 6197.2 6258.0 6329.8 6374.8 1198.9 1208.9 1231.0 1234.8 1236.3 727.8 730.9 754.1 754.5 760.9 13499.3 13742.5 13884.4 14281.8 14499.9 742.1 743.4 747.1 747.3 753.0 6171.9 6225.1 6242.5 6272.6 6370.2 162446.0 162574.0 163136.0 167010.2 167834.0 141826.0 142482.3 143951.0 144253.0 148040.0 118192.4 119579.4 120607.4 120698.4 123745.4 11685.4 11862.2 11889.0 11910.0 11935.2 57033.7 57856.7 58043.1 58093.4 59583.8 74434.0 74953.6 75436.6 78309.0 79228.2 17737.6 17754.0 17853.9 18040.8 18053.0 19358.9 19489.7 19575.7 19684.8 19693.7 17828.0 18063.6 18278.6 18486.3 18523.4 18750.5 18877.8 18902.5 18929.8 19087.5 18516.3 18592.4 18672.3 18700.7 18780.2 13428.3 13658.9 13677.4 13835.3 13910.1 13575.9 13741.6 13840.0 13841.9 13846.7 15:14:49 INFO - PID 3902 | -------- Summary: end -------- 15:14:49 INFO - PID 3902 | 15:14:49 INFO - TEST-INFO | 3902: exit 0 15:14:49 INFO - TEST-OK | dromaeo_css | took 1110015ms 15:14:49 INFO - TEST-START | kraken 15:14:49 INFO - Initialising browser for kraken test... 15:14:49 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:55543/getInfo.html -profile /tmp/tmp4MrTtB/profile 15:14:49 INFO - TEST-INFO | started process 4321 (/builds/slave/test/build/application/firefox/firefox http://localhost:55543/getInfo.html) 15:14:55 INFO - TEST-INFO | 4321: exit 0 15:14:55 INFO - Browser initialized. 15:14:55 INFO - Running cycle 1/1 for kraken test... 15:14:55 INFO - Using env: {'DISPLAY': ':0', 15:14:55 INFO - 'HOME': '/home/cltbld', 15:14:55 INFO - 'JSGC_DISABLE_POISONING': '1', 15:14:55 INFO - 'LANG': 'en_US.UTF-8', 15:14:55 INFO - 'LANGUAGE': 'en_US:en', 15:14:55 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 15:14:55 INFO - 'LOGNAME': 'cltbld', 15:14:55 INFO - 'MAIL': '/var/mail/cltbld', 15:14:55 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:14:55 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 15:14:55 INFO - 'MOZ_CRASHREPORTER': '1', 15:14:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:14:55 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 15:14:55 INFO - 'MOZ_NO_REMOTE': '1', 15:14:55 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:14:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:14:55 INFO - 'NO_EM_RESTART': '1', 15:14:55 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:14:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:14:55 INFO - 'PWD': '/builds/slave/test', 15:14:55 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:14:55 INFO - 'RUST_BACKTRACE': 'full', 15:14:55 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 15:14:55 INFO - 'SHELL': '/bin/bash', 15:14:55 INFO - 'SHLVL': '1', 15:14:55 INFO - 'TERM': 'linux', 15:14:55 INFO - 'TMOUT': '86400', 15:14:55 INFO - 'USER': 'cltbld', 15:14:55 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1507066478.500385-18170245', 15:14:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:14:55 INFO - '_': '/tools/buildbot/bin/python'} 15:14:55 INFO - TEST-INFO | started process 4596 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp4MrTtB/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 15:14:56 INFO - PID 4596 | 15:14:56 INFO - PID 4596 | (/builds/slave/test/build/application/firefox/firefox:4646): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 15:14:56 INFO - PID 4596 | 15:15:06 INFO - PID 4596 | RSS: Main: 146243584 15:15:06 INFO - PID 4596 | 15:15:09 INFO - PID 4596 | Cycle 1(1): loaded http://localhost:55543/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:55543/tests/kraken/driver.html?testName=audio-beat-detection) 15:15:09 INFO - PID 4596 | RSS: Main: 151760896 15:15:09 INFO - PID 4596 | 15:15:12 INFO - PID 4596 | Cycle 1(1): loaded http://localhost:55543/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:55543/tests/kraken/driver.html?testName=audio-dft) 15:15:13 INFO - PID 4596 | RSS: Main: 151773184 15:15:13 INFO - PID 4596 | 15:15:16 INFO - PID 4596 | Cycle 1(1): loaded http://localhost:55543/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:55543/tests/kraken/driver.html?testName=audio-fft) 15:15:16 INFO - PID 4596 | RSS: Main: 148426752 15:15:16 INFO - PID 4596 | 15:15:18 INFO - PID 4596 | Cycle 1(1): loaded http://localhost:55543/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:55543/tests/kraken/driver.html?testName=audio-oscillator) 15:15:19 INFO - PID 4596 | RSS: Main: 149381120 15:15:19 INFO - PID 4596 | 15:15:21 INFO - PID 4596 | Cycle 1(1): loaded http://localhost:55543/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:55543/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:15:22 INFO - PID 4596 | RSS: Main: 149364736 15:15:22 INFO - PID 4596 | 15:15:25 INFO - PID 4596 | Cycle 1(1): loaded http://localhost:55543/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:55543/tests/kraken/driver.html?testName=imaging-darkroom) 15:15:25 INFO - PID 4596 | RSS: Main: 149737472 15:15:25 INFO - PID 4596 | 15:15:29 INFO - PID 4596 | Cycle 1(1): loaded http://localhost:55543/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:55543/tests/kraken/driver.html?testName=imaging-desaturate) 15:15:29 INFO - PID 4596 | RSS: Main: 151920640 15:15:29 INFO - PID 4596 | 15:15:32 INFO - PID 4596 | Cycle 1(1): loaded http://localhost:55543/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:55543/tests/kraken/driver.html?testName=json-parse-financial) 15:15:33 INFO - PID 4596 | RSS: Main: 152346624 15:15:33 INFO - PID 4596 | 15:15:34 INFO - PID 4596 | Cycle 1(1): loaded http://localhost:55543/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:55543/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:15:35 INFO - PID 4596 | RSS: Main: 151453696 15:15:35 INFO - PID 4596 | 15:15:37 INFO - PID 4596 | Cycle 1(1): loaded http://localhost:55543/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:55543/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:15:37 INFO - PID 4596 | RSS: Main: 152653824 15:15:37 INFO - PID 4596 | 15:15:39 INFO - PID 4596 | Cycle 1(1): loaded http://localhost:55543/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:55543/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:15:40 INFO - PID 4596 | RSS: Main: 148713472 15:15:40 INFO - PID 4596 | 15:15:42 INFO - PID 4596 | Cycle 1(1): loaded http://localhost:55543/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:55543/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:15:43 INFO - PID 4596 | RSS: Main: 149516288 15:15:43 INFO - PID 4596 | 15:15:46 INFO - PID 4596 | Cycle 1(1): loaded http://localhost:55543/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:55543/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:15:46 INFO - PID 4596 | RSS: Main: 149065728 15:15:46 INFO - PID 4596 | 15:15:48 INFO - PID 4596 | Cycle 1(1): loaded http://localhost:55543/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:55543/tests/kraken/driver.html?testName=ai-astar) 15:15:48 INFO - PID 4596 | RSS: Main: 151220224 15:15:48 INFO - PID 4596 | 15:15:48 INFO - PID 4596 | __start_tp_report 15:15:48 INFO - PID 4596 | _x_x_mozilla_page_load 15:15:48 INFO - PID 4596 | _x_x_mozilla_page_load_details 15:15:48 INFO - PID 4596 | |i|pagename|runs| 15:15:48 INFO - PID 4596 | |0;ai-astar;106;105;104;106;106;109;108;105;107;106 15:15:48 INFO - PID 4596 | |1;audio-beat-detection;113;114;114;113;143;111;141;182;115;117 15:15:48 INFO - PID 4596 | |2;audio-dft;164;162;162;161;164;161;161;163;168;172 15:15:48 INFO - PID 4596 | |3;audio-fft;75;73;74;73;73;73;72;73;74;81 15:15:48 INFO - PID 4596 | |4;audio-oscillator;78;78;78;78;77;77;77;77;77;78 15:15:48 INFO - PID 4596 | |5;imaging-gaussian-blur;116;115;113;115;115;115;113;116;113;114 15:15:48 INFO - PID 4596 | |6;imaging-darkroom;149;150;149;151;150;152;149;151;150;151 15:15:48 INFO - PID 4596 | |7;imaging-desaturate;95;94;94;95;96;95;95;95;94;94 15:15:48 INFO - PID 4596 | |8;json-parse-financial;43;43;47;46;44;45;45;43;41;41 15:15:48 INFO - PID 4596 | |9;json-stringify-tinderbox;36;36;36;37;37;36;37;36;37;36 15:15:48 INFO - PID 4596 | |10;stanford-crypto-aes;71;70;69;70;69;71;70;69;70;71 15:15:48 INFO - PID 4596 | |11;stanford-crypto-ccm;103;108;108;114;103;103;104;108;104;113 15:15:48 INFO - PID 4596 | |12;stanford-crypto-pbkdf2;154;147;151;160;151;158;148;148;143;150 15:15:48 INFO - PID 4596 | |13;stanford-crypto-sha256-iterative;60;63;66;63;61;58;60;59;61;57 15:15:48 INFO - PID 4596 | __end_tp_report 15:15:48 INFO - PID 4596 | __start_cc_report 15:15:48 INFO - PID 4596 | _x_x_mozilla_cycle_collect,711 15:15:48 INFO - PID 4596 | __end_cc_report 15:15:48 INFO - PID 4596 | __startTimestamp1507068948622__endTimestamp 15:15:48 INFO - PID 4596 | 15:15:48 INFO - PID 4596 | ------- Summary: start ------- 15:15:48 INFO - PID 4596 | Number of tests: 14 15:15:48 INFO - PID 4596 | 15:15:48 INFO - PID 4596 | [#0] ai-astar Cycles:10 Average:106.20 Median:106.00 stddev:1.48 (1.4%) stddev-sans-first:1.56 15:15:48 INFO - PID 4596 | Values: 106.0 105.0 104.0 106.0 106.0 109.0 108.0 105.0 107.0 106.0 15:15:48 INFO - PID 4596 | 15:15:48 INFO - PID 4596 | [#1] audio-beat-detection Cycles:10 Average:126.30 Median:116.00 stddev:22.86 (19.7%) stddev-sans-first:23.73 15:15:48 INFO - PID 4596 | Values: 113.0 114.0 114.0 113.0 143.0 111.0 141.0 182.0 115.0 117.0 15:15:48 INFO - PID 4596 | 15:15:48 INFO - PID 4596 | [#2] audio-dft Cycles:10 Average:163.80 Median:163.50 stddev:3.58 (2.2%) stddev-sans-first:3.80 15:15:48 INFO - PID 4596 | Values: 164.0 162.0 162.0 161.0 164.0 161.0 161.0 163.0 168.0 172.0 15:15:48 INFO - PID 4596 | 15:15:48 INFO - PID 4596 | [#3] audio-fft Cycles:10 Average:74.10 Median:73.50 stddev:2.56 (3.5%) stddev-sans-first:2.69 15:15:48 INFO - PID 4596 | Values: 75.0 73.0 74.0 73.0 73.0 73.0 72.0 73.0 74.0 81.0 15:15:48 INFO - PID 4596 | 15:15:48 INFO - PID 4596 | [#4] audio-oscillator Cycles:10 Average:77.50 Median:78.00 stddev:0.53 (0.7%) stddev-sans-first:0.53 15:15:48 INFO - PID 4596 | Values: 78.0 78.0 78.0 78.0 77.0 77.0 77.0 77.0 77.0 78.0 15:15:48 INFO - PID 4596 | 15:15:48 INFO - PID 4596 | [#5] imaging-gaussian-blur Cycles:10 Average:114.50 Median:115.00 stddev:1.18 (1.0%) stddev-sans-first:1.12 15:15:48 INFO - PID 4596 | Values: 116.0 115.0 113.0 115.0 115.0 115.0 113.0 116.0 113.0 114.0 15:15:48 INFO - PID 4596 | 15:15:48 INFO - PID 4596 | [#6] imaging-darkroom Cycles:10 Average:150.20 Median:150.50 stddev:1.03 (0.7%) stddev-sans-first:1.00 15:15:48 INFO - PID 4596 | Values: 149.0 150.0 149.0 151.0 150.0 152.0 149.0 151.0 150.0 151.0 15:15:48 INFO - PID 4596 | 15:15:48 INFO - PID 4596 | [#7] imaging-desaturate Cycles:10 Average:94.70 Median:95.00 stddev:0.67 (0.7%) stddev-sans-first:0.71 15:15:48 INFO - PID 4596 | Values: 95.0 94.0 94.0 95.0 96.0 95.0 95.0 95.0 94.0 94.0 15:15:48 INFO - PID 4596 | 15:15:48 INFO - PID 4596 | [#8] json-parse-financial Cycles:10 Average:43.80 Median:44.50 stddev:1.99 (4.5%) stddev-sans-first:2.09 15:15:48 INFO - PID 4596 | Values: 43.0 43.0 47.0 46.0 44.0 45.0 45.0 43.0 41.0 41.0 15:15:48 INFO - PID 4596 | 15:15:48 INFO - PID 4596 | [#9] json-stringify-tinderbox Cycles:10 Average:36.40 Median:36.50 stddev:0.52 (1.4%) stddev-sans-first:0.53 15:15:48 INFO - PID 4596 | Values: 36.0 36.0 36.0 37.0 37.0 36.0 37.0 36.0 37.0 36.0 15:15:48 INFO - PID 4596 | 15:15:48 INFO - PID 4596 | [#10] stanford-crypto-aes Cycles:10 Average:70.00 Median:70.00 stddev:0.82 (1.2%) stddev-sans-first:0.78 15:15:48 INFO - PID 4596 | Values: 71.0 70.0 69.0 70.0 69.0 71.0 70.0 69.0 70.0 71.0 15:15:48 INFO - PID 4596 | 15:15:48 INFO - PID 4596 | [#11] stanford-crypto-ccm Cycles:10 Average:106.80 Median:108.00 stddev:4.13 (3.8%) stddev-sans-first:4.15 15:15:48 INFO - PID 4596 | Values: 103.0 108.0 108.0 114.0 103.0 103.0 104.0 108.0 104.0 113.0 15:15:48 INFO - PID 4596 | 15:15:48 INFO - PID 4596 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:151.00 Median:151.00 stddev:5.14 (3.4%) stddev-sans-first:5.34 15:15:48 INFO - PID 4596 | Values: 154.0 147.0 151.0 160.0 151.0 158.0 148.0 148.0 143.0 150.0 15:15:48 INFO - PID 4596 | 15:15:48 INFO - PID 4596 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:60.80 Median:61.00 stddev:2.66 (4.4%) stddev-sans-first:2.80 15:15:48 INFO - PID 4596 | Values: 60.0 63.0 66.0 63.0 61.0 58.0 60.0 59.0 61.0 57.0 15:15:48 INFO - PID 4596 | -------- Summary: end -------- 15:15:48 INFO - PID 4596 | 15:15:48 INFO - PID 4596 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 15:15:49 INFO - TEST-INFO | 4596: exit 0 15:15:49 INFO - TEST-OK | kraken | took 59768ms 15:15:49 INFO - SUITE-END | took 1169s 15:15:49 INFO - Completed test suite (00:19:30) 15:15:49 INFO - javascript benchmark 15:15:49 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 9217.008706069017, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3889.669378102861, 3892.260470510601, 3899.3760998240286, 3907.492026036615, 3912.061105334719, 6095.268567771874, 6111.694415279229, 6260.499160067195, 6264.84337891553, 6325.9051114233325, 6274.215723034621, 6290.465310448617, 6343.143675603792, 6354.523410744189, 6406.263279722822, 5575.665460072409, 5703.74333155009, 5710.029295019835, 5720.485156335929, 5728.0, 5488.1493368527945, 5598.944010559903, 5641.097424412095, 5678.659280443166, 5777.884442311151, 5643.717814109299, 5653.547716182737, 5718.142278658234, 5736.2256095427, 5737.598368114233, 5643.943560564424, 5663.9716801415725, 5709.571782116316, 5735.627184233012, 5741.770329186827, 5577.024020796344, 5651.7456714447535, 5701.743421546041, 5712.942870571283, 5716.8856622867725, 6159.26088869334, 6190.84522886927, 6197.752089916398, 6249.968755155394, 6311.495080393557, 6749.696263668097, 6808.761693340709, 6819.454443644497, 6831.521793474459, 6842.965785171092, 6782.0, 6796.184457865088, 6797.150356205474, 6882.0, 6887.793366199022, 6206.06908963659, 6306.400891915307, 6306.905396419057, 6360.236771587439, 6414.069959855888, 6969.4424446044195, 7028.191757947901, 7033.296670332926, 7057.329553692391, 7075.893861592081, 6677.866442671119, 6682.231543372477, 6705.195376554844, 6798.694058767367, 6805.149356330458, 6686.598804071771, 6772.424343930821, 6815.454763618898, 6877.037214789933, 6891.862162756717, 6692.330766923269, 6732.663366831639, 6779.0, 6781.932180678229, 6798.184217893884, 6683.465322774166, 6719.0, 6775.762848300285, 6790.320967903269, 6793.45652347811, 5022.673526220857, 5023.095842748267, 5035.16919708244, 5038.395392552844, 5042.369703787027, 4536.4102666653125, 4545.159145558082, 4553.977230113828, 4565.383673204141, 4567.383403240587, 6224.06639004153, 6270.0908368287255, 6274.686265686789, 6288.8427789304715, 6291.1192433059405, 6752.189737231563, 6772.051912732221, 6848.760293389708, 6850.4862135340045, 6864.862702745917, 6099.359567245464, 6148.631082135086, 6179.660118693425, 6276.372362763687, 6288.9056664150075, 5445.727713614304, 5501.807436739775, 5564.944350556523, 5573.860653483695, 5583.609147359727, 7121.89317160243, 7130.750423735144, 7133.964330178316, 7141.9642901785155, 7145.964270178616, 6739.966300168468, 6746.932530674827, 6757.560758550679, 6810.659467026728, 6821.4542836572955, 6796.048553202654, 6922.480813938874, 7034.331738484836, 7119.8932016020835, 7131.286871313035, 6689.732410703712, 6749.088873002278, 6749.5612785168805, 6755.189377274562, 6777.695003724943, 6628.635425051669, 6755.05429239897, 6799.796006119825, 6802.557833740791, 6807.047013418027, 6499.447546958561, 6512.772052978123, 6581.901271481028, 6584.209894812653, 6600.042993765973, 6491.772787952399, 6514.218293804774, 6589.044588534732, 6632.0, 6640.037194606852, 6385.297617262005, 6455.935440645721, 6471.0, 6473.190851143607, 6474.643894585654, 6469.611823290617, 6553.2136143663065, 6617.040529123154, 6633.1376920999965, 6641.269460359454, 6331.746730130743, 6348.269948955744, 6392.169018027626, 6408.263049749152, 6440.67796610177], "value": 6208.762488827257, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [987.4963768478076, 1012.0335079998603, 1012.3520946594181, 1014.6144465103268, 1017.8218811707949, 41029.17941641165, 45946.54053459464, 46177.769111154485, 46184.307235391454, 46311.0, 42613.57386426142, 42921.356179657254, 42969.14061718772, 43042.78478607602, 43049.7847510762, 7808.687652493908, 7919.049714034324, 7935.801604959865, 7937.206279372045, 7960.084590272106, 7759.301662850342, 7862.567558784264, 7864.488808227476, 7873.763787086396, 7874.0, 7807.609619519029, 7854.253845884632, 7878.094019187781, 8314.70898518552, 8592.05487396386, 8435.578221088952, 8526.275266602346, 8535.189157030072, 8536.829263414758, 8546.40175187737, 8285.088640249567, 8334.66661333546, 8441.282490988273, 8461.576921153917, 8522.659093636246, 41197.176056479, 41231.0, 41395.1720965579, 41538.37692434586, 41697.166056679, 3738.495303134069, 3793.336166170909, 3798.0504873781556, 3799.031247031989, 3801.543814742221, 33895.661043389744, 33918.660813392045, 33963.66036339654, 34159.82920085409, 34297.65702342994, 5599.300087489064, 5627.296587926509, 5627.409122042168, 5628.943710562883, 5636.323641163087, 238238.0, 238619.0, 239087.0, 239384.0, 239600.0, 47269.5273047272, 47464.762676187085, 52873.735631322364, 53022.46977530175, 53087.73456132694, 55739.16391254134, 55814.44185558174, 55859.0, 55887.16169257464, 55958.160627590616, 17209.483715488554, 17220.569485762957, 17307.826921730873, 17310.82689173092, 17398.56503587395, 14005.649858753613, 14095.929520352538, 14114.50599229022, 14179.220142892034, 14185.858141418661, 14615.561533154023, 14750.0, 14839.480618178311, 14865.2567371631, 14935.10389376619, 5821.53427725781, 5902.547009999199, 5905.143754155623, 5956.374580669054, 6027.6684782336515, 1075.6665433715416, 1090.1278976818514, 1105.9170562207867, 1108.5233349659563, 1122.927009744364, 26230.475390492084, 26303.079392221563, 26307.473850523264, 26349.868250658623, 26511.86744066306, 1182.1429463638965, 1229.674136353867, 1239.7148655809035, 1243.6890777305673, 1245.495574292413, 13784.517541885985, 13914.721705566035, 13999.930000350138, 14057.156570605797, 14096.295185240902, 304947.0, 305085.0, 307063.0, 307206.0, 307817.0, 59403.40596594065, 59626.0, 59706.40293597009, 59895.401045989856, 59972.0, 1533.4786172701336, 1573.9213039347987, 1578.921053947298, 1583.1767480909862, 1609.7343938250292, 25825.870870645525, 25990.740092598833, 26133.869330653986, 26144.60783088293, 26328.21015369542, 42195.0, 42287.365689515296, 42328.36507452329, 42343.5765642352, 42415.36376954286, 248186.0, 248208.0, 248433.0, 248629.0, 250742.0, 33833.0, 35708.28583428369, 41637.58362416337, 44042.779786100866, 44750.77624611856, 55809.441905580425, 55827.72086139706, 55964.44035559592, 56621.0, 56799.43200567942, 39245.2150956985, 39343.22955467068, 39462.0, 39498.0, 39682.603173969044, 40284.79857600811, 40529.0, 40573.79713101534, 40822.38766418447, 40891.38662919999, 47915.520844791106, 47931.760341199464, 48478.5152148474, 49261.0, 49361.0, 38948.41577376285, 39653.206935860544, 39807.60192398155, 40317.39523907202, 40422.0, 28052.298692532033, 28393.43213135767, 28657.570136447554, 28863.278418039718, 29044.27389315284], "value": 19426.242730436425, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [617.1606615003594, 619.3651507205111, 622.9221347331584, 649.3895738006274, 653.5196630476601, 23795.167169149085, 24158.0, 24322.75677243227, 24546.3863403415, 24645.38386540335, 26547.336316592096, 27172.864135679294, 27221.45557088857, 27226.0, 27278.863605681992, 6755.054292399068, 6863.0, 6873.243943166248, 6926.757563485279, 6928.376446119848, 6704.161979752531, 6944.548604340727, 6947.826304342381, 6963.721451141948, 6966.965165174167, 6931.618760968145, 6936.445084393236, 6944.236134025253, 6945.652717364137, 6947.930520694779, 6750.729970801162, 6926.168859736838, 7001.0, 7006.929930700704, 7009.684564194624, 6516.3157868423605, 6520.869582608369, 6527.445167160797, 6538.248101468344, 6551.93448065518, 30390.0, 30704.2323941901, 30800.537991930138, 30888.845555772303, 30912.53631195534, 2317.4901521665206, 2324.9186278480342, 2328.2200462844967, 2339.3683705399617, 2344.859308441499, 19899.303524376577, 20830.687539686918, 20912.0, 20950.266740664003, 20996.895015524977, 2570.974290257111, 2585.0564543941514, 2593.1701855406277, 2618.122928818848, 2623.081921327539, 141293.0, 147845.0, 166900.16549917293, 167115.0, 174713.0, 15845.445409410615, 16708.6658266834, 16812.663746724997, 16874.662506749795, 17045.14774261282, 26827.195184144508, 26866.462670746867, 26889.86555067251, 26903.0, 26962.056328028426, 10503.369797812156, 10722.73193170177, 10757.731056723487, 10833.83749243762, 10960.0, 9616.374935629161, 9685.564149613285, 9843.507824608741, 9913.603455861685, 9930.113278477067, 9924.900750992398, 9956.601735930482, 10087.193024558017, 10162.593496260066, 10192.694219173436, 2828.575713642929, 2875.2093174377214, 3045.7867949243782, 3051.0, 3075.7231849133686, 1052.7420781908481, 1064.7817197474653, 1066.088494337346, 1072.5495291977388, 1073.4042606353394, 20781.896090519753, 21239.89380053121, 21318.467038324165, 21360.0, 21906.014229359716, 1133.0935251798528, 1133.9149563782748, 1148.6955956671488, 1150.896419322265, 1177.4230626992883, 11425.31448113116, 11682.299062056303, 11828.88171118295, 11856.762864742657, 11925.224860384047, 628873.0, 640381.0, 640520.0, 647042.0, 658901.0, 136026.31986840002, 138585.30707346398, 138633.30683346518, 139821.30089349693, 141776.0, 1141.5547936304847, 1199.6161228407, 1251.4055823483955, 1256.2650849253107, 1284.9807252891028, 21533.106952484628, 21658.241961531272, 21887.67168492506, 22134.35187586035, 22320.21879234219, 65528.0, 66520.68320261224, 67392.0, 68461.0, 68629.0, 82499.5875020621, 83412.58293708492, 83850.16149838667, 84534.0, 84621.0, 15672.138032408327, 15691.215439228221, 15707.0, 15754.921225394259, 15972.440964566184, 24101.879490603136, 24296.0, 24372.26883193507, 24513.142040028513, 24791.752082478943, 18860.339888103852, 19472.123754431366, 19710.310139145062, 19987.800121998593, 20044.29844955478, 17042.65914681724, 17496.12519374001, 17613.11934403249, 17751.46745597634, 18007.459776206735, 19031.71452428187, 19931.501712457306, 20372.0, 20378.898105509972, 20441.0, 15361.769573456631, 15473.303701333241, 15664.138472383673, 15694.9215253923, 15708.214589270263, 19359.612807744048, 19402.4179274622, 19466.61066778685, 19513.804861951765, 19514.414567562995], "value": 13131.242582020572, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1490.1506141499344, 1520.9695806083878, 1529.5793656744402, 1547.6749882524673, 1564.8435156484345, 16761.66476670466, 17483.12584370783, 17488.3004679813, 17507.299708011695, 17519.124043797823, 16951.57621059474, 17042.48872533823, 17147.314107435686, 17152.571185720364, 17182.74225886613, 2784.860756962154, 2803.649543807019, 2863.355744957378, 2863.4416288823686, 2863.914082577526, 2770.4320614274025, 2813.08574713219, 2857.671367792699, 2865.5988161657383, 2869.3257084585107, 2783.8747256373513, 2880.9423811523757, 2882.9423411531757, 2885.0623547347195, 2892.811967222134, 2828.2081017315186, 2862.6421697287838, 2873.841938693371, 2878.510653188963, 2926.561015847619, 2648.139354709727, 2696.3933115049153, 2713.1589207345587, 2717.8097533172686, 2719.9184024479296, 16483.6703265934, 16689.0, 16723.91638041814, 16748.58128546796, 16771.2452939618, 8731.60707768152, 9013.143751343612, 9015.098490150998, 9015.954920225422, 9023.458592484407, 5540.113581826889, 5700.429957004308, 5726.742296596663, 5779.133130030487, 5826.854328641775, 2133.626615342309, 2137.722096127509, 2177.0638625391075, 2211.8562293450873, 2227.365200917731, 31607.84196079005, 31979.200519986953, 32178.195545111324, 32292.19269518281, 33066.66933330684, 7214.49498535108, 7281.5995120267835, 7343.3390994810725, 7603.163651998276, 7691.5385076894445, 20480.0, 20555.38333849987, 20642.58714825695, 20795.376138715863, 20829.270975516087, 8425.241728244488, 8472.872906906401, 8517.148285171434, 8520.19058189471, 8564.314854811724, 7759.922400776033, 7807.219278072261, 7865.960670196612, 7939.523628582304, 8153.714619988272, 5602.1036634138345, 5788.768449261982, 5826.096954971986, 5892.823215303548, 5910.261217347832, 1721.4491362764045, 1752.9386471473692, 1763.9823601764074, 1778.7420823980708, 1785.61609254011, 991.1674193677202, 1003.8042581696645, 1004.9648262310784, 1033.256055639953, 1041.953112109957, 8699.13008699125, 8891.19979201875, 8895.688650897187, 8950.328725345626, 9040.367174297868, 795.1253621016835, 803.2971150243537, 804.1355542791523, 817.4468509831249, 818.3453237410167, 4792.113459010095, 4995.300657907896, 5389.7035663038905, 5451.836444906659, 5509.807156749494, 24332.9477924461, 25187.87406062958, 25398.238052858444, 26036.0, 26389.208323750318, 25261.0, 25368.619470707956, 25502.23493295204, 26151.869240653676, 26182.08362707296, 10318.690439286833, 11660.941695291638, 12718.427670754838, 12857.485700571873, 13098.803517947239, 10178.491075446345, 10478.528466219192, 10499.68500944973, 10513.369197848153, 10754.300970436896, 21944.231951881608, 21979.560408792055, 21994.560108798054, 22523.32430027102, 22771.658425123303, 22187.88906055524, 22460.66309005399, 22897.198598048988, 23017.0, 23161.76838231596, 12693.555725549932, 13138.540151094665, 13294.734105318033, 13304.600861974157, 13313.46746130144, 15404.306806193525, 15647.374105035671, 15724.135172565619, 15791.28939197716, 15829.604259893595, 12611.621651350475, 12817.871821281668, 13136.211827290394, 13186.868131318564, 13232.735345293233, 12162.087843411957, 12283.877161228631, 12619.305938173122, 12665.240085594894, 12737.617871463872, 10733.838992414963, 10754.623588174376, 10785.892141078803, 10819.080378167944, 10927.45362731876, 7944.44388892772, 8134.186581341818, 8249.05135909378, 8278.213569710868, 8302.045264794626, 8267.83464330698, 8313.12712165214, 8332.750017499484, 8357.206065423776, 8434.95782521108], "value": 7407.853628958747, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [538.6498775795732, 562.7298896529666, 568.3605943313772, 578.9189513468115, 590.4479311843427, 6136.251168417002, 6137.907931381022, 6154.69226538673, 6198.729260501597, 6205.720742566584, 6084.543659225565, 6154.81535553933, 6158.907616385758, 6160.597367026863, 6178.660173690445, 5005.499450054984, 5036.219385995177, 5039.09296326661, 5039.546440820325, 5091.872703182432, 4870.561649451549, 4992.925106123411, 5001.399832020163, 5014.573761230282, 5070.847874563769, 4966.925496117561, 4978.278149668296, 4986.601071914256, 4991.600671946236, 5011.523905229015, 4950.727709975949, 4960.925586116211, 4969.0, 4988.351514303117, 5008.348914641091, 4465.218586747306, 4526.687260694394, 4539.54734484965, 4543.091381723635, 4562.726236425792, 6016.608920420159, 6045.45590896817, 6049.425304596057, 6091.2995005574485, 6116.847078823065, 5732.914006289909, 5740.052891272935, 5750.223719797816, 5810.099434587603, 5812.709364531756, 6545.836354091138, 6553.279139294674, 6563.245226798884, 6579.835504112388, 6610.338966103399, 4849.102915960559, 5029.0, 5059.6458247922665, 5094.7197904115255, 5112.667676601009, 6591.373819487189, 6676.998450232455, 6694.326418395402, 6716.83207919801, 6792.592444453299, 5214.687118772886, 5226.634135610547, 5238.345206849144, 5241.842744717665, 5248.396434410015, 5292.100342941709, 5309.7079660618265, 5314.548263397577, 5317.813876514303, 5326.866828329245, 5774.566907481955, 5792.391798861059, 5822.155787410802, 5840.912386314209, 5847.210626565445, 6493.837654058591, 6538.248101468297, 6540.640264785482, 6596.746618142538, 6626.734930602818, 1795.165248159612, 1803.2426380920044, 1803.603207294393, 1817.736428217901, 1829.0611951331632, 1260.8171815086944, 1313.4089659653193, 1315.6908126590245, 1322.1604281281263, 1331.8401791784854, 5859.414058594107, 5879.2944846618675, 5959.165716799652, 5988.850278743066, 6030.095485677096, 6364.172657554581, 6428.6785660717715, 6451.387118223761, 6475.701126100261, 6530.444912182517, 5838.9124163137585, 5859.970700146472, 5866.298773355765, 5907.852303692442, 6002.61939753859, 3398.5411969383817, 3442.225499262646, 3457.0, 3484.0244731475227, 3485.390056740111, 6690.933090669128, 6714.563553369016, 6718.798436046926, 6781.220159681699, 6832.350926661959, 6635.303293154149, 6649.60102393858, 6692.431143352772, 6737.764178253738, 6805.863882722417, 6673.496414382488, 6698.5980841149685, 6795.558288711218, 6822.522423430312, 6874.312568743086, 6399.0, 6442.291347951628, 6454.870902582016, 6512.902306465501, 6519.771807986698, 6539.378759017885, 6549.639769812516, 6571.638559879252, 6575.309592492719, 6610.140681711347, 6720.932790672031, 6759.357861003196, 6811.761588344582, 6818.943063825115, 6866.210385805696, 5159.948400515947, 5172.456892026434, 5179.741012949413, 5186.533212010937, 5234.214867769865, 6358.205224346957, 6455.7740479083, 6535.57518761279, 6563.571478552966, 6604.603723776589, 6183.505319574333, 6439.839004024937, 6478.218489915311, 6497.25281592604, 6498.772542961163, 6303.590266632654, 6337.176167098248, 6404.295527491879, 6432.35676432353, 6456.967715161394, 6161.336439161311, 6174.722137503733, 6203.65879876593, 6233.563650544418, 6321.1782468278825, 6380.9042864358, 6407.475888261531, 6414.5189110815945, 6502.317256687979, 6508.576942498722, 6192.142357292704, 6227.750889964351, 6293.433590976834, 6330.715117819801, 6339.080833279241], "value": 5093.9513395068225, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [969.0793745941353, 986.8125056239314, 988.1008282462959, 992.0377234082941, 1006.3106771861271, 20753.896230518843, 21237.150513979443, 21355.145794168253, 21359.67960480594, 21383.35849924501, 18621.90689046553, 18704.71942920854, 18763.249470021183, 18773.530661733428, 18775.90612046938, 1057.2599180573573, 1067.3542506783401, 1082.6427278997912, 1085.6091806949491, 1088.3198001249218, 1084.5444913136503, 1084.9457527123611, 1087.6845714742715, 1087.6900083476212, 1091.0834898685102, 1085.696005118564, 1127.4644543841691, 1129.8418221449003, 1130.530829705671, 1131.9377434241114, 1119.11030730569, 1120.2270433400927, 1124.983125253122, 1127.6786115956954, 1129.1926272714966, 792.6195426195401, 822.5599304372132, 828.556722153651, 828.5857071464268, 828.9046759622661, 19853.0, 19933.0, 19964.0, 20053.699194512094, 20143.093560789654, 23509.647355289686, 23688.881555592285, 24434.633480497807, 24616.138435154684, 24637.5072498549, 18840.152193151203, 18888.544489216452, 18988.5252868678, 19122.0, 19131.61736765257, 4064.126212864219, 4083.591640835922, 4086.162336720965, 4086.2031903778775, 4091.8747344480207, 147353.0, 171503.0, 172322.0, 172960.0, 175292.0, 19702.11340489653, 19724.506887327643, 19811.801881980995, 19851.900740496494, 19966.50083747918, 34490.31019379598, 36015.0, 36084.81957590195, 36180.27639447196, 36237.45643815345, 10029.69910902674, 10112.191024718006, 10127.240456965757, 10127.341722787995, 10201.795964080826, 4784.162771514971, 4858.222684370484, 4859.1010663027455, 4862.100511405401, 4873.975630121827, 6188.009918413032, 6197.163382943245, 6258.0, 6329.810105696836, 6374.776882809172, 1198.8670706182575, 1208.8904861017581, 1230.9815352769715, 1234.7715672600598, 1236.3323805145121, 727.8435136445689, 730.9122905251404, 754.1478129713498, 754.4567911103996, 760.8706519891632, 13499.325033748273, 13742.450301987808, 13884.375203115884, 14281.78577321341, 14499.92750036243, 742.0909386002212, 743.4089898530681, 747.0736287004075, 747.3274053352027, 753.0248328414716, 6171.907421388682, 6225.097360882647, 6242.531810114168, 6272.560920735505, 6370.235571731422, 162446.0, 162574.0, 163136.0, 167010.16494917448, 167834.0, 141826.0, 142482.2875885614, 143951.0, 144253.0, 148040.0, 118192.40903795598, 119579.40210298893, 120607.39696301287, 120698.39650801866, 123745.38127309305, 11685.357305348287, 11862.22895511789, 11889.0, 11909.975911289896, 11935.224210426295, 57033.714831425576, 57856.71071644615, 58043.12935306058, 58093.4190658088, 59583.843329252675, 74434.0, 74953.62523187349, 75436.62281688556, 78309.0, 79228.20771792208, 17737.645247095243, 17754.02352870553, 17853.910730446263, 18040.81959180444, 18053.0, 19358.903205483883, 19489.70765438491, 19575.70636440483, 19684.80315196887, 19693.704594431383, 17828.0, 18063.638727225643, 18278.634427311645, 18486.260549578405, 18523.44429667112, 18750.53123671919, 18877.811221887605, 18902.52743681419, 18929.810701892806, 19087.52281192981, 18516.2593496264, 18592.44222673322, 18672.25310987545, 18700.7194892074, 18780.248790048245, 13428.261445620585, 13658.863411366157, 13677.384517696528, 13835.308234588432, 13910.095843770527, 13575.86424135746, 13741.587752367444, 13840.0, 13841.86158138446, 13846.653833653836], "value": 10258.036521125638, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1376.1, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 105.0, 104.0, 106.0, 106.0, 109.0, 108.0, 105.0, 107.0, 106.0], "value": 106.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 114.0, 114.0, 113.0, 143.0, 111.0, 141.0, 182.0, 115.0, 117.0], "value": 126.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 162.0, 162.0, 161.0, 164.0, 161.0, 161.0, 163.0, 168.0, 172.0], "value": 163.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 73.0, 74.0, 73.0, 73.0, 73.0, 72.0, 73.0, 74.0, 81.0], "value": 74.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 78.0, 78.0, 78.0, 77.0, 77.0, 77.0, 77.0, 77.0, 78.0], "value": 77.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 115.0, 113.0, 115.0, 115.0, 115.0, 113.0, 116.0, 113.0, 114.0], "value": 114.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 150.0, 149.0, 151.0, 150.0, 152.0, 149.0, 151.0, 150.0, 151.0], "value": 150.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 94.0, 95.0, 96.0, 95.0, 95.0, 95.0, 94.0, 94.0], "value": 94.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [43.0, 43.0, 47.0, 46.0, 44.0, 45.0, 45.0, 43.0, 41.0, 41.0], "value": 43.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [36.0, 36.0, 36.0, 37.0, 37.0, 36.0, 37.0, 36.0, 37.0, 36.0], "value": 36.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 70.0, 69.0, 70.0, 69.0, 71.0, 70.0, 69.0, 70.0, 71.0], "value": 70.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 108.0, 108.0, 114.0, 103.0, 103.0, 104.0, 108.0, 104.0, 113.0], "value": 106.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 147.0, 151.0, 160.0, 151.0, 158.0, 148.0, 148.0, 143.0, 150.0], "value": 151.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 63.0, 66.0, 63.0, 61.0, 58.0, 60.0, 59.0, 61.0, 57.0], "value": 60.8, "unit": "score"}]}]} 15:15:49 INFO - Return code: 0 15:15:49 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 15:15:49 INFO - # TBPL SUCCESS # 15:15:49 INFO - Running post-action listener: _package_coverage_data 15:15:49 INFO - Running post-action listener: _resource_record_post_action 15:15:49 INFO - [mozharness: 2017-10-03 22:15:49.928364Z] Finished run-tests step (success) 15:15:49 INFO - Running post-run listener: _resource_record_post_run 15:15:50 INFO - Total resource usage - Wall time: 1188s; CPU: 13.0%; Read bytes: 9719808; Write bytes: 450129920; Read time: 2748; Write time: 317768 15:15:50 INFO - TinderboxPrint: CPU usage
13.1% 15:15:50 INFO - TinderboxPrint: I/O read bytes / time
9,719,808 / 2,748 15:15:50 INFO - TinderboxPrint: I/O write bytes / time
450,129,920 / 317,768 15:15:50 INFO - TinderboxPrint: CPU idle
8,230.6 (86.9%) 15:15:50 INFO - TinderboxPrint: CPU user
1,187.7 (12.5%) 15:15:50 INFO - TinderboxPrint: Swap in / out
0 / 0 15:15:50 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 128876544; Read time: 0; Write time: 101304 15:15:50 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 15:15:50 INFO - run-tests - Wall time: 1171s; CPU: 13.0%; Read bytes: 9719808; Write bytes: 321253376; Read time: 2748; Write time: 216464 15:15:50 INFO - Running post-run listener: _upload_blobber_files 15:15:50 INFO - Blob upload gear active. 15:15:50 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:15:50 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 15:15:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 15:15:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 15:15:50 INFO - (blobuploader) - INFO - Open directory for files ... 15:15:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 15:15:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:15:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:15:57 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 15:15:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:15:57 INFO - (blobuploader) - INFO - Done attempting. 15:15:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 15:15:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:15:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:16:12 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 15:16:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:16:12 INFO - (blobuploader) - INFO - Done attempting. 15:16:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 15:16:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:16:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:16:13 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 15:16:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:16:13 INFO - (blobuploader) - INFO - Done attempting. 15:16:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 15:16:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:16:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:16:14 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 15:16:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:16:14 INFO - (blobuploader) - INFO - Done attempting. 15:16:14 INFO - (blobuploader) - INFO - Iteration through files over. 15:16:14 INFO - Return code: 0 15:16:14 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:16:14 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:16:14 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dfba329cc2e85f20461c1e4e8b2e54cb4a7d828df896f43b7363ebd8fa935bfc2576d2cec2409014ab06c3d427e291f4c9c7b5fe1f6c40e66bed28e704050ce9", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/90ab17f626f6bea2fa23164915f2ef99c1d7e8bd1bba6ec92ae4ad0ebb7b2f18e9445f1c05278c52353577a885803acaaee21449e6dbe284d96565b9860ff07c", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/655f82786ec4770a5da65f85162a35ea240df5db20c5232fe10f53fa79bc0fac68a380134194891df724c1360d5bc367745f18c4a865b07b083cc431bab23a79", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b080e9e3430a490f6c10b5e8b5c162d58603bb5ffa290ebde51dc87790aedfcdfae962f1bdb8b21d76cc0c93383dd538862a51ed3fadafcb1150c7c388c9adb4"} 15:16:14 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:16:14 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:16:14 INFO - Contents: 15:16:14 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dfba329cc2e85f20461c1e4e8b2e54cb4a7d828df896f43b7363ebd8fa935bfc2576d2cec2409014ab06c3d427e291f4c9c7b5fe1f6c40e66bed28e704050ce9", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/90ab17f626f6bea2fa23164915f2ef99c1d7e8bd1bba6ec92ae4ad0ebb7b2f18e9445f1c05278c52353577a885803acaaee21449e6dbe284d96565b9860ff07c", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/655f82786ec4770a5da65f85162a35ea240df5db20c5232fe10f53fa79bc0fac68a380134194891df724c1360d5bc367745f18c4a865b07b083cc431bab23a79", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b080e9e3430a490f6c10b5e8b5c162d58603bb5ffa290ebde51dc87790aedfcdfae962f1bdb8b21d76cc0c93383dd538862a51ed3fadafcb1150c7c388c9adb4"} 15:16:14 INFO - Running post-run listener: copy_logs_to_upload_dir 15:16:14 INFO - Copying logs to upload dir... 15:16:14 INFO - mkdir: /builds/slave/test/build/upload/logs 15:16:14 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1254.396532 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 54 secs) (at 2017-10-03 15:16:14.269174) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-10-03 15:16:14.272245) ========= 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=cbe409c6de289db6ac707de3000001d4-1507066478.500385-18170245 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dfba329cc2e85f20461c1e4e8b2e54cb4a7d828df896f43b7363ebd8fa935bfc2576d2cec2409014ab06c3d427e291f4c9c7b5fe1f6c40e66bed28e704050ce9", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/90ab17f626f6bea2fa23164915f2ef99c1d7e8bd1bba6ec92ae4ad0ebb7b2f18e9445f1c05278c52353577a885803acaaee21449e6dbe284d96565b9860ff07c", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/655f82786ec4770a5da65f85162a35ea240df5db20c5232fe10f53fa79bc0fac68a380134194891df724c1360d5bc367745f18c4a865b07b083cc431bab23a79", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b080e9e3430a490f6c10b5e8b5c162d58603bb5ffa290ebde51dc87790aedfcdfae962f1bdb8b21d76cc0c93383dd538862a51ed3fadafcb1150c7c388c9adb4"} build_url:https://queue.taskcluster.net/v1/task/CDkVOCj0R22Mi5t4EKDL4Q/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011166 build_url: 'https://queue.taskcluster.net/v1/task/CDkVOCj0R22Mi5t4EKDL4Q/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dfba329cc2e85f20461c1e4e8b2e54cb4a7d828df896f43b7363ebd8fa935bfc2576d2cec2409014ab06c3d427e291f4c9c7b5fe1f6c40e66bed28e704050ce9", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/90ab17f626f6bea2fa23164915f2ef99c1d7e8bd1bba6ec92ae4ad0ebb7b2f18e9445f1c05278c52353577a885803acaaee21449e6dbe284d96565b9860ff07c", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/655f82786ec4770a5da65f85162a35ea240df5db20c5232fe10f53fa79bc0fac68a380134194891df724c1360d5bc367745f18c4a865b07b083cc431bab23a79", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b080e9e3430a490f6c10b5e8b5c162d58603bb5ffa290ebde51dc87790aedfcdfae962f1bdb8b21d76cc0c93383dd538862a51ed3fadafcb1150c7c388c9adb4"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-10-03 15:16:14.300692) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-10-03 15:16:14.301022) ========= 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=cbe409c6de289db6ac707de3000001d4-1507066478.500385-18170245 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004438 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-10-03 15:16:14.382775) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-10-03 15:16:14.383092) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-10-03 15:16:14.383393) ========= ========= Total master_lag: 0.22 =========