builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-068 starttime: 1506452520.73 results: success (0) revision: b30ae5a455367f336ded1052081e4c54a139fa81 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-26 12:02:00.731943) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-26 12:02:00.732429) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-26 12:02:00.732716) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-26 12:02:00.750392) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-26 12:02:00.750785) ========= 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=97facb8ba2ac28944cc45e6b000001d4-1506452519.880506-810758685 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-26 12:02:00-- 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% 68.4M=0s 2017-09-26 12:02:00 (68.4 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.104707 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-26 12:02:00.872295) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-26 12:02:00.872690) ========= 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=97facb8ba2ac28944cc45e6b000001d4-1506452519.880506-810758685 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016040 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-26 12:02:00.922714) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-26 12:02:00.923077) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev b30ae5a455367f336ded1052081e4c54a139fa81 --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 b30ae5a455367f336ded1052081e4c54a139fa81 --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=97facb8ba2ac28944cc45e6b000001d4-1506452519.880506-810758685 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-26 12:02:00,991 truncating revision to first 12 chars 2017-09-26 12:02:00,991 Setting DEBUG logging. 2017-09-26 12:02:00,991 attempt 1/10 2017-09-26 12:02:00,991 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/b30ae5a45536?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-26 12:02:01,356 unpacking tar archive at: mozilla-central-b30ae5a45536/testing/mozharness/ program finished with exit code 0 elapsedTime=0.661229 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-26 12:02:01.600615) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-26 12:02:01.601063) ========= script_repo_revision: b30ae5a455367f336ded1052081e4c54a139fa81 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-26 12:02:01.601478) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-26 12:02:01.601812) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-26 12:02:01.615615) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 35 secs) (at 2017-09-26 12:02:01.616216) ========= /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=97facb8ba2ac28944cc45e6b000001d4-1506452519.880506-810758685 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 12:02:01 INFO - MultiFileLogger online at 20170926 12:02:01 in /builds/slave/test 12:02:01 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 12:02:01 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:02:01 INFO - {'append_to_log': False, 12:02:01 INFO - 'base_work_dir': '/builds/slave/test', 12:02:01 INFO - 'blob_upload_branch': 'Firefox', 12:02:01 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:02:01 INFO - 'branch': 'Firefox', 12:02:01 INFO - 'buildbot_json_path': 'buildprops.json', 12:02:01 INFO - 'code_coverage': False, 12:02:01 INFO - 'config_files': ('talos/linux_config.py',), 12:02:01 INFO - 'default_actions': ('clobber', 12:02:01 INFO - 'read-buildbot-config', 12:02:01 INFO - 'download-and-extract', 12:02:01 INFO - 'populate-webroot', 12:02:01 INFO - 'create-virtualenv', 12:02:01 INFO - 'install', 12:02:01 INFO - 'setup-mitmproxy', 12:02:01 INFO - 'run-tests'), 12:02:01 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:02:01 INFO - 'disable_ccov_upload': False, 12:02:01 INFO - 'disable_stylo': False, 12:02:01 INFO - 'download_minidump_stackwalk': True, 12:02:01 INFO - 'download_symbols': 'ondemand', 12:02:01 INFO - 'enable_stylo': False, 12:02:01 INFO - 'enable_webrender': False, 12:02:01 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 12:02:01 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:02:01 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:02:01 INFO - 'gecko_profile': False, 12:02:01 INFO - 'gecko_profile_interval': 0, 12:02:01 INFO - 'installer_path': 'installer.exe', 12:02:01 INFO - 'log_level': 'info', 12:02:01 INFO - 'log_name': 'talos', 12:02:01 INFO - 'log_to_console': True, 12:02:01 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 12:02:01 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 12:02:01 INFO - 'opt_config_files': (), 12:02:01 INFO - 'pip_index': False, 12:02:01 INFO - 'suite': 'dromaeojs-e10s', 12:02:01 INFO - 'system_bits': '32', 12:02:01 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:02:01 INFO - 'title': 'talos-linux64-ix-068', 12:02:01 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:02:01 INFO - 'use_talos_json': True, 12:02:01 INFO - 'verify': 'False', 12:02:01 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:02:01 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:02:01 INFO - 'work_dir': 'build'} 12:02:01 INFO - [mozharness: 2017-09-26 19:02:01.817250Z] Running clobber step. 12:02:01 INFO - Running pre-action listener: _resource_record_pre_action 12:02:01 INFO - Running main action method: clobber 12:02:01 INFO - rmtree: /builds/slave/test/build 12:02:01 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:02:02 INFO - Running post-action listener: _resource_record_post_action 12:02:02 INFO - [mozharness: 2017-09-26 19:02:02.814558Z] Finished clobber step (success) 12:02:02 INFO - [mozharness: 2017-09-26 19:02:02.814656Z] Running read-buildbot-config step. 12:02:02 INFO - Running pre-action listener: _resource_record_pre_action 12:02:02 INFO - Running main action method: read_buildbot_config 12:02:02 INFO - Using buildbot properties: 12:02:02 INFO - { 12:02:02 INFO - "project": "", 12:02:02 INFO - "product": "firefox", 12:02:02 INFO - "who": "ffxbld@noreply.mozilla.org", 12:02:02 INFO - "installer_path": "public/build/target.tar.bz2", 12:02:02 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 12:02:02 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 12:02:02 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs-e10s", 12:02:02 INFO - "stage_platform": "linux64-pgo", 12:02:02 INFO - "basedir": "/builds/slave/test", 12:02:02 INFO - "buildnumber": 206, 12:02:02 INFO - "platform": "ubuntu64_hw", 12:02:02 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 12:02:02 INFO - "slavebuilddir": "test", 12:02:02 INFO - "taskId": "ADPWR2_JRVqZqa-rPZP5iw", 12:02:02 INFO - "branch": "mozilla-central", 12:02:02 INFO - "script_repo_revision": "production", 12:02:02 INFO - "revision": "b30ae5a455367f336ded1052081e4c54a139fa81", 12:02:02 INFO - "slavename": "talos-linux64-ix-068", 12:02:02 INFO - "repo_path": "mozilla-central" 12:02:02 INFO - } 12:02:02 INFO - Finding installer, test and symbols from parent task. 12:02:02 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ADPWR2_JRVqZqa-rPZP5iw'}, attempt #1 12:02:03 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ADPWR2_JRVqZqa-rPZP5iw'}, attempt #1 12:02:03 INFO - Task dependencies: TPEV7epBRV-h_JJWHMoJwA 12:02:03 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TPEV7epBRV-h_JJWHMoJwA'}, attempt #1 12:02:04 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/TPEV7epBRV-h_JJWHMoJwA/artifacts/public/build/target.tar.bz2 12:02:04 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/TPEV7epBRV-h_JJWHMoJwA/artifacts/public/build/target.test_packages.json 12:02:04 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/TPEV7epBRV-h_JJWHMoJwA/artifacts/public/build/target.crashreporter-symbols.zip 12:02:04 INFO - Running post-action listener: _resource_record_post_action 12:02:04 INFO - [mozharness: 2017-09-26 19:02:04.253884Z] Finished read-buildbot-config step (success) 12:02:04 INFO - [mozharness: 2017-09-26 19:02:04.254106Z] Running download-and-extract step. 12:02:04 INFO - Running pre-action listener: _resource_record_pre_action 12:02:04 INFO - Running main action method: download_and_extract 12:02:04 INFO - mkdir: /builds/slave/test/build/tests 12:02:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:02:04 INFO - trying https://queue.taskcluster.net/v1/task/TPEV7epBRV-h_JJWHMoJwA/artifacts/public/build/target.test_packages.json 12:02:04 INFO - Downloading https://queue.taskcluster.net/v1/task/TPEV7epBRV-h_JJWHMoJwA/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 12:02:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TPEV7epBRV-h_JJWHMoJwA/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 12:02:04 INFO - Downloaded 989 bytes. 12:02:04 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 12:02:04 INFO - Using the following test package requirements: 12:02:04 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 12:02:04 INFO - u'common': [u'target.common.tests.zip'], 12:02:04 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 12:02:04 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 12:02:04 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 12:02:04 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 12:02:04 INFO - u'mozbase': [u'target.common.tests.zip'], 12:02:04 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 12:02:04 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 12:02:04 INFO - u'web-platform': [u'target.common.tests.zip', 12:02:04 INFO - u'target.web-platform.tests.tar.gz'], 12:02:04 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 12:02:04 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 12:02:04 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/TPEV7epBRV-h_JJWHMoJwA/artifacts/public/build/target.common.tests.zip 12:02:04 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TPEV7epBRV-h_JJWHMoJwA/artifacts/public/build/target.common.tests.zip'}, attempt #1 12:02:04 INFO - Fetch https://queue.taskcluster.net/v1/task/TPEV7epBRV-h_JJWHMoJwA/artifacts/public/build/target.common.tests.zip into memory 12:02:05 INFO - Content-Length response header: 55346132 12:02:05 INFO - Bytes received: 55346132 12:02:16 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/TPEV7epBRV-h_JJWHMoJwA/artifacts/public/build/target.talos.tests.zip 12:02:16 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TPEV7epBRV-h_JJWHMoJwA/artifacts/public/build/target.talos.tests.zip'}, attempt #1 12:02:16 INFO - Fetch https://queue.taskcluster.net/v1/task/TPEV7epBRV-h_JJWHMoJwA/artifacts/public/build/target.talos.tests.zip into memory 12:02:17 INFO - Content-Length response header: 14513823 12:02:17 INFO - Bytes received: 14513823 12:02:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:02:17 INFO - trying https://queue.taskcluster.net/v1/task/TPEV7epBRV-h_JJWHMoJwA/artifacts/public/build/target.tar.bz2 12:02:17 INFO - Downloading https://queue.taskcluster.net/v1/task/TPEV7epBRV-h_JJWHMoJwA/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 12:02:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TPEV7epBRV-h_JJWHMoJwA/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 12:02:19 INFO - Downloaded 62273072 bytes. 12:02:19 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/TPEV7epBRV-h_JJWHMoJwA/artifacts/public/build/target.tar.bz2 12:02:19 INFO - mkdir: /builds/slave/test/properties 12:02:19 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:02:19 INFO - Writing to file /builds/slave/test/properties/build_url 12:02:19 INFO - Contents: 12:02:19 INFO - build_url:https://queue.taskcluster.net/v1/task/TPEV7epBRV-h_JJWHMoJwA/artifacts/public/build/target.tar.bz2 12:02:19 INFO - Running post-action listener: _resource_record_post_action 12:02:19 INFO - Running post-action listener: find_tests_for_verification 12:02:19 INFO - Running post-action listener: set_extra_try_arguments 12:02:19 INFO - [mozharness: 2017-09-26 19:02:19.036257Z] Finished download-and-extract step (success) 12:02:19 INFO - [mozharness: 2017-09-26 19:02:19.036370Z] Running populate-webroot step. 12:02:19 INFO - Running pre-action listener: _resource_record_pre_action 12:02:19 INFO - Running main action method: populate_webroot 12:02:19 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:02:19 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 12:02:19 INFO - 'tests': ['tresize', 12:02:19 INFO - 'tcanvasmark']}, 12:02:19 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:02:19 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 12:02:19 INFO - 'tests': ['dromaeo_css', 12:02:19 INFO - 'kraken']}, 12:02:19 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 12:02:19 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:02:19 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 12:02:19 INFO - 'talos_options': ['--disable-stylo'], 12:02:19 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:02:19 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 12:02:19 INFO - 'tests': ['damp', 'tps']}, 12:02:19 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 12:02:19 INFO - 'talos_options': ['--disable-stylo'], 12:02:19 INFO - 'tests': ['damp', 'tps']}, 12:02:19 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:02:19 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 12:02:19 INFO - 'tests': ['dromaeo_dom']}, 12:02:19 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 12:02:19 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 12:02:19 INFO - 'tests': ['basic_compositor_video', 12:02:19 INFO - 'glvideo']}, 12:02:19 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 12:02:19 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 12:02:19 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 12:02:19 INFO - 'talos_options': ['--disable-stylo'], 12:02:19 INFO - 'tests': ['ts_paint_webext', 12:02:19 INFO - 'tp5o_webext']}, 12:02:19 INFO - 'other-e10s': {'tests': ['a11yr', 12:02:19 INFO - 'ts_paint', 12:02:19 INFO - 'tpaint', 12:02:19 INFO - 'sessionrestore', 12:02:19 INFO - 'sessionrestore_many_windows', 12:02:19 INFO - 'sessionrestore_no_auto_restore', 12:02:19 INFO - 'tabpaint']}, 12:02:19 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 12:02:19 INFO - 'tests': ['a11yr', 12:02:19 INFO - 'ts_paint', 12:02:19 INFO - 'tpaint', 12:02:19 INFO - 'sessionrestore', 12:02:19 INFO - 'sessionrestore_many_windows', 12:02:19 INFO - 'sessionrestore_no_auto_restore', 12:02:19 INFO - 'tabpaint']}, 12:02:19 INFO - 'perf-reftest-e10s': {'tests': ['perf_reftest']}, 12:02:19 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']}, 12:02:19 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 12:02:19 INFO - 'tests': ['perf_reftest_singletons']}, 12:02:19 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 12:02:19 INFO - 'tests': ['perf_reftest']}, 12:02:19 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:02:19 INFO - 'tsvgr_opacity', 12:02:19 INFO - 'tart', 12:02:19 INFO - 'tscrollx', 12:02:19 INFO - 'tsvg_static']}, 12:02:19 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 12:02:19 INFO - 'tests': ['tsvgx', 12:02:19 INFO - 'tsvgr_opacity', 12:02:19 INFO - 'tart', 12:02:19 INFO - 'tscrollx', 12:02:19 INFO - 'tsvg_static']}, 12:02:19 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 12:02:19 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 12:02:19 INFO - 'talos_options': ['--disable-stylo'], 12:02:19 INFO - 'tests': ['tp5o']}, 12:02:19 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 12:02:19 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 12:02:19 INFO - 'talos_options': ['--mitmproxy', 12:02:19 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 12:02:19 INFO - '--firstNonBlankPaint'], 12:02:19 INFO - 'tests': ['tp6_google', 12:02:19 INFO - 'tp6_youtube', 12:02:19 INFO - 'tp6_amazon', 12:02:19 INFO - 'tp6_facebook']}, 12:02:19 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 12:02:19 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 12:02:19 INFO - 'talos_options': ['--disable-stylo', 12:02:19 INFO - '--mitmproxy', 12:02:19 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 12:02:19 INFO - '--firstNonBlankPaint'], 12:02:19 INFO - 'tests': ['tp6_google', 12:02:19 INFO - 'tp6_youtube', 12:02:19 INFO - 'tp6_amazon', 12:02:19 INFO - 'tp6_facebook']}, 12:02:19 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 12:02:19 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 12:02:19 INFO - 'talos_options': ['--stylo-threads=1', 12:02:19 INFO - '--mitmproxy', 12:02:19 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 12:02:19 INFO - '--firstNonBlankPaint'], 12:02:19 INFO - 'tests': ['tp6_google', 12:02:19 INFO - 'tp6_youtube', 12:02:19 INFO - 'tp6_amazon', 12:02:19 INFO - 'tp6_facebook']}, 12:02:19 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 12:02:19 INFO - 'talos_options': ['--xperf_path', 12:02:19 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:02:19 INFO - 'tests': ['tp5n']}, 12:02:19 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 12:02:19 INFO - 'talos_options': ['--disable-stylo', 12:02:19 INFO - '--xperf_path', 12:02:19 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:02:19 INFO - 'tests': ['tp5n']}}} 12:02:19 INFO - Running post-action listener: _resource_record_post_action 12:02:19 INFO - [mozharness: 2017-09-26 19:02:19.053304Z] Finished populate-webroot step (success) 12:02:19 INFO - [mozharness: 2017-09-26 19:02:19.053407Z] Running create-virtualenv step. 12:02:19 INFO - Running pre-action listener: _resource_record_pre_action 12:02:19 INFO - Running main action method: create_virtualenv 12:02:19 INFO - Creating virtualenv /builds/slave/test/build/venv 12:02:19 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 12:02:19 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 12:02:19 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 12:02:19 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:02:19 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:02:20 INFO - Installing distribute.............................................................................................................................................................................................done. 12:02:20 INFO - Installing pip...............done. 12:02:20 INFO - Return code: 0 12:02:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 12:02:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 12:02:20 INFO - Reading from file tmpfile_stdout 12:02:20 INFO - Output received: 12:02:20 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 12:02:20 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:02:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:02:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:02:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:02:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:02:20 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 0x195a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b22b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cc79c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cc4d60>, '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 0x1cc5a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cc5ef0>, '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': '97facb8ba2ac28944cc45e6b000001d4-1506452519.880506-810758685', '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 12:02:20 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 12:02:20 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 12:02:20 INFO - Using env: {'DISPLAY': ':0', 12:02:20 INFO - 'HOME': '/home/cltbld', 12:02:20 INFO - 'LANG': 'en_US.UTF-8', 12:02:20 INFO - 'LANGUAGE': 'en_US:en', 12:02:20 INFO - 'LOGNAME': 'cltbld', 12:02:20 INFO - 'MAIL': '/var/mail/cltbld', 12:02:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:02:20 INFO - 'MOZ_NO_REMOTE': '1', 12:02:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:02:20 INFO - 'NO_EM_RESTART': '1', 12:02:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:02:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:02:20 INFO - 'PWD': '/builds/slave/test', 12:02:20 INFO - 'SHELL': '/bin/bash', 12:02:20 INFO - 'SHLVL': '1', 12:02:20 INFO - 'TERM': 'linux', 12:02:20 INFO - 'TMOUT': '86400', 12:02:20 INFO - 'USER': 'cltbld', 12:02:20 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1506452519.880506-810758685', 12:02:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:02:20 INFO - '_': '/tools/buildbot/bin/python'} 12:02:22 INFO - Ignoring indexes: http://pypi.python.org/simple/ 12:02:22 INFO - Downloading/unpacking pip>=1.5 12:02:22 INFO - Running setup.py egg_info for package pip 12:02:22 INFO - warning: no files found matching 'pip/cacert.pem' 12:02:22 INFO - warning: no files found matching '*.html' under directory 'docs' 12:02:22 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 12:02:22 INFO - no previously-included directories found matching 'docs/_build/_sources' 12:02:22 INFO - Installing collected packages: pip 12:02:22 INFO - Found existing installation: pip 1.1 12:02:22 INFO - Uninstalling pip: 12:02:22 INFO - Successfully uninstalled pip 12:02:22 INFO - Running setup.py install for pip 12:02:22 INFO - warning: no files found matching 'pip/cacert.pem' 12:02:22 INFO - warning: no files found matching '*.html' under directory 'docs' 12:02:22 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 12:02:22 INFO - no previously-included directories found matching 'docs/_build/_sources' 12:02:22 INFO - Installing pip script to /builds/slave/test/build/venv/bin 12:02:22 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 12:02:22 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 12:02:22 INFO - Successfully installed pip 12:02:22 INFO - Cleaning up... 12:02:22 INFO - Return code: 0 12:02:22 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:02: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')]} 12:02:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:02: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')]} 12:02:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:02:22 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x195a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b22b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cc79c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cc4d60>, '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 0x1cc5a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cc5ef0>, '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': '97facb8ba2ac28944cc45e6b000001d4-1506452519.880506-810758685', '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 12:02:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 12:02:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 12:02:22 INFO - Using env: {'DISPLAY': ':0', 12:02:22 INFO - 'HOME': '/home/cltbld', 12:02:22 INFO - 'LANG': 'en_US.UTF-8', 12:02:22 INFO - 'LANGUAGE': 'en_US:en', 12:02:22 INFO - 'LOGNAME': 'cltbld', 12:02:22 INFO - 'MAIL': '/var/mail/cltbld', 12:02:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:02:22 INFO - 'MOZ_NO_REMOTE': '1', 12:02:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:02:22 INFO - 'NO_EM_RESTART': '1', 12:02:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:02:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:02:22 INFO - 'PWD': '/builds/slave/test', 12:02:22 INFO - 'SHELL': '/bin/bash', 12:02:22 INFO - 'SHLVL': '1', 12:02:22 INFO - 'TERM': 'linux', 12:02:22 INFO - 'TMOUT': '86400', 12:02:22 INFO - 'USER': 'cltbld', 12:02:22 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1506452519.880506-810758685', 12:02:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:02:22 INFO - '_': '/tools/buildbot/bin/python'} 12:02:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:02:22 INFO - Downloading/unpacking psutil>=3.1.1 12:02:22 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 12:02:22 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 12:02:22 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 12:02:22 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 12:02:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:02:23 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:02:23 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:02:23 INFO - Installing collected packages: psutil 12:02:23 INFO - Running setup.py install for psutil 12:02:23 INFO - building 'psutil._psutil_linux' extension 12:02:23 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 12:02:23 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 12:02:23 INFO - building 'psutil._psutil_posix' extension 12:02:23 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 12:02:24 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 12:02:24 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:02:24 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:02:24 INFO - Successfully installed psutil 12:02:24 INFO - Cleaning up... 12:02:24 INFO - Return code: 0 12:02:24 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:02:24 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')]} 12:02:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:02:24 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')]} 12:02:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:02:24 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 0x195a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b22b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cc79c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cc4d60>, '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 0x1cc5a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cc5ef0>, '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': '97facb8ba2ac28944cc45e6b000001d4-1506452519.880506-810758685', '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 12:02:24 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 12:02:24 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 12:02:24 INFO - Using env: {'DISPLAY': ':0', 12:02:24 INFO - 'HOME': '/home/cltbld', 12:02:24 INFO - 'LANG': 'en_US.UTF-8', 12:02:24 INFO - 'LANGUAGE': 'en_US:en', 12:02:24 INFO - 'LOGNAME': 'cltbld', 12:02:24 INFO - 'MAIL': '/var/mail/cltbld', 12:02:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:02:24 INFO - 'MOZ_NO_REMOTE': '1', 12:02:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:02:24 INFO - 'NO_EM_RESTART': '1', 12:02:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:02:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:02:24 INFO - 'PWD': '/builds/slave/test', 12:02:24 INFO - 'SHELL': '/bin/bash', 12:02:24 INFO - 'SHLVL': '1', 12:02:24 INFO - 'TERM': 'linux', 12:02:24 INFO - 'TMOUT': '86400', 12:02:24 INFO - 'USER': 'cltbld', 12:02:24 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1506452519.880506-810758685', 12:02:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:02:24 INFO - '_': '/tools/buildbot/bin/python'} 12:02:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:02:24 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:02:24 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 12:02:24 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 12:02:24 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 12:02:24 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 12:02:25 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:02:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:02:25 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:02:25 INFO - Installing collected packages: mozsystemmonitor 12:02:25 INFO - Running setup.py install for mozsystemmonitor 12:02:25 INFO - Successfully installed mozsystemmonitor 12:02:25 INFO - Cleaning up... 12:02:26 INFO - Return code: 0 12:02:26 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 12:02:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:02:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:02:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:02:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:02:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x195a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b22b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cc79c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cc4d60>, '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 0x1cc5a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cc5ef0>, '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': '97facb8ba2ac28944cc45e6b000001d4-1506452519.880506-810758685', '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 12:02:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 12:02:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 12:02:26 INFO - Using env: {'DISPLAY': ':0', 12:02:26 INFO - 'HOME': '/home/cltbld', 12:02:26 INFO - 'LANG': 'en_US.UTF-8', 12:02:26 INFO - 'LANGUAGE': 'en_US:en', 12:02:26 INFO - 'LOGNAME': 'cltbld', 12:02:26 INFO - 'MAIL': '/var/mail/cltbld', 12:02:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:02:26 INFO - 'MOZ_NO_REMOTE': '1', 12:02:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:02:26 INFO - 'NO_EM_RESTART': '1', 12:02:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:02:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:02:26 INFO - 'PWD': '/builds/slave/test', 12:02:26 INFO - 'SHELL': '/bin/bash', 12:02:26 INFO - 'SHLVL': '1', 12:02:26 INFO - 'TERM': 'linux', 12:02:26 INFO - 'TMOUT': '86400', 12:02:26 INFO - 'USER': 'cltbld', 12:02:26 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1506452519.880506-810758685', 12:02:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:02:26 INFO - '_': '/tools/buildbot/bin/python'} 12:02:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:02:27 INFO - Downloading/unpacking jsonschema==2.5.1 12:02:27 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 12:02:27 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 12:02:27 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 12:02:27 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 12:02:28 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:02:28 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 12:02:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:02:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:02:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:02:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:02:28 INFO - Downloading functools32-3.2.3-2.tar.gz 12:02:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:02:28 INFO - warning: no files found matching '*.txt' 12:02:28 INFO - no previously-included directories found matching 'build' 12:02:28 INFO - no previously-included directories found matching 'dist' 12:02:28 INFO - no previously-included directories found matching '.git*' 12:02:28 INFO - Installing collected packages: jsonschema, functools32 12:02:28 INFO - Running setup.py install for functools32 12:02:28 INFO - warning: no files found matching '*.txt' 12:02:28 INFO - no previously-included directories found matching 'build' 12:02:28 INFO - no previously-included directories found matching 'dist' 12:02:28 INFO - no previously-included directories found matching '.git*' 12:02:28 INFO - Successfully installed jsonschema functools32 12:02:28 INFO - Cleaning up... 12:02:28 INFO - Return code: 0 12:02:28 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 12:02:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:02:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:02:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:02:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:02:28 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x195a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b22b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cc79c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cc4d60>, '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 0x1cc5a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cc5ef0>, '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': '97facb8ba2ac28944cc45e6b000001d4-1506452519.880506-810758685', '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 12:02:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 12:02:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 12:02:28 INFO - Using env: {'DISPLAY': ':0', 12:02:28 INFO - 'HOME': '/home/cltbld', 12:02:28 INFO - 'LANG': 'en_US.UTF-8', 12:02:28 INFO - 'LANGUAGE': 'en_US:en', 12:02:28 INFO - 'LOGNAME': 'cltbld', 12:02:28 INFO - 'MAIL': '/var/mail/cltbld', 12:02:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:02:28 INFO - 'MOZ_NO_REMOTE': '1', 12:02:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:02:28 INFO - 'NO_EM_RESTART': '1', 12:02:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:02:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:02:28 INFO - 'PWD': '/builds/slave/test', 12:02:28 INFO - 'SHELL': '/bin/bash', 12:02:28 INFO - 'SHLVL': '1', 12:02:28 INFO - 'TERM': 'linux', 12:02:28 INFO - 'TMOUT': '86400', 12:02:28 INFO - 'USER': 'cltbld', 12:02:28 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1506452519.880506-810758685', 12:02:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:02:28 INFO - '_': '/tools/buildbot/bin/python'} 12:02:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:02:28 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 12:02:28 INFO - Cleaning up... 12:02:28 INFO - Return code: 0 12:02:28 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:02:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:02:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:02:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:02:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:02:28 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x195a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b22b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cc79c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cc4d60>, '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 0x1cc5a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cc5ef0>, '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': '97facb8ba2ac28944cc45e6b000001d4-1506452519.880506-810758685', '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 12:02:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 12:02:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 12:02:28 INFO - Using env: {'DISPLAY': ':0', 12:02:28 INFO - 'HOME': '/home/cltbld', 12:02:28 INFO - 'LANG': 'en_US.UTF-8', 12:02:28 INFO - 'LANGUAGE': 'en_US:en', 12:02:28 INFO - 'LOGNAME': 'cltbld', 12:02:28 INFO - 'MAIL': '/var/mail/cltbld', 12:02:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:02:28 INFO - 'MOZ_NO_REMOTE': '1', 12:02:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:02:28 INFO - 'NO_EM_RESTART': '1', 12:02:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:02:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:02:28 INFO - 'PWD': '/builds/slave/test', 12:02:28 INFO - 'SHELL': '/bin/bash', 12:02:28 INFO - 'SHLVL': '1', 12:02:28 INFO - 'TERM': 'linux', 12:02:28 INFO - 'TMOUT': '86400', 12:02:28 INFO - 'USER': 'cltbld', 12:02:28 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1506452519.880506-810758685', 12:02:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:02:28 INFO - '_': '/tools/buildbot/bin/python'} 12:02:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:02:28 INFO - Downloading/unpacking blobuploader==1.2.4 12:02:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:02:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:02:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:02:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:02:30 INFO - Downloading blobuploader-1.2.4.tar.gz 12:02:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:02:30 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:02:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:02:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:02:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:02:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:02:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:02:30 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:02:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:02:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:02:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:02:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:02:30 INFO - Downloading docopt-0.6.1.tar.gz 12:02:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:02:30 INFO - Installing collected packages: blobuploader, requests, docopt 12:02:30 INFO - Running setup.py install for blobuploader 12:02:30 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:02:30 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:02:30 INFO - Running setup.py install for requests 12:02:31 INFO - Running setup.py install for docopt 12:02:31 INFO - Successfully installed blobuploader requests docopt 12:02:31 INFO - Cleaning up... 12:02:31 INFO - Return code: 0 12:02:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:02:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:02:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:02:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:02:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:02:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x195a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b22b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cc79c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cc4d60>, '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 0x1cc5a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cc5ef0>, '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': '97facb8ba2ac28944cc45e6b000001d4-1506452519.880506-810758685', '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 12:02:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 12:02:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 12:02:31 INFO - Using env: {'DISPLAY': ':0', 12:02:31 INFO - 'HOME': '/home/cltbld', 12:02:31 INFO - 'LANG': 'en_US.UTF-8', 12:02:31 INFO - 'LANGUAGE': 'en_US:en', 12:02:31 INFO - 'LOGNAME': 'cltbld', 12:02:31 INFO - 'MAIL': '/var/mail/cltbld', 12:02:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:02:31 INFO - 'MOZ_NO_REMOTE': '1', 12:02:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:02:31 INFO - 'NO_EM_RESTART': '1', 12:02:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:02:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:02:31 INFO - 'PWD': '/builds/slave/test', 12:02:31 INFO - 'SHELL': '/bin/bash', 12:02:31 INFO - 'SHLVL': '1', 12:02:31 INFO - 'TERM': 'linux', 12:02:31 INFO - 'TMOUT': '86400', 12:02:31 INFO - 'USER': 'cltbld', 12:02:31 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1506452519.880506-810758685', 12:02:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:02:31 INFO - '_': '/tools/buildbot/bin/python'} 12:02:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:02:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:02:31 INFO - Running setup.py (path:/tmp/pip-Gu6ZMd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:02:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:02:31 INFO - Running setup.py (path:/tmp/pip-Lbe6wp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:02:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:02:31 INFO - Running setup.py (path:/tmp/pip-iCluvE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:02:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:02:31 INFO - Running setup.py (path:/tmp/pip-h0MjQK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:02:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:02:31 INFO - Running setup.py (path:/tmp/pip-oRn8eZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:02:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:02:31 INFO - Running setup.py (path:/tmp/pip-3P0Gzb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:02:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:02:31 INFO - Running setup.py (path:/tmp/pip-sjjudI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:02:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:02:31 INFO - Running setup.py (path:/tmp/pip-Re2CM5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:02:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:02:31 INFO - Running setup.py (path:/tmp/pip-hERsJd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:02:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:02:32 INFO - Running setup.py (path:/tmp/pip-mxdEHY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:02:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:02:32 INFO - Running setup.py (path:/tmp/pip-2Dz3Th-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:02:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:02:32 INFO - Running setup.py (path:/tmp/pip-9pgEKW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:02:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:02:32 INFO - Running setup.py (path:/tmp/pip-Y_N3CA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:02:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:02:32 INFO - Running setup.py (path:/tmp/pip-8Y57ja-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:02:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:02:32 INFO - Running setup.py (path:/tmp/pip-_tBHW_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:02:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:02:32 INFO - Running setup.py (path:/tmp/pip-O5y7Fs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:02:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:02:32 INFO - Running setup.py (path:/tmp/pip-NLszH3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:02:32 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:02:32 INFO - Running setup.py install for manifestparser 12:02:32 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:02:32 INFO - Running setup.py install for mozcrash 12:02:32 INFO - Running setup.py install for mozdebug 12:02:32 INFO - Running setup.py install for mozdevice 12:02:33 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:02:33 INFO - Running setup.py install for mozfile 12:02:33 INFO - Running setup.py install for mozhttpd 12:02:33 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:02:33 INFO - Running setup.py install for mozinfo 12:02:33 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:02:33 INFO - Running setup.py install for mozInstall 12:02:33 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:02:33 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:02:33 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:02:33 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:02:33 INFO - Running setup.py install for mozleak 12:02:33 INFO - Running setup.py install for mozlog 12:02:33 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:02:33 INFO - Running setup.py install for moznetwork 12:02:33 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:02:33 INFO - Running setup.py install for mozprocess 12:02:33 INFO - Running setup.py install for mozprofile 12:02:33 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:02:33 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:02:33 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:02:33 INFO - Running setup.py install for mozrunner 12:02:34 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:02:34 INFO - Running setup.py install for mozscreenshot 12:02:34 INFO - Running setup.py install for moztest 12:02:34 INFO - Running setup.py install for mozversion 12:02:34 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:02:34 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:02:34 INFO - Cleaning up... 12:02:34 INFO - Return code: 0 12:02:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:02:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:02:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:02:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:02:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:02:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x195a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b22b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cc79c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cc4d60>, '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 0x1cc5a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cc5ef0>, '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': '97facb8ba2ac28944cc45e6b000001d4-1506452519.880506-810758685', '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 12:02:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 12:02:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 12:02:34 INFO - Using env: {'DISPLAY': ':0', 12:02:34 INFO - 'HOME': '/home/cltbld', 12:02:34 INFO - 'LANG': 'en_US.UTF-8', 12:02:34 INFO - 'LANGUAGE': 'en_US:en', 12:02:34 INFO - 'LOGNAME': 'cltbld', 12:02:34 INFO - 'MAIL': '/var/mail/cltbld', 12:02:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:02:34 INFO - 'MOZ_NO_REMOTE': '1', 12:02:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:02:34 INFO - 'NO_EM_RESTART': '1', 12:02:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:02:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:02:34 INFO - 'PWD': '/builds/slave/test', 12:02:34 INFO - 'SHELL': '/bin/bash', 12:02:34 INFO - 'SHLVL': '1', 12:02:34 INFO - 'TERM': 'linux', 12:02:34 INFO - 'TMOUT': '86400', 12:02:34 INFO - 'USER': 'cltbld', 12:02:34 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1506452519.880506-810758685', 12:02:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:02:34 INFO - '_': '/tools/buildbot/bin/python'} 12:02:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:02:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:02:34 INFO - Running setup.py (path:/tmp/pip-USgvF1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:02:34 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 12:02:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:02:34 INFO - Running setup.py (path:/tmp/pip-emuYlH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:02:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 12:02:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:02:34 INFO - Running setup.py (path:/tmp/pip-TA4Juq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:02:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 12:02:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:02:34 INFO - Running setup.py (path:/tmp/pip-7H7dM6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:02:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:02:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:02:34 INFO - Running setup.py (path:/tmp/pip-J_WjfF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:02:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 12:02:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:02:34 INFO - Running setup.py (path:/tmp/pip-7lqSWI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:02:35 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)) 12:02:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:02:35 INFO - Running setup.py (path:/tmp/pip-dSKeIA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:02:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 12:02:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:02:35 INFO - Running setup.py (path:/tmp/pip-cDpAWP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:02:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.13 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 12:02:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:02:35 INFO - Running setup.py (path:/tmp/pip-MTZYmx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:02:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 12:02:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:02:35 INFO - Running setup.py (path:/tmp/pip-nhvFyk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:02:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.5 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:02:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:02:35 INFO - Running setup.py (path:/tmp/pip-Fkdjcr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:02:35 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 12:02:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:02:35 INFO - Running setup.py (path:/tmp/pip-Qg3UQ7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:02:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 12:02:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:02:35 INFO - Running setup.py (path:/tmp/pip-nD2o3H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:02:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 12:02:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:02:35 INFO - Running setup.py (path:/tmp/pip-58GON0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:02:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 12:02:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:02:35 INFO - Running setup.py (path:/tmp/pip-9OIIC6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:02:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 12:02:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:02:35 INFO - Running setup.py (path:/tmp/pip-ns9tMG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:02:35 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 12:02:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:02:35 INFO - Running setup.py (path:/tmp/pip-inPPDq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:02:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 12:02:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 12:02:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 12:02:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 12:02:35 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:02:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:02:35 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.13->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 12:02:35 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:02:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:02:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:02:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:02:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:02:36 INFO - Downloading blessings-1.6.tar.gz 12:02:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:02:37 INFO - Installing collected packages: blessings 12:02:37 INFO - Running setup.py install for blessings 12:02:37 INFO - Successfully installed blessings 12:02:37 INFO - Cleaning up... 12:02:37 INFO - Return code: 0 12:02:37 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:02:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:02:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:02:37 INFO - Reading from file tmpfile_stdout 12:02:37 INFO - Current package versions: 12:02:37 INFO - argparse == 1.2.1 12:02:37 INFO - blessings == 1.6 12:02:37 INFO - blobuploader == 1.2.4 12:02:37 INFO - docopt == 0.6.1 12:02:37 INFO - functools32 == 3.2.3-2 12:02:37 INFO - jsonschema == 2.5.1 12:02:37 INFO - manifestparser == 1.1 12:02:37 INFO - mozInstall == 1.13 12:02:37 INFO - mozcrash == 1.0 12:02:37 INFO - mozdebug == 0.1 12:02:37 INFO - mozdevice == 0.51 12:02:37 INFO - mozfile == 1.2 12:02:37 INFO - mozhttpd == 0.7 12:02:37 INFO - mozinfo == 0.10 12:02:37 INFO - mozleak == 0.1 12:02:37 INFO - mozlog == 3.5 12:02:37 INFO - moznetwork == 0.27 12:02:37 INFO - mozprocess == 0.25 12:02:37 INFO - mozprofile == 0.28 12:02:37 INFO - mozrunner == 6.13 12:02:37 INFO - mozscreenshot == 0.1 12:02:37 INFO - mozsystemmonitor == 0.3 12:02:37 INFO - moztest == 0.8 12:02:37 INFO - mozversion == 1.4 12:02:37 INFO - psutil == 3.1.1 12:02:37 INFO - requests == 1.2.3 12:02:37 INFO - wsgiref == 0.1.2 12:02:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:02:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:02:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:02:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:02:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:02:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x195a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b22b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cc79c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cc4d60>, '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 0x1cc5a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cc5ef0>, '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': '97facb8ba2ac28944cc45e6b000001d4-1506452519.880506-810758685', '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 12:02:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 12:02:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 12:02:37 INFO - Using env: {'DISPLAY': ':0', 12:02:37 INFO - 'HOME': '/home/cltbld', 12:02:37 INFO - 'LANG': 'en_US.UTF-8', 12:02:37 INFO - 'LANGUAGE': 'en_US:en', 12:02:37 INFO - 'LOGNAME': 'cltbld', 12:02:37 INFO - 'MAIL': '/var/mail/cltbld', 12:02:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:02:37 INFO - 'MOZ_NO_REMOTE': '1', 12:02:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:02:37 INFO - 'NO_EM_RESTART': '1', 12:02:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:02:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:02:37 INFO - 'PWD': '/builds/slave/test', 12:02:37 INFO - 'SHELL': '/bin/bash', 12:02:37 INFO - 'SHLVL': '1', 12:02:37 INFO - 'TERM': 'linux', 12:02:37 INFO - 'TMOUT': '86400', 12:02:37 INFO - 'USER': 'cltbld', 12:02:37 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1506452519.880506-810758685', 12:02:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:02:37 INFO - '_': '/tools/buildbot/bin/python'} 12:02:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:02:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 12:02:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 12:02:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 12:02:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 12:02:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 12:02:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 12:02:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 12:02:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 12:02:37 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 12:02:37 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 12:02:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:02:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:02:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:02:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:02:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 12:02:38 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 12:02:38 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 12:02:38 INFO - Installing collected packages: simplejson 12:02:38 INFO - Running setup.py install for simplejson 12:02:38 INFO - building 'simplejson._speedups' extension 12:02:38 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c simplejson/_speedups.c -o build/temp.linux-x86_64-2.7/simplejson/_speedups.o 12:02:39 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/simplejson/_speedups.o -o build/lib.linux-x86_64-2.7/simplejson/_speedups.so 12:02:39 INFO - Successfully installed simplejson 12:02:39 INFO - Cleaning up... 12:02:39 INFO - Return code: 0 12:02:39 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:02: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')]} 12:02:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:02: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')]} 12:02:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:02:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x195a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b22b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cc79c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cc4d60>, '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 0x1cc5a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cc5ef0>, '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': '97facb8ba2ac28944cc45e6b000001d4-1506452519.880506-810758685', '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 12:02:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 12:02:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 12:02:39 INFO - Using env: {'DISPLAY': ':0', 12:02:39 INFO - 'HOME': '/home/cltbld', 12:02:39 INFO - 'LANG': 'en_US.UTF-8', 12:02:39 INFO - 'LANGUAGE': 'en_US:en', 12:02:39 INFO - 'LOGNAME': 'cltbld', 12:02:39 INFO - 'MAIL': '/var/mail/cltbld', 12:02:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:02:39 INFO - 'MOZ_NO_REMOTE': '1', 12:02:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:02:39 INFO - 'NO_EM_RESTART': '1', 12:02:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:02:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:02:39 INFO - 'PWD': '/builds/slave/test', 12:02:39 INFO - 'SHELL': '/bin/bash', 12:02:39 INFO - 'SHLVL': '1', 12:02:39 INFO - 'TERM': 'linux', 12:02:39 INFO - 'TMOUT': '86400', 12:02:39 INFO - 'USER': 'cltbld', 12:02:39 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1506452519.880506-810758685', 12:02:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:02:39 INFO - '_': '/tools/buildbot/bin/python'} 12:02:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:02:40 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 12:02:40 INFO - Cleaning up... 12:02:40 INFO - Return code: 0 12:02:40 INFO - Running post-action listener: _resource_record_post_action 12:02:40 INFO - Running post-action listener: _start_resource_monitoring 12:02:40 INFO - Starting resource monitoring. 12:02:40 INFO - [mozharness: 2017-09-26 19:02:40.106285Z] Finished create-virtualenv step (success) 12:02:40 INFO - [mozharness: 2017-09-26 19:02:40.106903Z] Running install step. 12:02:40 INFO - Running pre-action listener: _resource_record_pre_action 12:02:40 INFO - Running main action method: install 12:02:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:02:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:02:40 INFO - Reading from file tmpfile_stdout 12:02:40 INFO - Detecting whether we're running mozinstall >=1.0... 12:02:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:02:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:02:40 INFO - Reading from file tmpfile_stdout 12:02:40 INFO - Output received: 12:02:40 INFO - Usage: mozinstall [options] installer 12:02:40 INFO - Options: 12:02:40 INFO - -h, --help show this help message and exit 12:02:40 INFO - -d DEST, --destination=DEST 12:02:40 INFO - Directory to install application into. [default: 12:02:40 INFO - "/builds/slave/test"] 12:02:40 INFO - --app=APP Application being installed. [default: firefox] 12:02:40 INFO - mkdir: /builds/slave/test/build/application 12:02:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 12:02:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 12:02:58 INFO - Reading from file tmpfile_stdout 12:02:58 INFO - Output received: 12:02:58 INFO - /builds/slave/test/build/application/firefox/firefox 12:02:58 INFO - Running post-action listener: _resource_record_post_action 12:02:58 INFO - [mozharness: 2017-09-26 19:02:58.046352Z] Finished install step (success) 12:02:58 INFO - [mozharness: 2017-09-26 19:02:58.046606Z] Running setup-mitmproxy step. 12:02:58 INFO - Running pre-action listener: _resource_record_pre_action 12:02:58 INFO - Running main action method: setup_mitmproxy 12:02:58 INFO - Skipping: mitmproxy is not required 12:02:58 INFO - Running post-action listener: _resource_record_post_action 12:02:58 INFO - [mozharness: 2017-09-26 19:02:58.047738Z] Finished setup-mitmproxy step (success) 12:02:58 INFO - [mozharness: 2017-09-26 19:02:58.047959Z] Running run-tests step. 12:02:58 INFO - Running pre-action listener: _resource_record_pre_action 12:02:58 INFO - Running pre-action listener: _set_gcov_prefix 12:02:58 INFO - Running main action method: run_tests 12:02:58 WARNING - Try message not found. 12:02:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:02:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:02:58 INFO - Python 2.7.3 12:02:58 INFO - Return code: 0 12:02:58 INFO - grabbing minidump binary from tooltool 12:02:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:02:58 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 0x1cc4d60>, '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 0x1cc5a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cc5ef0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:02:58 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 12:02:58 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 12:02:58 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 12:02:58 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:02:58 INFO - Return code: 0 12:02:58 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 12:02:58 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:02:58 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:02:58 INFO - ENV: RUST_BACKTRACE is now full 12:02:58 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 12:02:58 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:02:58 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/TPEV7epBRV-h_JJWHMoJwA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-068', '--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 12:02:58 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/TPEV7epBRV-h_JJWHMoJwA/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-068 --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 12:02:58 INFO - Using env: {'DISPLAY': ':0', 12:02:58 INFO - 'HOME': '/home/cltbld', 12:02:58 INFO - 'LANG': 'en_US.UTF-8', 12:02:58 INFO - 'LANGUAGE': 'en_US:en', 12:02:58 INFO - 'LOGNAME': 'cltbld', 12:02:58 INFO - 'MAIL': '/var/mail/cltbld', 12:02:58 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:02:58 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 12:02:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:02:58 INFO - 'MOZ_NO_REMOTE': '1', 12:02:58 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:02:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:02:58 INFO - 'NO_EM_RESTART': '1', 12:02:58 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:02:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:02:58 INFO - 'PWD': '/builds/slave/test', 12:02:58 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:02:58 INFO - 'RUST_BACKTRACE': 'full', 12:02:58 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 12:02:58 INFO - 'SHELL': '/bin/bash', 12:02:58 INFO - 'SHLVL': '1', 12:02:58 INFO - 'TERM': 'linux', 12:02:58 INFO - 'TMOUT': '86400', 12:02:58 INFO - 'USER': 'cltbld', 12:02:58 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1506452519.880506-810758685', 12:02:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:02:58 INFO - '_': '/tools/buildbot/bin/python'} 12:02:58 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/TPEV7epBRV-h_JJWHMoJwA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-068', '--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 12:02:58 INFO - mozversion application_buildid: 20170926173447 12:02:58 INFO - mozversion application_changeset: b30ae5a455367f336ded1052081e4c54a139fa81 12:02:58 INFO - mozversion application_display_name: Nightly 12:02:58 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:02:58 INFO - mozversion application_name: Firefox 12:02:58 INFO - mozversion application_remotingname: firefox 12:02:58 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 12:02:58 INFO - mozversion application_vendor: Mozilla 12:02:58 INFO - mozversion application_version: 58.0a1 12:02:58 INFO - mozversion platform_buildid: 20170926173447 12:02:58 INFO - mozversion platform_changeset: b30ae5a455367f336ded1052081e4c54a139fa81 12:02:58 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 12:02:58 INFO - mozversion platform_version: 58.0a1 12:02:58 INFO - using testdate: 1506452578 12:02:58 INFO - actual date: 1506452578 12:02:58 INFO - starting webserver on 'localhost:54778' 12:02:58 INFO - SUITE-START | Running 2 tests 12:02:58 INFO - TEST-START | dromaeo_css 12:02:58 INFO - Initialising browser for dromaeo_css test... 12:02:58 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:54778/getInfo.html -profile /tmp/tmpl0TFtr/profile 12:02:58 INFO - TEST-INFO | started process 10587 (/builds/slave/test/build/application/firefox/firefox http://localhost:54778/getInfo.html) 12:03:04 INFO - TEST-INFO | 10587: exit 0 12:03:04 INFO - Browser initialized. 12:03:04 INFO - Running cycle 1/1 for dromaeo_css test... 12:03:04 INFO - Using env: {'DISPLAY': ':0', 12:03:04 INFO - 'HOME': '/home/cltbld', 12:03:04 INFO - 'JSGC_DISABLE_POISONING': '1', 12:03:04 INFO - 'LANG': 'en_US.UTF-8', 12:03:04 INFO - 'LANGUAGE': 'en_US:en', 12:03:04 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 12:03:04 INFO - 'LOGNAME': 'cltbld', 12:03:04 INFO - 'MAIL': '/var/mail/cltbld', 12:03:04 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:03:04 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 12:03:04 INFO - 'MOZ_CRASHREPORTER': '1', 12:03:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:03:04 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 12:03:04 INFO - 'MOZ_NO_REMOTE': '1', 12:03:04 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:03:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:03:04 INFO - 'NO_EM_RESTART': '1', 12:03:04 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:03:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:03:04 INFO - 'PWD': '/builds/slave/test', 12:03:04 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:03:04 INFO - 'RUST_BACKTRACE': 'full', 12:03:04 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 12:03:04 INFO - 'SHELL': '/bin/bash', 12:03:04 INFO - 'SHLVL': '1', 12:03:04 INFO - 'TERM': 'linux', 12:03:04 INFO - 'TMOUT': '86400', 12:03:04 INFO - 'USER': 'cltbld', 12:03:04 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1506452519.880506-810758685', 12:03:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:03:04 INFO - '_': '/tools/buildbot/bin/python'} 12:03:04 INFO - TEST-INFO | started process 10826 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpl0TFtr/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 12:03:05 INFO - PID 10826 | 12:03:05 INFO - PID 10826 | (/builds/slave/test/build/application/firefox/firefox:10875): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 12:03:05 INFO - PID 10826 | 12:03:15 INFO - PID 10826 | RSS: Main: 145108992 12:03:15 INFO - PID 10826 | 12:06:12 INFO - PID 10826 | Cycle 1(1): loaded http://localhost:54778/tests/dromaeo/cssquery-dojo.html (next: http://localhost:54778/tests/dromaeo/cssquery-ext.html) 12:06:13 INFO - PID 10826 | RSS: Main: 147329024 12:06:13 INFO - PID 10826 | 12:09:14 INFO - PID 10826 | Cycle 1(1): loaded http://localhost:54778/tests/dromaeo/cssquery-ext.html (next: http://localhost:54778/tests/dromaeo/cssquery-jquery.html) 12:09:15 INFO - PID 10826 | RSS: Main: 147734528 12:09:15 INFO - PID 10826 | 12:12:16 INFO - PID 10826 | Cycle 1(1): loaded http://localhost:54778/tests/dromaeo/cssquery-jquery.html (next: http://localhost:54778/tests/dromaeo/cssquery-mootools.html) 12:12:17 INFO - PID 10826 | RSS: Main: 148566016 12:12:17 INFO - PID 10826 | 12:15:19 INFO - PID 10826 | Cycle 1(1): loaded http://localhost:54778/tests/dromaeo/cssquery-mootools.html (next: http://localhost:54778/tests/dromaeo/cssquery-prototype.html) 12:15:19 INFO - PID 10826 | RSS: Main: 147591168 12:15:19 INFO - PID 10826 | 12:18:22 INFO - PID 10826 | Cycle 1(1): loaded http://localhost:54778/tests/dromaeo/cssquery-prototype.html (next: http://localhost:54778/tests/dromaeo/cssquery-yui.html) 12:18:23 INFO - PID 10826 | RSS: Main: 149012480 12:18:23 INFO - PID 10826 | 12:21:24 INFO - PID 10826 | Cycle 1(1): loaded http://localhost:54778/tests/dromaeo/cssquery-yui.html (next: http://localhost:54778/tests/dromaeo/cssquery-dojo.html) 12:21:25 INFO - PID 10826 | RSS: Main: 150831104 12:21:25 INFO - PID 10826 | 12:21:25 INFO - PID 10826 | __start_tp_report 12:21:25 INFO - PID 10826 | _x_x_mozilla_page_load 12:21:25 INFO - PID 10826 | _x_x_mozilla_page_load_details 12:21:25 INFO - PID 10826 | |i|pagename|runs| 12:21:25 INFO - PID 10826 | |0;dojo.html;3836.4053571696386;3841.385378339466;3854.3832986722114;3869.3808990561506;3870.8645197418095;6094.847628809271;6146.907796383058;6260.937390626093;6276.560640755143;6307;6185.412385823351;6229.314775374705;6231.750729970796;6241.251049874021;6280.905786413196;5541.695206763627;5618.494335509814;5627.634203776762;5667.801626943058;5720.2849643794525;214.90716524476284;5665.603407761459;5694.6867922264255;5734.053881109612;5735.824156048;5560.054790685573;5565.749541270652;5581.134924086753;5698.943010569883;5705.286839145107;5576.581756368289;5618.353889302742;5697.1739097831;5706.457886500817;5707.971460142673;5591.245181900432;5625.887482250373;5678.346990096151;5681.034224181858;5696.031674615326;6216.564840461169;6256.687165641745;6263.404976527223;6285.937140628581;6317;6782.559133656297;6795.252522222551;6812.420944219747;6887.965560172217;6919.688614012382;6741.528093033489;6760.391564759146;6823.590584564942;6849.691763870638;6859.55412898165;6316.9368306317265;6342.429181373653;6424.38968298011;6431.774887878949;6436.549441539094;7007.8598428031155;7007.894881576781;7037.049998250273;7103.467239956921;7140.321669441395;6591.077249185118;6719.328067193242;6724.957631567115;6733.46132309414;6824.385805277549;6700.698468568926;6713.865722685519;6819.522633415712;6828.419584335288;6844.041834143224;6576.473882089422;6678.532502724763;6764.154480689914;6764.898526522106;6776.11910451648;6662.6335551545135;6675.532712710161;6702.899456508157;6757.391834734897;6759.831004224934;4948.876278093005;4950.455449900508;5011.473795251519;5020.046191223656;5020.698758074527;4569.771511424416;4593;4639.443266807937;4646.3495110684535;4665.696729712626;6047.599313243788;6171.321154672983;6197.907031394533;6252.3122456530655;6253.624782512973;6598.505112116611;6713;6726.293739157416;6765.9661701691175;6788.558743681645;6068.787592434243;6144.662043587646;6223.346548612421;6228.750849966042;6234.657093859882;5452.427495113036;5485.698286594195;5533.446655334435;5558.610897237236;5561.527270181999;6726.7981960541265;6728.629925354153;6779.966100169468;6797;6818.965905170442;6569.37590928861;6591.670416479253;6710.194776626836;6729.2934241903895;6823.1812182538415;6914.170299564084;6918.238993710788;6922.8961565577565;7017.157941047107;7035.577865328097;6632.966835165793;6640.136782218281;6641.203055633355;6670.232923213698;6753.662316884234;6673.032410300576;6739.056532085414;6778.4916131289365;6803.149606299213;6865.141857267841;6536.379043990813;6607.2071351438135;6666.066750654815;6672.399484046459;6680.331966803476;6580.670966451563;6587.143671322698;6587.934120658732;6679.799606011828;6688.565243259367;6317.494600431863;6421.261554921057;6444.13004244421;6502.707378167899;6569.4744420447205;6434.742610295536;6522.380373864476;6523.217213934358;6627.536072474881;6639.502037347315;6373.107764912823;6385.904211436739;6443.96778016107;6465.709043092978;6472.902906456501 12:21:25 INFO - PID 10826 | |1;ext.html;944.9433034017959;955.7037318431292;965.1410244882055;1003.8694969653944;1010.8281592129342;42294.15411691768;43046.139077218475;43168.568314316886;43171.568284317145;43204.78397608015;43743.34384984228;44583;44700.55299447005;44787;44986.10027799442;7967.20327967203;8003.559804210766;8043.11525732172;8054.275115239628;8065.354771618257;8353.457025293366;8485.024222214432;8498.915010849874;8501.277391421736;8510.319174466058;8548.572571371438;8561.95719021404;8564.229219370256;8571.528565928871;8588.8711669325;8433.15668433158;8479.109693482189;8533.402661813676;8539.274161696232;8540.914590854074;8328.08391076981;8394.748157555283;8394.958025209835;8400.747977560683;8405.747827565183;41168.58831411708;41385.58614413848;41389;41476.792616037026;41524.584754152376;3630.4735813306925;3633;3638.9818050909576;3646.21606354635;3684.5578530576236;32672.203028833406;32690.509642355384;32765.344693106243;33069.3386132276;33085.17287067819;5160.8193713220235;5397.352317721859;5408;5482.862928426821;5508.228847961289;226401;228902;229160;230661;230718;50527.74736126296;50554.74722626437;50628.49371506238;50752.492475075516;50818.237726434134;59199;59938;60161.699191503765;60321.396786032456;60445.69777151086;16829.83170168307;17100.486985390457;17129.14354282281;17142.142892855558;17152.399666011628;13799.448022079005;13860.792088118485;13866.4453421862;13872.653183670489;13901.235432051335;15660.530184094496;15715.764263536055;15752.212389380486;15966.441174558835;16001.119938403499;5899.646021238739;5974.880502389928;5983.282006159288;5983.970080149571;6037.335893051761;1088.755030118217;1098.873629532614;1107.0977153619774;1116.944152792357;1132.9603463878889;25490.49019019609;25955.351116222246;25983;26113.477730445666;26480.205593832216;1205.8974987126016;1214.2410993129295;1224.5530381410722;1229.7847876621556;1232.119034890058;13765.724685506435;13843.615638436077;13930.442782288797;14080.507182248572;14216.289185540682;293865;293873;295634;296256;296837;51772;52363.73818130885;57067;57584.13623795647;58297.417025830044;1534.9079055256498;1539.738244498438;1539.8845086618549;1543.6835448732984;1561.2818103672212;29810.701892980793;29962.550561741155;30232.54651180278;30374.69625303807;30643.693563064084;41847.790761046;41932.58067419287;42016.15967680691;42068.36897446479;42180.57819421766;245629;245909;246731;248769;248991;35714.821425892704;35768.28463430769;35801.28397432089;36187;36235.27529449343;47353;47433.762831185624;47484.52515474801;47602.28596570985;47941;34949.82525087358;35050;35114.824425877705;35258.64741352656;35405.46891796677;33945;34202;34400.48399274063;34402.82798585991;35013.12467188341;39664.80167599144;40353.596464034985;40878.38682419706;40909;41090.58909410987;33575.66424335823;33710.83144584261;33814.66185338115;34298.82850585731;34376.48435273423;24929.252122436355;25794.742052579986;26395.340116497242;26440.603390949535;26975.595366069134 12:21:25 INFO - PID 10826 | |2;jquery.html;608.2883026858577;610.27682196597;619.3249358199562;620.5594028239949;630.3979699387086;23371.766282337172;23940.521189576197;24170.637440438386;24180.395490112733;24189.516209675796;25838.87080564599;26452.206433807016;26742.19773406801;26744.5988310175;26894.865525672394;6398.328175541571;6565.409113179837;6568.244651865025;6572.967135164317;6597.439217666504;6399.39205775453;6459.35406459355;6584.868302633944;6617.536772425932;6649.268580456146;6592.538522303441;6602.8679426411445;6606.570572912769;6610.471162307004;6612.2726500084955;6400.391962763577;6575.243846957614;6578.375054369827;6586.5718728282745;6602.537822352438;6228.968855155696;6246.781362652286;6258.49932005443;6261.78083767066;6265.279492858334;30475.085747427613;30656.693433065608;30893.536596951064;30991.38017239665;31101;2252.8085112765434;2272.534130503243;2277.39648993017;2280.1335492512903;2285.440067183534;20039.499012524804;20740.06669699867;21284.255051073294;21353.466163345885;21397.786022139735;2586.1982785336604;2605.648237487934;2637.129747183425;2639.181853625363;2659.4946960077523;143433;148183;158583;166512.16743916323;168703.15648421802;15747.291371888292;16074.839251607335;16804;16926.322947082226;17603.647927041387;26306.342341441235;26316.21051368462;26330.73669263283;26383.736162638128;26402.33994150123;10357;10738.194635402377;10789.298695584763;10792.352458852494;10873.293235939638;9695.951520242354;9768.853467197998;9790.06994335537;9793.608255669837;9856;9515.809683806285;9612;9620.3746756462;9926.30515863897;9974.80050398988;2948.508510308786;2951.439226546949;2953.424082303973;2974.7471464925725;3051.67957364478;1031.664708969573;1034.2450011491653;1036.4403222259896;1040.089921318846;1047.0838016735356;20241.797582024286;20489;20570.82927560111;21056.789432105485;21078.683819742717;1086.22877756792;1086.9021788039115;1087.1846115413439;1101.4437708956925;1113.9832902506469;11067.889321106846;11377.203595748219;11485.598004069818;11520.94239528797;11545.538178472934;648075;650528;659412;672402;684941;137370;138032;138487;139592;140136;1084.6962850401742;1136.9260998035102;1207.197213852778;1210.2435977514053;1268.422867595242;21802.92872177204;21955.341339759834;22185.66721499211;22256.553324033885;22318.330450086523;63740.72518549696;63956.04065938922;64278;66047.66976165089;66519.00221496585;81362;81591;82345;83355;83550.58224708837;15391.307391167647;15555.45555905538;15651.686966260839;15729.056256624639;15925.920370398075;23578.646320304866;23862.522749544565;24032.279031629078;24250.51498970046;24429.633555497036;19409.2236310546;19583.31458399006;19948.401547953585;19965.900170499055;19986;17215.56961075933;17518.124093795515;17782.644347113244;17783.911080444515;18271.26914923388;19413.805861941764;19614.607707846048;19885.707429017068;20194.89902550478;20275.29036483716;15252.237388130327;15259.313330899915;15379.307931142901;15406.229688515305;15433.305501252695;19346.709799353303;19524.21903123859;19690.40928772139;19740.60518789645;19751.40745777629 12:21:25 INFO - PID 10826 | |3;mootools.html;1440.7838824176374;1494.6114010357303;1513.7048275586258;1521.92390380481;1523.0937592132684;16727.41454049107;17465.82534174658;17479.126043697797;17492.212850421747;17508.82491175088;17018.914905425485;17029.744553831704;17085.65828683429;17100.401485947994;17225.7416138758;2669.532831754445;2713.8371697698103;2716.891324347034;2722.4963381774437;2732.194002769188;2498.950020999579;2638.1953504181215;2647.4308023774884;2647.9735202648017;2655.0839960213693;2630.9605355919675;2732.5491293936475;2733.412316351984;2743.7667798237176;2745.9038933637325;2696.3528753099313;2719.456108778242;2748.69764325924;2748.738869807385;2751.3259251483314;2579.123098146639;2610.6214598883244;2642.630031795531;2649.9602505962425;2650.8011899107646;16307.59231019234;16392;16503.587410314718;16504.587385315343;16505;8334.833303333899;8625.568721563897;8632.697855575025;8642.827143457158;8694.087120852282;5490.450954904518;5613.158026296048;5656.321241451011;5683.54531637468;5730.942690573124;2117.735283089614;2135.071244008861;2154.8707077575395;2181.094845639058;2188.715466989297;28515.144545663665;29083.12750617485;29159.125226243035;29261.414771704447;29300.706992930012;6754.527183097234;6955.200151982587;6970.198427180939;7072.681729322193;7096.254893236239;19814.603707925762;19878.1054852532;19889.701654475193;19890.104945277497;19965.401037968884;8017.078036025931;8209.38429617781;8239.382046346547;8243.629036693363;8316.459430137022;7277.963610181915;7287.307705767944;7340.853182936418;7392.297731715478;7423.85152296951;5575.470330318613;5611.915821262684;5637.915431268534;5685.204071430003;5713.800016999468;1562.093985488414;1580.2651766928434;1587.6745267220192;1591.4429949517576;1599.6560739440843;962.5283611030544;976.272676855747;990.3166814897697;990.6087095597198;994.0556471352244;8201.30288925448;8221.383396245306;8226.835463290701;8250.669973201006;8254.83490330202;808.4421748993176;815.5269943432795;816.1715858403725;820.5527987246965;825.2036784502983;5151.6908985461;5708.143778433268;5737.942620573825;6020;6059.666718330446;24721.752782471944;25314.367140821625;25464.872675636503;25774.871125644626;25775.87112064428;24317.756822431904;24636;24725.258242252763;25056;25459.236222913343;12366.567170149181;12617.87382126167;13011.154274972096;13019.088663793444;13055.673608159683;9293.628254869729;9394.483303418378;9643.373180743229;9649.228061755182;9790.75523111928;21076.367708968755;21166.89416552969;21213.787862121797;21521.24675636408;22057.448563786034;22132.778672213073;22217.555648887253;22219.222327219115;22361.329160125224;22618.660720088883;13386.732265354833;13841;13856.861431385558;13896.305184740924;13923.23422211788;15721.842781572037;15806.9209653951;15926;15992.12043337581;16094.597635059217;12688.429020694275;12718.554850580185;12774.42515086842;12944.482220711443;13014.609561713163;12439.315837629018;12460.750784984431;12524.624261272176;12591.370431478572;12876.742465150832;10268.64059757905;10287.331323463952;10328.483575821028;10347.120494757728;10358.533865975898;8018.358531317597;8108.270255677017;8125.674973001013;8127.674893004214;8188.181181881764;8232.83534329322;8250.628721707417;8268.214519620626;8309.127541608044;8347 12:21:25 INFO - PID 10826 | |4;prototype.html;557.9414161513041;577.1141298107406;579.7304253522111;582.5659883386877;587.6885250817068;6241.282252540959;6277.748890044399;6284.717187726552;6305.621662700241;6378.298387177407;6174.660393678346;6182.629042257457;6198.535109866755;6239.251289845225;6246;5148.974255128728;5157.484251574831;5177.844664660066;5185.092608793465;5198.9480105198845;4861.368022157132;5102.540771330579;5113.284140220372;5193.16909294513;5194.0910340690225;4943.937053533507;4983.202687569989;4990.600751939836;4993.450720420769;5025.271335656327;4980.651354405193;4981.900361992758;4997.275395067732;5020.748962551857;5022.17134172861;4498.572635599613;4539.114872599845;4552.863414097583;4557.361969324297;4559.863204103882;6140.785072522485;6154.784582539634;6161.9691901540655;6168.228971378578;6174.981128113855;5848.67832269225;5860.472557469848;5862.765489380419;5888.793892213752;5899.793507227227;6642.501812364092;6655.46756259498;6668.19981602206;6674.966625166891;6738.494612904003;5099.490050994871;5108.948910510884;5128.051310507568;5128.461511541272;5136.5890728741615;6703.55320308084;6767.4924380671155;6799.762008329685;6938.687759050805;7001.229864714877;5224.9738751306;5226.712530810766;5248.373004368658;5256.474352564713;5277.313949186581;5303.655262407931;5306.363236411578;5362.249285100089;5365.356157261075;5377.489138531833;5918.763249469973;5934.1692163097205;5934.347221805598;5953.761849526057;5964.970175149097;6587.374199451045;6655.567388119756;6668.266490686021;6680.5991640501725;6725.578791635007;1805.3410505165782;1807.9965619081536;1833.3949796567101;1843.7831031519402;1845.3633496443706;1257.7057121198388;1274.796032634774;1309.9476020959055;1320.320035181882;1328.1632571479906;5959.553033522503;6054.8183554493435;6061.909071363933;6084.330723620398;6091.939080609137;6596.769113081019;6670.299618540081;6723.193216814014;6765;6830.316968303229;5969.432903874125;5985.551083668743;5998;6007.36922623127;6043.758249669964;3547.6452354764833;3551.875684351087;3554.893353199408;3570.393033184365;3575.1955809943074;6730.966345168243;6795.422389096883;6804.829879253059;6826.829329266808;6921.861562768717;6915.89626155608;6948.93051069493;6950.443964482829;6971.965140174369;7012;6833.726650933907;6842.315768423118;6854.383105520427;6873.347032032049;6926.445884329241;6706;6714.3957043866285;6726.199697171082;6767.255601883888;6780.38976492118;6662.200535935719;6677.332266773284;6683.799486015428;6695.866082678222;6696.263411024881;6791.592504449749;6846.281140480178;6847.727250017559;6909.170899492093;6941;5114.979583064594;5162.457941916045;5221.686698798084;5225.320708307896;5230;6505.381988711065;6522.836929076621;6592.297158165129;6649.202095748541;6712.878511492179;6448.871022579428;6484.206739057704;6517.73929042833;6597.208334999639;6610.570312929644;6447.935520644921;6489.253735820441;6532.216134063943;6536.379043990813;6539.607623542603;6439.8068057958335;6462.256840463218;6482.837929051811;6498.187726534184;6504.772332968323;6477.255115661759;6584.38682522785;6666.866662666816;6684.364985326386;6690.632015239208;6421.614703117828;6451;6453.903191452226;6481.731394012247;6484.837879052872 12:21:25 INFO - PID 10826 | |5;yui.html;941.830470515307;973.4840534516704;977.1840513171503;981.1954197558001;1003.4230317567399;18157.636847263064;18472.815271847277;18517.907410462947;18543.72184417233;18560.814391856078;17596.560085997855;18355.724664130048;18408;19695.803041969575;19966.60066798667;1060.141285558698;1093.2128867215592;1093.7320356512666;1093.742970401959;1094.4035500652144;1031.7265924530006;1084.7559299157665;1089.5315014543744;1090.841827934949;1094.5512339940626;1133.4785998440686;1144.7252659361734;1146.323669035269;1146.9713257168596;1147.638493874431;1054.6150655010943;1079.5465904320204;1144.1075960750586;1145.73075327298;1147.3058799426394;817.2603793566832;819.0171793847347;819.7950512371907;819.9914105650024;824.2128767027474;21264.14943402262;21516.784832151636;21556.245531406483;21603.35189944304;21699.891500542555;22260.332190034325;23176.65235021459;23180.956856941477;23276.418089547726;23469.76530234693;18471.63056738871;18821.15304811287;18965;18978.335758248395;19002.23991040357;3861.2663593917346;3884.5921178276158;3895.1625400538737;3897.259520691088;3898.1813819098024;160260.85957825033;161368.19315903346;162449;163770;166926.1653691736;16687.749683754755;16791.91604041972;16902;17018.829811701973;17045.573860653334;32386.35227295441;33317.50023749645;33569.16077098092;34157.658423415945;34521.48217776736;8537.871931921027;8597.742067737852;8608.870866937;8617.310615150773;8635.740927772176;4320.373545835899;4533.274676051816;4576.290674945389;4602.953970460319;4607.769611519478;5996.370381110008;6185.783497577653;6191.133241346215;6223.439890409885;6230.283517395557;1031.0771859185986;1045.7385653586603;1070.0369667299337;1072.3244356055634;1073.838924161382;743.0600290632354;749.6439191384158;750.6096829648661;764.1556080531044;766.9309762121325;13279.336033198302;13608.523701670394;13898.23559704226;13898.930505347409;13994.370253338624;752.2064222245474;753.2994315286836;758.5486635451897;760.3195140349357;768.0092680442278;6039.879202416015;6111.174991376196;6184.5980011299125;6196.907046394308;6316.652584107918;158614;158786;160065;160143;161528;140494;140536;141027.29486352502;141062;145070;114463;117093.41453292679;117292;120630.3968480152;121005.39497302457;11615.245009074382;11672.766544669228;11692.47383867711;11769.411529423323;11899.345535995604;58005;58494.707526462094;58852.70573647104;59295.703521482115;59589;72634.91047634184;73239.26760732324;73936.63031684807;75196;75788.62105689436;15342.23288835576;15761.211939402754;15778.211089445711;15790.131542765259;15914.363425462852;18620.627587447903;18771.155298011894;18775.812241877407;19035.143418546475;19254.133563989933;15683.921580392025;15861.920690396475;15916.602084947506;16065.11641859709;16093;17460.650786984443;17552.736708949633;17561.648767024843;17789.110544472984;17930.731039034687;17307.221175047405;17613.20740566703;17633.559161021076;17820.198091085673;17859.910700446413;11871.406429678655;12143.817842732542;12162.635120946386;12214.938925305316;12261.26432414058;11943.402829858645;12079.818802718142;12164.75670486603;12321.156545894499;12376.07179461562 12:21:25 INFO - PID 10826 | __end_tp_report 12:21:25 INFO - PID 10826 | __start_cc_report 12:21:25 INFO - PID 10826 | _x_x_mozilla_cycle_collect,269 12:21:25 INFO - PID 10826 | __end_cc_report 12:21:25 INFO - PID 10826 | __startTimestamp1506453685024__endTimestamp 12:21:25 INFO - PID 10826 | 12:21:25 INFO - PID 10826 | ------- Summary: start ------- 12:21:25 INFO - PID 10826 | Number of tests: 6 12:21:25 INFO - PID 10826 | 12:21:25 INFO - PID 10826 | [#0] dojo.html Cycles:165 Average:6197.95 Median:6465.71 stddev:853.10 (13.2%) stddev-sans-first:835.36 12:21:25 INFO - PID 10826 | Values: 3836.4 3841.4 3854.4 3869.4 3870.9 6094.8 6146.9 6260.9 6276.6 6307.0 6185.4 6229.3 6231.8 6241.3 6280.9 5541.7 5618.5 5627.6 5667.8 5720.3 214.9 5665.6 5694.7 5734.1 5735.8 5560.1 5565.7 5581.1 5698.9 5705.3 5576.6 5618.4 5697.2 5706.5 5708.0 5591.2 5625.9 5678.3 5681.0 5696.0 6216.6 6256.7 6263.4 6285.9 6317.0 6782.6 6795.3 6812.4 6888.0 6919.7 6741.5 6760.4 6823.6 6849.7 6859.6 6316.9 6342.4 6424.4 6431.8 6436.5 7007.9 7007.9 7037.0 7103.5 7140.3 6591.1 6719.3 6725.0 6733.5 6824.4 6700.7 6713.9 6819.5 6828.4 6844.0 6576.5 6678.5 6764.2 6764.9 6776.1 6662.6 6675.5 6702.9 6757.4 6759.8 4948.9 4950.5 5011.5 5020.0 5020.7 4569.8 4593.0 4639.4 4646.3 4665.7 6047.6 6171.3 6197.9 6252.3 6253.6 6598.5 6713.0 6726.3 6766.0 6788.6 6068.8 6144.7 6223.3 6228.8 6234.7 5452.4 5485.7 5533.4 5558.6 5561.5 6726.8 6728.6 6780.0 6797.0 6819.0 6569.4 6591.7 6710.2 6729.3 6823.2 6914.2 6918.2 6922.9 7017.2 7035.6 6633.0 6640.1 6641.2 6670.2 6753.7 6673.0 6739.1 6778.5 6803.1 6865.1 6536.4 6607.2 6666.1 6672.4 6680.3 6580.7 6587.1 6587.9 6679.8 6688.6 6317.5 6421.3 6444.1 6502.7 6569.5 6434.7 6522.4 6523.2 6627.5 6639.5 6373.1 6385.9 6444.0 6465.7 6472.9 12:21:25 INFO - PID 10826 | 12:21:25 INFO - PID 10826 | [#1] ext.html Cycles:180 Average:43721.78 Median:36211.14 stddev:67281.91 (185.8%) stddev-sans-first:67393.99 12:21:25 INFO - PID 10826 | Values: 944.9 955.7 965.1 1003.9 1010.8 42294.2 43046.1 43168.6 43171.6 43204.8 43743.3 44583.0 44700.6 44787.0 44986.1 7967.2 8003.6 8043.1 8054.3 8065.4 8353.5 8485.0 8498.9 8501.3 8510.3 8548.6 8562.0 8564.2 8571.5 8588.9 8433.2 8479.1 8533.4 8539.3 8540.9 8328.1 8394.7 8395.0 8400.7 8405.7 41168.6 41385.6 41389.0 41476.8 41524.6 3630.5 3633.0 3639.0 3646.2 3684.6 32672.2 32690.5 32765.3 33069.3 33085.2 5160.8 5397.4 5408.0 5482.9 5508.2 226401.0 228902.0 229160.0 230661.0 230718.0 50527.7 50554.7 50628.5 50752.5 50818.2 59199.0 59938.0 60161.7 60321.4 60445.7 16829.8 17100.5 17129.1 17142.1 17152.4 13799.4 13860.8 13866.4 13872.7 13901.2 15660.5 15715.8 15752.2 15966.4 16001.1 5899.6 5974.9 5983.3 5984.0 6037.3 1088.8 1098.9 1107.1 1116.9 1133.0 25490.5 25955.4 25983.0 26113.5 26480.2 1205.9 1214.2 1224.6 1229.8 1232.1 13765.7 13843.6 13930.4 14080.5 14216.3 293865.0 293873.0 295634.0 296256.0 296837.0 51772.0 52363.7 57067.0 57584.1 58297.4 1534.9 1539.7 1539.9 1543.7 1561.3 29810.7 29962.6 30232.5 30374.7 30643.7 41847.8 41932.6 42016.2 42068.4 42180.6 245629.0 245909.0 246731.0 248769.0 248991.0 35714.8 35768.3 35801.3 36187.0 36235.3 47353.0 47433.8 47484.5 47602.3 47941.0 34949.8 35050.0 35114.8 35258.6 35405.5 33945.0 34202.0 34400.5 34402.8 35013.1 39664.8 40353.6 40878.4 40909.0 41090.6 33575.7 33710.8 33814.7 34298.8 34376.5 24929.3 25794.7 26395.3 26440.6 26975.6 12:21:25 INFO - PID 10826 | 12:21:25 INFO - PID 10826 | [#2] jquery.html Cycles:180 Average:42005.91 Median:23475.21 stddev:110753.57 (471.8%) stddev-sans-first:111020.65 12:21:25 INFO - PID 10826 | Values: 608.3 610.3 619.3 620.6 630.4 23371.8 23940.5 24170.6 24180.4 24189.5 25838.9 26452.2 26742.2 26744.6 26894.9 6398.3 6565.4 6568.2 6573.0 6597.4 6399.4 6459.4 6584.9 6617.5 6649.3 6592.5 6602.9 6606.6 6610.5 6612.3 6400.4 6575.2 6578.4 6586.6 6602.5 6229.0 6246.8 6258.5 6261.8 6265.3 30475.1 30656.7 30893.5 30991.4 31101.0 2252.8 2272.5 2277.4 2280.1 2285.4 20039.5 20740.1 21284.3 21353.5 21397.8 2586.2 2605.6 2637.1 2639.2 2659.5 143433.0 148183.0 158583.0 166512.2 168703.2 15747.3 16074.8 16804.0 16926.3 17603.6 26306.3 26316.2 26330.7 26383.7 26402.3 10357.0 10738.2 10789.3 10792.4 10873.3 9696.0 9768.9 9790.1 9793.6 9856.0 9515.8 9612.0 9620.4 9926.3 9974.8 2948.5 2951.4 2953.4 2974.7 3051.7 1031.7 1034.2 1036.4 1040.1 1047.1 20241.8 20489.0 20570.8 21056.8 21078.7 1086.2 1086.9 1087.2 1101.4 1114.0 11067.9 11377.2 11485.6 11520.9 11545.5 648075.0 650528.0 659412.0 672402.0 684941.0 137370.0 138032.0 138487.0 139592.0 140136.0 1084.7 1136.9 1207.2 1210.2 1268.4 21802.9 21955.3 22185.7 22256.6 22318.3 63740.7 63956.0 64278.0 66047.7 66519.0 81362.0 81591.0 82345.0 83355.0 83550.6 15391.3 15555.5 15651.7 15729.1 15925.9 23578.6 23862.5 24032.3 24250.5 24429.6 19409.2 19583.3 19948.4 19965.9 19986.0 17215.6 17518.1 17782.6 17783.9 18271.3 19413.8 19614.6 19885.7 20194.9 20275.3 15252.2 15259.3 15379.3 15406.2 15433.3 19346.7 19524.2 19690.4 19740.6 19751.4 12:21:25 INFO - PID 10826 | 12:21:25 INFO - PID 10826 | [#3] mootools.html Cycles:180 Average:10410.00 Median:2645.03 stddev:7728.98 (292.2%) stddev-sans-first:7721.28 12:21:25 INFO - PID 10826 | Values: 1440.8 1494.6 1513.7 1521.9 1523.1 16727.4 17465.8 17479.1 17492.2 17508.8 17018.9 17029.7 17085.7 17100.4 17225.7 2669.5 2713.8 2716.9 2722.5 2732.2 2499.0 2638.2 2647.4 2648.0 2655.1 2631.0 2732.5 2733.4 2743.8 2745.9 2696.4 2719.5 2748.7 2748.7 2751.3 2579.1 2610.6 2642.6 2650.0 2650.8 16307.6 16392.0 16503.6 16504.6 16505.0 8334.8 8625.6 8632.7 8642.8 8694.1 5490.5 5613.2 5656.3 5683.5 5730.9 2117.7 2135.1 2154.9 2181.1 2188.7 28515.1 29083.1 29159.1 29261.4 29300.7 6754.5 6955.2 6970.2 7072.7 7096.3 19814.6 19878.1 19889.7 19890.1 19965.4 8017.1 8209.4 8239.4 8243.6 8316.5 7278.0 7287.3 7340.9 7392.3 7423.9 5575.5 5611.9 5637.9 5685.2 5713.8 1562.1 1580.3 1587.7 1591.4 1599.7 962.5 976.3 990.3 990.6 994.1 8201.3 8221.4 8226.8 8250.7 8254.8 808.4 815.5 816.2 820.6 825.2 5151.7 5708.1 5737.9 6020.0 6059.7 24721.8 25314.4 25464.9 25774.9 25775.9 24317.8 24636.0 24725.3 25056.0 25459.2 12366.6 12617.9 13011.2 13019.1 13055.7 9293.6 9394.5 9643.4 9649.2 9790.8 21076.4 21166.9 21213.8 21521.2 22057.4 22132.8 22217.6 22219.2 22361.3 22618.7 13386.7 13841.0 13856.9 13896.3 13923.2 15721.8 15806.9 15926.0 15992.1 16094.6 12688.4 12718.6 12774.4 12944.5 13014.6 12439.3 12460.8 12524.6 12591.4 12876.7 10268.6 10287.3 10328.5 10347.1 10358.5 8018.4 8108.3 8125.7 8127.7 8188.2 8232.8 8250.6 8268.2 8309.1 8347.0 12:21:25 INFO - PID 10826 | 12:21:25 INFO - PID 10826 | [#4] prototype.html Cycles:180 Average:5625.38 Median:6178.81 stddev:1550.96 (25.1%) stddev-sans-first:1507.96 12:21:25 INFO - PID 10826 | Values: 557.9 577.1 579.7 582.6 587.7 6241.3 6277.7 6284.7 6305.6 6378.3 6174.7 6182.6 6198.5 6239.3 6246.0 5149.0 5157.5 5177.8 5185.1 5198.9 4861.4 5102.5 5113.3 5193.2 5194.1 4943.9 4983.2 4990.6 4993.5 5025.3 4980.7 4981.9 4997.3 5020.7 5022.2 4498.6 4539.1 4552.9 4557.4 4559.9 6140.8 6154.8 6162.0 6168.2 6175.0 5848.7 5860.5 5862.8 5888.8 5899.8 6642.5 6655.5 6668.2 6675.0 6738.5 5099.5 5108.9 5128.1 5128.5 5136.6 6703.6 6767.5 6799.8 6938.7 7001.2 5225.0 5226.7 5248.4 5256.5 5277.3 5303.7 5306.4 5362.2 5365.4 5377.5 5918.8 5934.2 5934.3 5953.8 5965.0 6587.4 6655.6 6668.3 6680.6 6725.6 1805.3 1808.0 1833.4 1843.8 1845.4 1257.7 1274.8 1309.9 1320.3 1328.2 5959.6 6054.8 6061.9 6084.3 6091.9 6596.8 6670.3 6723.2 6765.0 6830.3 5969.4 5985.6 5998.0 6007.4 6043.8 3547.6 3551.9 3554.9 3570.4 3575.2 6731.0 6795.4 6804.8 6826.8 6921.9 6915.9 6948.9 6950.4 6972.0 7012.0 6833.7 6842.3 6854.4 6873.3 6926.4 6706.0 6714.4 6726.2 6767.3 6780.4 6662.2 6677.3 6683.8 6695.9 6696.3 6791.6 6846.3 6847.7 6909.2 6941.0 5115.0 5162.5 5221.7 5225.3 5230.0 6505.4 6522.8 6592.3 6649.2 6712.9 6448.9 6484.2 6517.7 6597.2 6610.6 6447.9 6489.3 6532.2 6536.4 6539.6 6439.8 6462.3 6482.8 6498.2 6504.8 6477.3 6584.4 6666.9 6684.4 6690.6 6421.6 6451.0 6453.9 6481.7 6484.8 12:21:25 INFO - PID 10826 | 12:21:25 INFO - PID 10826 | [#5] yui.html Cycles:180 Average:28901.66 Median:17895.32 stddev:44462.42 (248.5%) stddev-sans-first:44537.59 12:21:25 INFO - PID 10826 | Values: 941.8 973.5 977.2 981.2 1003.4 18157.6 18472.8 18517.9 18543.7 18560.8 17596.6 18355.7 18408.0 19695.8 19966.6 1060.1 1093.2 1093.7 1093.7 1094.4 1031.7 1084.8 1089.5 1090.8 1094.6 1133.5 1144.7 1146.3 1147.0 1147.6 1054.6 1079.5 1144.1 1145.7 1147.3 817.3 819.0 819.8 820.0 824.2 21264.1 21516.8 21556.2 21603.4 21699.9 22260.3 23176.7 23181.0 23276.4 23469.8 18471.6 18821.2 18965.0 18978.3 19002.2 3861.3 3884.6 3895.2 3897.3 3898.2 160260.9 161368.2 162449.0 163770.0 166926.2 16687.7 16791.9 16902.0 17018.8 17045.6 32386.4 33317.5 33569.2 34157.7 34521.5 8537.9 8597.7 8608.9 8617.3 8635.7 4320.4 4533.3 4576.3 4603.0 4607.8 5996.4 6185.8 6191.1 6223.4 6230.3 1031.1 1045.7 1070.0 1072.3 1073.8 743.1 749.6 750.6 764.2 766.9 13279.3 13608.5 13898.2 13898.9 13994.4 752.2 753.3 758.5 760.3 768.0 6039.9 6111.2 6184.6 6196.9 6316.7 158614.0 158786.0 160065.0 160143.0 161528.0 140494.0 140536.0 141027.3 141062.0 145070.0 114463.0 117093.4 117292.0 120630.4 121005.4 11615.2 11672.8 11692.5 11769.4 11899.3 58005.0 58494.7 58852.7 59295.7 59589.0 72634.9 73239.3 73936.6 75196.0 75788.6 15342.2 15761.2 15778.2 15790.1 15914.4 18620.6 18771.2 18775.8 19035.1 19254.1 15683.9 15861.9 15916.6 16065.1 16093.0 17460.7 17552.7 17561.6 17789.1 17930.7 17307.2 17613.2 17633.6 17820.2 17859.9 11871.4 12143.8 12162.6 12214.9 12261.3 11943.4 12079.8 12164.8 12321.2 12376.1 12:21:25 INFO - PID 10826 | -------- Summary: end -------- 12:21:25 INFO - PID 10826 | 12:21:25 INFO - TEST-INFO | 10826: exit 0 12:21:25 INFO - TEST-OK | dromaeo_css | took 1107286ms 12:21:25 INFO - TEST-START | kraken 12:21:25 INFO - Initialising browser for kraken test... 12:21:25 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:54778/getInfo.html -profile /tmp/tmp7tf4PR/profile 12:21:25 INFO - TEST-INFO | started process 11199 (/builds/slave/test/build/application/firefox/firefox http://localhost:54778/getInfo.html) 12:21:31 INFO - TEST-INFO | 11199: exit 0 12:21:31 INFO - Browser initialized. 12:21:31 INFO - Running cycle 1/1 for kraken test... 12:21:31 INFO - Using env: {'DISPLAY': ':0', 12:21:31 INFO - 'HOME': '/home/cltbld', 12:21:31 INFO - 'JSGC_DISABLE_POISONING': '1', 12:21:31 INFO - 'LANG': 'en_US.UTF-8', 12:21:31 INFO - 'LANGUAGE': 'en_US:en', 12:21:31 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 12:21:31 INFO - 'LOGNAME': 'cltbld', 12:21:31 INFO - 'MAIL': '/var/mail/cltbld', 12:21:31 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:21:31 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 12:21:31 INFO - 'MOZ_CRASHREPORTER': '1', 12:21:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:21:31 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 12:21:31 INFO - 'MOZ_NO_REMOTE': '1', 12:21:31 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:21:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:21:31 INFO - 'NO_EM_RESTART': '1', 12:21:31 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:21:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:21:31 INFO - 'PWD': '/builds/slave/test', 12:21:31 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:21:31 INFO - 'RUST_BACKTRACE': 'full', 12:21:31 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 12:21:31 INFO - 'SHELL': '/bin/bash', 12:21:31 INFO - 'SHLVL': '1', 12:21:31 INFO - 'TERM': 'linux', 12:21:31 INFO - 'TMOUT': '86400', 12:21:31 INFO - 'USER': 'cltbld', 12:21:31 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1506452519.880506-810758685', 12:21:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:21:31 INFO - '_': '/tools/buildbot/bin/python'} 12:21:31 INFO - TEST-INFO | started process 11440 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp7tf4PR/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 12:21:31 INFO - PID 11440 | 12:21:31 INFO - PID 11440 | (/builds/slave/test/build/application/firefox/firefox:11489): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 12:21:31 INFO - PID 11440 | 12:21:42 INFO - PID 11440 | RSS: Main: 144760832 12:21:42 INFO - PID 11440 | 12:21:44 INFO - PID 11440 | Cycle 1(1): loaded http://localhost:54778/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:54778/tests/kraken/driver.html?testName=audio-beat-detection) 12:21:45 INFO - PID 11440 | RSS: Main: 150368256 12:21:45 INFO - PID 11440 | 12:21:48 INFO - PID 11440 | Cycle 1(1): loaded http://localhost:54778/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:54778/tests/kraken/driver.html?testName=audio-dft) 12:21:48 INFO - PID 11440 | RSS: Main: 150777856 12:21:48 INFO - PID 11440 | 12:21:51 INFO - PID 11440 | Cycle 1(1): loaded http://localhost:54778/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:54778/tests/kraken/driver.html?testName=audio-fft) 12:21:52 INFO - PID 11440 | RSS: Main: 146952192 12:21:52 INFO - PID 11440 | 12:21:54 INFO - PID 11440 | Cycle 1(1): loaded http://localhost:54778/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:54778/tests/kraken/driver.html?testName=audio-oscillator) 12:21:55 INFO - PID 11440 | RSS: Main: 148037632 12:21:55 INFO - PID 11440 | 12:21:57 INFO - PID 11440 | Cycle 1(1): loaded http://localhost:54778/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:54778/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:21:57 INFO - PID 11440 | RSS: Main: 148242432 12:21:57 INFO - PID 11440 | 12:22:00 INFO - PID 11440 | Cycle 1(1): loaded http://localhost:54778/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:54778/tests/kraken/driver.html?testName=imaging-darkroom) 12:22:01 INFO - PID 11440 | RSS: Main: 148504576 12:22:01 INFO - PID 11440 | 12:22:05 INFO - PID 11440 | Cycle 1(1): loaded http://localhost:54778/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:54778/tests/kraken/driver.html?testName=imaging-desaturate) 12:22:05 INFO - PID 11440 | RSS: Main: 149385216 12:22:05 INFO - PID 11440 | 12:22:08 INFO - PID 11440 | Cycle 1(1): loaded http://localhost:54778/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:54778/tests/kraken/driver.html?testName=json-parse-financial) 12:22:09 INFO - PID 11440 | RSS: Main: 150835200 12:22:09 INFO - PID 11440 | 12:22:10 INFO - PID 11440 | Cycle 1(1): loaded http://localhost:54778/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:54778/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:22:11 INFO - PID 11440 | RSS: Main: 151019520 12:22:11 INFO - PID 11440 | 12:22:12 INFO - PID 11440 | Cycle 1(1): loaded http://localhost:54778/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:54778/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:22:13 INFO - PID 11440 | RSS: Main: 150958080 12:22:13 INFO - PID 11440 | 12:22:15 INFO - PID 11440 | Cycle 1(1): loaded http://localhost:54778/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:54778/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:22:16 INFO - PID 11440 | RSS: Main: 147976192 12:22:16 INFO - PID 11440 | 12:22:18 INFO - PID 11440 | Cycle 1(1): loaded http://localhost:54778/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:54778/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:22:19 INFO - PID 11440 | RSS: Main: 148066304 12:22:19 INFO - PID 11440 | 12:22:21 INFO - PID 11440 | Cycle 1(1): loaded http://localhost:54778/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:54778/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:22:22 INFO - PID 11440 | RSS: Main: 147914752 12:22:22 INFO - PID 11440 | 12:22:24 INFO - PID 11440 | Cycle 1(1): loaded http://localhost:54778/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:54778/tests/kraken/driver.html?testName=ai-astar) 12:22:24 INFO - PID 11440 | RSS: Main: 149778432 12:22:24 INFO - PID 11440 | 12:22:24 INFO - PID 11440 | __start_tp_report 12:22:24 INFO - PID 11440 | _x_x_mozilla_page_load 12:22:24 INFO - PID 11440 | _x_x_mozilla_page_load_details 12:22:24 INFO - PID 11440 | |i|pagename|runs| 12:22:24 INFO - PID 11440 | |0;ai-astar;107;106;107;105;107;95;106;106;106;106 12:22:24 INFO - PID 11440 | |1;audio-beat-detection;112;120;115;114;114;116;116;114;114;113 12:22:24 INFO - PID 11440 | |2;audio-dft;165;162;162;161;162;163;163;163;166;171 12:22:24 INFO - PID 11440 | |3;audio-fft;74;73;74;73;78;73;73;74;74;81 12:22:24 INFO - PID 11440 | |4;audio-oscillator;79;77;77;79;78;78;78;77;77;78 12:22:24 INFO - PID 11440 | |5;imaging-gaussian-blur;110;114;114;110;114;114;115;113;114;113 12:22:24 INFO - PID 11440 | |6;imaging-darkroom;150;155;158;149;150;150;151;151;150;150 12:22:24 INFO - PID 11440 | |7;imaging-desaturate;95;94;95;94;94;94;94;100;97;94 12:22:24 INFO - PID 11440 | |8;json-parse-financial;47;48;47;46;46;45;48;47;46;48 12:22:24 INFO - PID 11440 | |9;json-stringify-tinderbox;37;36;37;36;36;36;36;36;36;36 12:22:24 INFO - PID 11440 | |10;stanford-crypto-aes;80;72;72;69;72;75;72;70;75;70 12:22:24 INFO - PID 11440 | |11;stanford-crypto-ccm;111;104;106;106;105;104;109;105;114;115 12:22:24 INFO - PID 11440 | |12;stanford-crypto-pbkdf2;150;152;161;150;149;151;157;150;151;156 12:22:24 INFO - PID 11440 | |13;stanford-crypto-sha256-iterative;69;67;60;58;59;64;63;61;63;60 12:22:24 INFO - PID 11440 | __end_tp_report 12:22:24 INFO - PID 11440 | __start_cc_report 12:22:24 INFO - PID 11440 | _x_x_mozilla_cycle_collect,684 12:22:24 INFO - PID 11440 | __end_cc_report 12:22:24 INFO - PID 11440 | __startTimestamp1506453744447__endTimestamp 12:22:24 INFO - PID 11440 | 12:22:24 INFO - PID 11440 | ------- Summary: start ------- 12:22:24 INFO - PID 11440 | Number of tests: 14 12:22:24 INFO - PID 11440 | 12:22:24 INFO - PID 11440 | [#0] ai-astar Cycles:10 Average:105.10 Median:106.50 stddev:3.60 (3.4%) stddev-sans-first:3.76 12:22:24 INFO - PID 11440 | Values: 107.0 106.0 107.0 105.0 107.0 95.0 106.0 106.0 106.0 106.0 12:22:24 INFO - PID 11440 | 12:22:24 INFO - PID 11440 | [#1] audio-beat-detection Cycles:10 Average:114.80 Median:114.50 stddev:2.20 (1.9%) stddev-sans-first:2.09 12:22:24 INFO - PID 11440 | Values: 112.0 120.0 115.0 114.0 114.0 116.0 116.0 114.0 114.0 113.0 12:22:24 INFO - PID 11440 | 12:22:24 INFO - PID 11440 | [#2] audio-dft Cycles:10 Average:163.80 Median:163.00 stddev:2.94 (1.8%) stddev-sans-first:3.08 12:22:24 INFO - PID 11440 | Values: 165.0 162.0 162.0 161.0 162.0 163.0 163.0 163.0 166.0 171.0 12:22:24 INFO - PID 11440 | 12:22:24 INFO - PID 11440 | [#3] audio-fft Cycles:10 Average:74.70 Median:74.00 stddev:2.67 (3.6%) stddev-sans-first:2.82 12:22:24 INFO - PID 11440 | Values: 74.0 73.0 74.0 73.0 78.0 73.0 73.0 74.0 74.0 81.0 12:22:24 INFO - PID 11440 | 12:22:24 INFO - PID 11440 | [#4] audio-oscillator Cycles:10 Average:77.80 Median:78.00 stddev:0.79 (1.0%) stddev-sans-first:0.71 12:22:24 INFO - PID 11440 | Values: 79.0 77.0 77.0 79.0 78.0 78.0 78.0 77.0 77.0 78.0 12:22:24 INFO - PID 11440 | 12:22:24 INFO - PID 11440 | [#5] imaging-gaussian-blur Cycles:10 Average:113.10 Median:114.00 stddev:1.73 (1.5%) stddev-sans-first:1.42 12:22:24 INFO - PID 11440 | Values: 110.0 114.0 114.0 110.0 114.0 114.0 115.0 113.0 114.0 113.0 12:22:24 INFO - PID 11440 | 12:22:24 INFO - PID 11440 | [#6] imaging-darkroom Cycles:10 Average:151.40 Median:150.50 stddev:2.84 (1.9%) stddev-sans-first:2.96 12:22:24 INFO - PID 11440 | Values: 150.0 155.0 158.0 149.0 150.0 150.0 151.0 151.0 150.0 150.0 12:22:24 INFO - PID 11440 | 12:22:24 INFO - PID 11440 | [#7] imaging-desaturate Cycles:10 Average:95.10 Median:94.00 stddev:1.97 (2.1%) stddev-sans-first:2.09 12:22:24 INFO - PID 11440 | Values: 95.0 94.0 95.0 94.0 94.0 94.0 94.0 100.0 97.0 94.0 12:22:24 INFO - PID 11440 | 12:22:24 INFO - PID 11440 | [#8] json-parse-financial Cycles:10 Average:46.80 Median:47.00 stddev:1.03 (2.2%) stddev-sans-first:1.09 12:22:24 INFO - PID 11440 | Values: 47.0 48.0 47.0 46.0 46.0 45.0 48.0 47.0 46.0 48.0 12:22:24 INFO - PID 11440 | 12:22:24 INFO - PID 11440 | [#9] json-stringify-tinderbox Cycles:10 Average:36.20 Median:36.00 stddev:0.42 (1.2%) stddev-sans-first:0.33 12:22:24 INFO - PID 11440 | Values: 37.0 36.0 37.0 36.0 36.0 36.0 36.0 36.0 36.0 36.0 12:22:24 INFO - PID 11440 | 12:22:24 INFO - PID 11440 | [#10] stanford-crypto-aes Cycles:10 Average:72.70 Median:72.00 stddev:3.23 (4.5%) stddev-sans-first:2.09 12:22:24 INFO - PID 11440 | Values: 80.0 72.0 72.0 69.0 72.0 75.0 72.0 70.0 75.0 70.0 12:22:24 INFO - PID 11440 | 12:22:24 INFO - PID 11440 | [#11] stanford-crypto-ccm Cycles:10 Average:107.90 Median:107.50 stddev:4.12 (3.8%) stddev-sans-first:4.22 12:22:24 INFO - PID 11440 | Values: 111.0 104.0 106.0 106.0 105.0 104.0 109.0 105.0 114.0 115.0 12:22:24 INFO - PID 11440 | 12:22:24 INFO - PID 11440 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:152.70 Median:151.50 stddev:3.95 (2.6%) stddev-sans-first:4.06 12:22:24 INFO - PID 11440 | Values: 150.0 152.0 161.0 150.0 149.0 151.0 157.0 150.0 151.0 156.0 12:22:24 INFO - PID 11440 | 12:22:24 INFO - PID 11440 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.40 Median:63.00 stddev:3.53 (5.6%) stddev-sans-first:2.83 12:22:24 INFO - PID 11440 | Values: 69.0 67.0 60.0 58.0 59.0 64.0 63.0 61.0 63.0 60.0 12:22:24 INFO - PID 11440 | -------- Summary: end -------- 12:22:24 INFO - PID 11440 | 12:22:24 INFO - PID 11440 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 12:22:25 INFO - TEST-INFO | 11440: exit 0 12:22:25 INFO - TEST-OK | kraken | took 59459ms 12:22:25 INFO - SUITE-END | took 1166s 12:22:25 INFO - Completed test suite (00:19:27) 12:22:25 INFO - javascript benchmark 12:22:25 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 9055.492998406846, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3836.4053571696386, 3841.385378339466, 3854.3832986722114, 3869.3808990561506, 3870.8645197418095, 6094.847628809271, 6146.907796383058, 6260.937390626093, 6276.560640755143, 6307.0, 6185.412385823351, 6229.314775374705, 6231.750729970796, 6241.251049874021, 6280.905786413196, 5541.695206763627, 5618.494335509814, 5627.634203776762, 5667.801626943058, 5720.2849643794525, 214.90716524476284, 5665.603407761459, 5694.6867922264255, 5734.053881109612, 5735.824156048, 5560.054790685573, 5565.749541270652, 5581.134924086753, 5698.943010569883, 5705.286839145107, 5576.581756368289, 5618.353889302742, 5697.1739097831, 5706.457886500817, 5707.971460142673, 5591.245181900432, 5625.887482250373, 5678.346990096151, 5681.034224181858, 5696.031674615326, 6216.564840461169, 6256.687165641745, 6263.404976527223, 6285.937140628581, 6317.0, 6782.559133656297, 6795.252522222551, 6812.420944219747, 6887.965560172217, 6919.688614012382, 6741.528093033489, 6760.391564759146, 6823.590584564942, 6849.691763870638, 6859.55412898165, 6316.9368306317265, 6342.429181373653, 6424.38968298011, 6431.774887878949, 6436.549441539094, 7007.8598428031155, 7007.894881576781, 7037.049998250273, 7103.467239956921, 7140.321669441395, 6591.077249185118, 6719.328067193242, 6724.957631567115, 6733.46132309414, 6824.385805277549, 6700.698468568926, 6713.865722685519, 6819.522633415712, 6828.419584335288, 6844.041834143224, 6576.473882089422, 6678.532502724763, 6764.154480689914, 6764.898526522106, 6776.11910451648, 6662.6335551545135, 6675.532712710161, 6702.899456508157, 6757.391834734897, 6759.831004224934, 4948.876278093005, 4950.455449900508, 5011.473795251519, 5020.046191223656, 5020.698758074527, 4569.771511424416, 4593.0, 4639.443266807937, 4646.3495110684535, 4665.696729712626, 6047.599313243788, 6171.321154672983, 6197.907031394533, 6252.3122456530655, 6253.624782512973, 6598.505112116611, 6713.0, 6726.293739157416, 6765.9661701691175, 6788.558743681645, 6068.787592434243, 6144.662043587646, 6223.346548612421, 6228.750849966042, 6234.657093859882, 5452.427495113036, 5485.698286594195, 5533.446655334435, 5558.610897237236, 5561.527270181999, 6726.7981960541265, 6728.629925354153, 6779.966100169468, 6797.0, 6818.965905170442, 6569.37590928861, 6591.670416479253, 6710.194776626836, 6729.2934241903895, 6823.1812182538415, 6914.170299564084, 6918.238993710788, 6922.8961565577565, 7017.157941047107, 7035.577865328097, 6632.966835165793, 6640.136782218281, 6641.203055633355, 6670.232923213698, 6753.662316884234, 6673.032410300576, 6739.056532085414, 6778.4916131289365, 6803.149606299213, 6865.141857267841, 6536.379043990813, 6607.2071351438135, 6666.066750654815, 6672.399484046459, 6680.331966803476, 6580.670966451563, 6587.143671322698, 6587.934120658732, 6679.799606011828, 6688.565243259367, 6317.494600431863, 6421.261554921057, 6444.13004244421, 6502.707378167899, 6569.4744420447205, 6434.742610295536, 6522.380373864476, 6523.217213934358, 6627.536072474881, 6639.502037347315, 6373.107764912823, 6385.904211436739, 6443.96778016107, 6465.709043092978, 6472.902906456501], "value": 6144.485352897131, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [944.9433034017959, 955.7037318431292, 965.1410244882055, 1003.8694969653944, 1010.8281592129342, 42294.15411691768, 43046.139077218475, 43168.568314316886, 43171.568284317145, 43204.78397608015, 43743.34384984228, 44583.0, 44700.55299447005, 44787.0, 44986.10027799442, 7967.20327967203, 8003.559804210766, 8043.11525732172, 8054.275115239628, 8065.354771618257, 8353.457025293366, 8485.024222214432, 8498.915010849874, 8501.277391421736, 8510.319174466058, 8548.572571371438, 8561.95719021404, 8564.229219370256, 8571.528565928871, 8588.8711669325, 8433.15668433158, 8479.109693482189, 8533.402661813676, 8539.274161696232, 8540.914590854074, 8328.08391076981, 8394.748157555283, 8394.958025209835, 8400.747977560683, 8405.747827565183, 41168.58831411708, 41385.58614413848, 41389.0, 41476.792616037026, 41524.584754152376, 3630.4735813306925, 3633.0, 3638.9818050909576, 3646.21606354635, 3684.5578530576236, 32672.203028833406, 32690.509642355384, 32765.344693106243, 33069.3386132276, 33085.17287067819, 5160.8193713220235, 5397.352317721859, 5408.0, 5482.862928426821, 5508.228847961289, 226401.0, 228902.0, 229160.0, 230661.0, 230718.0, 50527.74736126296, 50554.74722626437, 50628.49371506238, 50752.492475075516, 50818.237726434134, 59199.0, 59938.0, 60161.699191503765, 60321.396786032456, 60445.69777151086, 16829.83170168307, 17100.486985390457, 17129.14354282281, 17142.142892855558, 17152.399666011628, 13799.448022079005, 13860.792088118485, 13866.4453421862, 13872.653183670489, 13901.235432051335, 15660.530184094496, 15715.764263536055, 15752.212389380486, 15966.441174558835, 16001.119938403499, 5899.646021238739, 5974.880502389928, 5983.282006159288, 5983.970080149571, 6037.335893051761, 1088.755030118217, 1098.873629532614, 1107.0977153619774, 1116.944152792357, 1132.9603463878889, 25490.49019019609, 25955.351116222246, 25983.0, 26113.477730445666, 26480.205593832216, 1205.8974987126016, 1214.2410993129295, 1224.5530381410722, 1229.7847876621556, 1232.119034890058, 13765.724685506435, 13843.615638436077, 13930.442782288797, 14080.507182248572, 14216.289185540682, 293865.0, 293873.0, 295634.0, 296256.0, 296837.0, 51772.0, 52363.73818130885, 57067.0, 57584.13623795647, 58297.417025830044, 1534.9079055256498, 1539.738244498438, 1539.8845086618549, 1543.6835448732984, 1561.2818103672212, 29810.701892980793, 29962.550561741155, 30232.54651180278, 30374.69625303807, 30643.693563064084, 41847.790761046, 41932.58067419287, 42016.15967680691, 42068.36897446479, 42180.57819421766, 245629.0, 245909.0, 246731.0, 248769.0, 248991.0, 35714.821425892704, 35768.28463430769, 35801.28397432089, 36187.0, 36235.27529449343, 47353.0, 47433.762831185624, 47484.52515474801, 47602.28596570985, 47941.0, 34949.82525087358, 35050.0, 35114.824425877705, 35258.64741352656, 35405.46891796677, 33945.0, 34202.0, 34400.48399274063, 34402.82798585991, 35013.12467188341, 39664.80167599144, 40353.596464034985, 40878.38682419706, 40909.0, 41090.58909410987, 33575.66424335823, 33710.83144584261, 33814.66185338115, 34298.82850585731, 34376.48435273423, 24929.252122436355, 25794.742052579986, 26395.340116497242, 26440.603390949535, 26975.595366069134], "value": 18925.266502085833, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [608.2883026858577, 610.27682196597, 619.3249358199562, 620.5594028239949, 630.3979699387086, 23371.766282337172, 23940.521189576197, 24170.637440438386, 24180.395490112733, 24189.516209675796, 25838.87080564599, 26452.206433807016, 26742.19773406801, 26744.5988310175, 26894.865525672394, 6398.328175541571, 6565.409113179837, 6568.244651865025, 6572.967135164317, 6597.439217666504, 6399.39205775453, 6459.35406459355, 6584.868302633944, 6617.536772425932, 6649.268580456146, 6592.538522303441, 6602.8679426411445, 6606.570572912769, 6610.471162307004, 6612.2726500084955, 6400.391962763577, 6575.243846957614, 6578.375054369827, 6586.5718728282745, 6602.537822352438, 6228.968855155696, 6246.781362652286, 6258.49932005443, 6261.78083767066, 6265.279492858334, 30475.085747427613, 30656.693433065608, 30893.536596951064, 30991.38017239665, 31101.0, 2252.8085112765434, 2272.534130503243, 2277.39648993017, 2280.1335492512903, 2285.440067183534, 20039.499012524804, 20740.06669699867, 21284.255051073294, 21353.466163345885, 21397.786022139735, 2586.1982785336604, 2605.648237487934, 2637.129747183425, 2639.181853625363, 2659.4946960077523, 143433.0, 148183.0, 158583.0, 166512.16743916323, 168703.15648421802, 15747.291371888292, 16074.839251607335, 16804.0, 16926.322947082226, 17603.647927041387, 26306.342341441235, 26316.21051368462, 26330.73669263283, 26383.736162638128, 26402.33994150123, 10357.0, 10738.194635402377, 10789.298695584763, 10792.352458852494, 10873.293235939638, 9695.951520242354, 9768.853467197998, 9790.06994335537, 9793.608255669837, 9856.0, 9515.809683806285, 9612.0, 9620.3746756462, 9926.30515863897, 9974.80050398988, 2948.508510308786, 2951.439226546949, 2953.424082303973, 2974.7471464925725, 3051.67957364478, 1031.664708969573, 1034.2450011491653, 1036.4403222259896, 1040.089921318846, 1047.0838016735356, 20241.797582024286, 20489.0, 20570.82927560111, 21056.789432105485, 21078.683819742717, 1086.22877756792, 1086.9021788039115, 1087.1846115413439, 1101.4437708956925, 1113.9832902506469, 11067.889321106846, 11377.203595748219, 11485.598004069818, 11520.94239528797, 11545.538178472934, 648075.0, 650528.0, 659412.0, 672402.0, 684941.0, 137370.0, 138032.0, 138487.0, 139592.0, 140136.0, 1084.6962850401742, 1136.9260998035102, 1207.197213852778, 1210.2435977514053, 1268.422867595242, 21802.92872177204, 21955.341339759834, 22185.66721499211, 22256.553324033885, 22318.330450086523, 63740.72518549696, 63956.04065938922, 64278.0, 66047.66976165089, 66519.00221496585, 81362.0, 81591.0, 82345.0, 83355.0, 83550.58224708837, 15391.307391167647, 15555.45555905538, 15651.686966260839, 15729.056256624639, 15925.920370398075, 23578.646320304866, 23862.522749544565, 24032.279031629078, 24250.51498970046, 24429.633555497036, 19409.2236310546, 19583.31458399006, 19948.401547953585, 19965.900170499055, 19986.0, 17215.56961075933, 17518.124093795515, 17782.644347113244, 17783.911080444515, 18271.26914923388, 19413.805861941764, 19614.607707846048, 19885.707429017068, 20194.89902550478, 20275.29036483716, 15252.237388130327, 15259.313330899915, 15379.307931142901, 15406.229688515305, 15433.305501252695, 19346.709799353303, 19524.21903123859, 19690.40928772139, 19740.60518789645, 19751.40745777629], "value": 12915.183374289145, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1440.7838824176374, 1494.6114010357303, 1513.7048275586258, 1521.92390380481, 1523.0937592132684, 16727.41454049107, 17465.82534174658, 17479.126043697797, 17492.212850421747, 17508.82491175088, 17018.914905425485, 17029.744553831704, 17085.65828683429, 17100.401485947994, 17225.7416138758, 2669.532831754445, 2713.8371697698103, 2716.891324347034, 2722.4963381774437, 2732.194002769188, 2498.950020999579, 2638.1953504181215, 2647.4308023774884, 2647.9735202648017, 2655.0839960213693, 2630.9605355919675, 2732.5491293936475, 2733.412316351984, 2743.7667798237176, 2745.9038933637325, 2696.3528753099313, 2719.456108778242, 2748.69764325924, 2748.738869807385, 2751.3259251483314, 2579.123098146639, 2610.6214598883244, 2642.630031795531, 2649.9602505962425, 2650.8011899107646, 16307.59231019234, 16392.0, 16503.587410314718, 16504.587385315343, 16505.0, 8334.833303333899, 8625.568721563897, 8632.697855575025, 8642.827143457158, 8694.087120852282, 5490.450954904518, 5613.158026296048, 5656.321241451011, 5683.54531637468, 5730.942690573124, 2117.735283089614, 2135.071244008861, 2154.8707077575395, 2181.094845639058, 2188.715466989297, 28515.144545663665, 29083.12750617485, 29159.125226243035, 29261.414771704447, 29300.706992930012, 6754.527183097234, 6955.200151982587, 6970.198427180939, 7072.681729322193, 7096.254893236239, 19814.603707925762, 19878.1054852532, 19889.701654475193, 19890.104945277497, 19965.401037968884, 8017.078036025931, 8209.38429617781, 8239.382046346547, 8243.629036693363, 8316.459430137022, 7277.963610181915, 7287.307705767944, 7340.853182936418, 7392.297731715478, 7423.85152296951, 5575.470330318613, 5611.915821262684, 5637.915431268534, 5685.204071430003, 5713.800016999468, 1562.093985488414, 1580.2651766928434, 1587.6745267220192, 1591.4429949517576, 1599.6560739440843, 962.5283611030544, 976.272676855747, 990.3166814897697, 990.6087095597198, 994.0556471352244, 8201.30288925448, 8221.383396245306, 8226.835463290701, 8250.669973201006, 8254.83490330202, 808.4421748993176, 815.5269943432795, 816.1715858403725, 820.5527987246965, 825.2036784502983, 5151.6908985461, 5708.143778433268, 5737.942620573825, 6020.0, 6059.666718330446, 24721.752782471944, 25314.367140821625, 25464.872675636503, 25774.871125644626, 25775.87112064428, 24317.756822431904, 24636.0, 24725.258242252763, 25056.0, 25459.236222913343, 12366.567170149181, 12617.87382126167, 13011.154274972096, 13019.088663793444, 13055.673608159683, 9293.628254869729, 9394.483303418378, 9643.373180743229, 9649.228061755182, 9790.75523111928, 21076.367708968755, 21166.89416552969, 21213.787862121797, 21521.24675636408, 22057.448563786034, 22132.778672213073, 22217.555648887253, 22219.222327219115, 22361.329160125224, 22618.660720088883, 13386.732265354833, 13841.0, 13856.861431385558, 13896.305184740924, 13923.23422211788, 15721.842781572037, 15806.9209653951, 15926.0, 15992.12043337581, 16094.597635059217, 12688.429020694275, 12718.554850580185, 12774.42515086842, 12944.482220711443, 13014.609561713163, 12439.315837629018, 12460.750784984431, 12524.624261272176, 12591.370431478572, 12876.742465150832, 10268.64059757905, 10287.331323463952, 10328.483575821028, 10347.120494757728, 10358.533865975898, 8018.358531317597, 8108.270255677017, 8125.674973001013, 8127.674893004214, 8188.181181881764, 8232.83534329322, 8250.628721707417, 8268.214519620626, 8309.127541608044, 8347.0], "value": 7214.963987857932, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [557.9414161513041, 577.1141298107406, 579.7304253522111, 582.5659883386877, 587.6885250817068, 6241.282252540959, 6277.748890044399, 6284.717187726552, 6305.621662700241, 6378.298387177407, 6174.660393678346, 6182.629042257457, 6198.535109866755, 6239.251289845225, 6246.0, 5148.974255128728, 5157.484251574831, 5177.844664660066, 5185.092608793465, 5198.9480105198845, 4861.368022157132, 5102.540771330579, 5113.284140220372, 5193.16909294513, 5194.0910340690225, 4943.937053533507, 4983.202687569989, 4990.600751939836, 4993.450720420769, 5025.271335656327, 4980.651354405193, 4981.900361992758, 4997.275395067732, 5020.748962551857, 5022.17134172861, 4498.572635599613, 4539.114872599845, 4552.863414097583, 4557.361969324297, 4559.863204103882, 6140.785072522485, 6154.784582539634, 6161.9691901540655, 6168.228971378578, 6174.981128113855, 5848.67832269225, 5860.472557469848, 5862.765489380419, 5888.793892213752, 5899.793507227227, 6642.501812364092, 6655.46756259498, 6668.19981602206, 6674.966625166891, 6738.494612904003, 5099.490050994871, 5108.948910510884, 5128.051310507568, 5128.461511541272, 5136.5890728741615, 6703.55320308084, 6767.4924380671155, 6799.762008329685, 6938.687759050805, 7001.229864714877, 5224.9738751306, 5226.712530810766, 5248.373004368658, 5256.474352564713, 5277.313949186581, 5303.655262407931, 5306.363236411578, 5362.249285100089, 5365.356157261075, 5377.489138531833, 5918.763249469973, 5934.1692163097205, 5934.347221805598, 5953.761849526057, 5964.970175149097, 6587.374199451045, 6655.567388119756, 6668.266490686021, 6680.5991640501725, 6725.578791635007, 1805.3410505165782, 1807.9965619081536, 1833.3949796567101, 1843.7831031519402, 1845.3633496443706, 1257.7057121198388, 1274.796032634774, 1309.9476020959055, 1320.320035181882, 1328.1632571479906, 5959.553033522503, 6054.8183554493435, 6061.909071363933, 6084.330723620398, 6091.939080609137, 6596.769113081019, 6670.299618540081, 6723.193216814014, 6765.0, 6830.316968303229, 5969.432903874125, 5985.551083668743, 5998.0, 6007.36922623127, 6043.758249669964, 3547.6452354764833, 3551.875684351087, 3554.893353199408, 3570.393033184365, 3575.1955809943074, 6730.966345168243, 6795.422389096883, 6804.829879253059, 6826.829329266808, 6921.861562768717, 6915.89626155608, 6948.93051069493, 6950.443964482829, 6971.965140174369, 7012.0, 6833.726650933907, 6842.315768423118, 6854.383105520427, 6873.347032032049, 6926.445884329241, 6706.0, 6714.3957043866285, 6726.199697171082, 6767.255601883888, 6780.38976492118, 6662.200535935719, 6677.332266773284, 6683.799486015428, 6695.866082678222, 6696.263411024881, 6791.592504449749, 6846.281140480178, 6847.727250017559, 6909.170899492093, 6941.0, 5114.979583064594, 5162.457941916045, 5221.686698798084, 5225.320708307896, 5230.0, 6505.381988711065, 6522.836929076621, 6592.297158165129, 6649.202095748541, 6712.878511492179, 6448.871022579428, 6484.206739057704, 6517.73929042833, 6597.208334999639, 6610.570312929644, 6447.935520644921, 6489.253735820441, 6532.216134063943, 6536.379043990813, 6539.607623542603, 6439.8068057958335, 6462.256840463218, 6482.837929051811, 6498.187726534184, 6504.772332968323, 6477.255115661759, 6584.38682522785, 6666.866662666816, 6684.364985326386, 6690.632015239208, 6421.614703117828, 6451.0, 6453.903191452226, 6481.731394012247, 6484.837879052872], "value": 5182.7058101785815, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [941.830470515307, 973.4840534516704, 977.1840513171503, 981.1954197558001, 1003.4230317567399, 18157.636847263064, 18472.815271847277, 18517.907410462947, 18543.72184417233, 18560.814391856078, 17596.560085997855, 18355.724664130048, 18408.0, 19695.803041969575, 19966.60066798667, 1060.141285558698, 1093.2128867215592, 1093.7320356512666, 1093.742970401959, 1094.4035500652144, 1031.7265924530006, 1084.7559299157665, 1089.5315014543744, 1090.841827934949, 1094.5512339940626, 1133.4785998440686, 1144.7252659361734, 1146.323669035269, 1146.9713257168596, 1147.638493874431, 1054.6150655010943, 1079.5465904320204, 1144.1075960750586, 1145.73075327298, 1147.3058799426394, 817.2603793566832, 819.0171793847347, 819.7950512371907, 819.9914105650024, 824.2128767027474, 21264.14943402262, 21516.784832151636, 21556.245531406483, 21603.35189944304, 21699.891500542555, 22260.332190034325, 23176.65235021459, 23180.956856941477, 23276.418089547726, 23469.76530234693, 18471.63056738871, 18821.15304811287, 18965.0, 18978.335758248395, 19002.23991040357, 3861.2663593917346, 3884.5921178276158, 3895.1625400538737, 3897.259520691088, 3898.1813819098024, 160260.85957825033, 161368.19315903346, 162449.0, 163770.0, 166926.1653691736, 16687.749683754755, 16791.91604041972, 16902.0, 17018.829811701973, 17045.573860653334, 32386.35227295441, 33317.50023749645, 33569.16077098092, 34157.658423415945, 34521.48217776736, 8537.871931921027, 8597.742067737852, 8608.870866937, 8617.310615150773, 8635.740927772176, 4320.373545835899, 4533.274676051816, 4576.290674945389, 4602.953970460319, 4607.769611519478, 5996.370381110008, 6185.783497577653, 6191.133241346215, 6223.439890409885, 6230.283517395557, 1031.0771859185986, 1045.7385653586603, 1070.0369667299337, 1072.3244356055634, 1073.838924161382, 743.0600290632354, 749.6439191384158, 750.6096829648661, 764.1556080531044, 766.9309762121325, 13279.336033198302, 13608.523701670394, 13898.23559704226, 13898.930505347409, 13994.370253338624, 752.2064222245474, 753.2994315286836, 758.5486635451897, 760.3195140349357, 768.0092680442278, 6039.879202416015, 6111.174991376196, 6184.5980011299125, 6196.907046394308, 6316.652584107918, 158614.0, 158786.0, 160065.0, 160143.0, 161528.0, 140494.0, 140536.0, 141027.29486352502, 141062.0, 145070.0, 114463.0, 117093.41453292679, 117292.0, 120630.3968480152, 121005.39497302457, 11615.245009074382, 11672.766544669228, 11692.47383867711, 11769.411529423323, 11899.345535995604, 58005.0, 58494.707526462094, 58852.70573647104, 59295.703521482115, 59589.0, 72634.91047634184, 73239.26760732324, 73936.63031684807, 75196.0, 75788.62105689436, 15342.23288835576, 15761.211939402754, 15778.211089445711, 15790.131542765259, 15914.363425462852, 18620.627587447903, 18771.155298011894, 18775.812241877407, 19035.143418546475, 19254.133563989933, 15683.921580392025, 15861.920690396475, 15916.602084947506, 16065.11641859709, 16093.0, 17460.650786984443, 17552.736708949633, 17561.648767024843, 17789.110544472984, 17930.731039034687, 17307.221175047405, 17613.20740566703, 17633.559161021076, 17820.198091085673, 17859.910700446413, 11871.406429678655, 12143.817842732542, 12162.635120946386, 12214.938925305316, 12261.26432414058, 11943.402829858645, 12079.818802718142, 12164.75670486603, 12321.156545894499, 12376.07179461562], "value": 9818.068498663046, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1374.5000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 106.0, 107.0, 105.0, 107.0, 95.0, 106.0, 106.0, 106.0, 106.0], "value": 105.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 120.0, 115.0, 114.0, 114.0, 116.0, 116.0, 114.0, 114.0, 113.0], "value": 114.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 162.0, 162.0, 161.0, 162.0, 163.0, 163.0, 163.0, 166.0, 171.0], "value": 163.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 73.0, 74.0, 73.0, 78.0, 73.0, 73.0, 74.0, 74.0, 81.0], "value": 74.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 77.0, 77.0, 79.0, 78.0, 78.0, 78.0, 77.0, 77.0, 78.0], "value": 77.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 114.0, 114.0, 110.0, 114.0, 114.0, 115.0, 113.0, 114.0, 113.0], "value": 113.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 155.0, 158.0, 149.0, 150.0, 150.0, 151.0, 151.0, 150.0, 150.0], "value": 151.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 95.0, 94.0, 94.0, 94.0, 94.0, 100.0, 97.0, 94.0], "value": 95.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [47.0, 48.0, 47.0, 46.0, 46.0, 45.0, 48.0, 47.0, 46.0, 48.0], "value": 46.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [37.0, 36.0, 37.0, 36.0, 36.0, 36.0, 36.0, 36.0, 36.0, 36.0], "value": 36.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 72.0, 72.0, 69.0, 72.0, 75.0, 72.0, 70.0, 75.0, 70.0], "value": 72.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 104.0, 106.0, 106.0, 105.0, 104.0, 109.0, 105.0, 114.0, 115.0], "value": 107.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 152.0, 161.0, 150.0, 149.0, 151.0, 157.0, 150.0, 151.0, 156.0], "value": 152.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 67.0, 60.0, 58.0, 59.0, 64.0, 63.0, 61.0, 63.0, 60.0], "value": 62.4, "unit": "score"}]}]} 12:22:25 INFO - Return code: 0 12:22:25 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:22:25 INFO - # TBPL SUCCESS # 12:22:25 INFO - Running post-action listener: _package_coverage_data 12:22:25 INFO - Running post-action listener: _resource_record_post_action 12:22:25 INFO - [mozharness: 2017-09-26 19:22:25.745699Z] Finished run-tests step (success) 12:22:25 INFO - Running post-run listener: _resource_record_post_run 12:22:25 INFO - Total resource usage - Wall time: 1185s; CPU: 13.0%; Read bytes: 167936; Write bytes: 333418496; Read time: 288; Write time: 204252 12:22:25 INFO - TinderboxPrint: CPU usage
13.1% 12:22:25 INFO - TinderboxPrint: I/O read bytes / time
167,936 / 288 12:22:25 INFO - TinderboxPrint: I/O write bytes / time
333,418,496 / 204,252 12:22:25 INFO - TinderboxPrint: CPU idle
8,211.1 (86.9%) 12:22:25 INFO - TinderboxPrint: CPU user
1,186.8 (12.6%) 12:22:25 INFO - TinderboxPrint: Swap in / out
0 / 0 12:22:25 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 9494528; Read time: 0; Write time: 1160 12:22:25 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 12:22:25 INFO - run-tests - Wall time: 1168s; CPU: 13.0%; Read bytes: 167936; Write bytes: 323309568; Read time: 288; Write time: 203092 12:22:26 INFO - Running post-run listener: _upload_blobber_files 12:22:26 INFO - Blob upload gear active. 12:22:26 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:22:26 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 12:22:26 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'] 12:22:26 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 12:22:26 INFO - (blobuploader) - INFO - Open directory for files ... 12:22:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 12:22:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:22:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:22:27 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 12:22:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:22:27 INFO - (blobuploader) - INFO - Done attempting. 12:22:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 12:22:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:22:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:22:28 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 12:22:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:22:28 INFO - (blobuploader) - INFO - Done attempting. 12:22:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:22:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:22:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:22:36 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:22:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:22:36 INFO - (blobuploader) - INFO - Done attempting. 12:22:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 12:22:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:22:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:22:36 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 12:22:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:22:36 INFO - (blobuploader) - INFO - Done attempting. 12:22:36 INFO - (blobuploader) - INFO - Iteration through files over. 12:22:36 INFO - Return code: 0 12:22:36 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:22:36 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:22:36 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cce21ea3ea2cd4fc53ff5dc9cdf26911e030d3195f068660f7feabaf1e6dd4144dcefb942508379c5b0d97871a5d4b5a21d9056fc2a0aa9f6230de8cbca748ab", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/27bfff010dabc8eb33adbcbf9d0f31b32ca831b63e58c2e2516fb6c49329c84f5978b886174c004022246292977886dea029f5c0f3f5aa5c7ac4221e4f71b88d", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c0ac8ffa43c78aaf4098d6cd26cab8ed0d7413d2b6f8f0f39a33a26b04472aeef3ff7d3e3d5e75490f335fa11b4923eafa6d9dc2a47eeed999edadf2525f3a07", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3029de8e2f98b10cda43504281e0396a39d46e89928bf7021ae04d63c1b2e82fc60431ffd36bc1546d7edc87fc0941403f218646592ccca3a10ca94b2befd742"} 12:22:36 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:22:37 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:22:37 INFO - Contents: 12:22:37 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cce21ea3ea2cd4fc53ff5dc9cdf26911e030d3195f068660f7feabaf1e6dd4144dcefb942508379c5b0d97871a5d4b5a21d9056fc2a0aa9f6230de8cbca748ab", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/27bfff010dabc8eb33adbcbf9d0f31b32ca831b63e58c2e2516fb6c49329c84f5978b886174c004022246292977886dea029f5c0f3f5aa5c7ac4221e4f71b88d", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c0ac8ffa43c78aaf4098d6cd26cab8ed0d7413d2b6f8f0f39a33a26b04472aeef3ff7d3e3d5e75490f335fa11b4923eafa6d9dc2a47eeed999edadf2525f3a07", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3029de8e2f98b10cda43504281e0396a39d46e89928bf7021ae04d63c1b2e82fc60431ffd36bc1546d7edc87fc0941403f218646592ccca3a10ca94b2befd742"} 12:22:37 INFO - Running post-run listener: copy_logs_to_upload_dir 12:22:37 INFO - Copying logs to upload dir... 12:22:37 INFO - mkdir: /builds/slave/test/build/upload/logs 12:22:37 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1235.405568 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 35 secs) (at 2017-09-26 12:22:37.054716) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-26 12:22:37.057992) ========= 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=97facb8ba2ac28944cc45e6b000001d4-1506452519.880506-810758685 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/cce21ea3ea2cd4fc53ff5dc9cdf26911e030d3195f068660f7feabaf1e6dd4144dcefb942508379c5b0d97871a5d4b5a21d9056fc2a0aa9f6230de8cbca748ab", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/27bfff010dabc8eb33adbcbf9d0f31b32ca831b63e58c2e2516fb6c49329c84f5978b886174c004022246292977886dea029f5c0f3f5aa5c7ac4221e4f71b88d", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c0ac8ffa43c78aaf4098d6cd26cab8ed0d7413d2b6f8f0f39a33a26b04472aeef3ff7d3e3d5e75490f335fa11b4923eafa6d9dc2a47eeed999edadf2525f3a07", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3029de8e2f98b10cda43504281e0396a39d46e89928bf7021ae04d63c1b2e82fc60431ffd36bc1546d7edc87fc0941403f218646592ccca3a10ca94b2befd742"} build_url:https://queue.taskcluster.net/v1/task/TPEV7epBRV-h_JJWHMoJwA/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011330 build_url: 'https://queue.taskcluster.net/v1/task/TPEV7epBRV-h_JJWHMoJwA/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cce21ea3ea2cd4fc53ff5dc9cdf26911e030d3195f068660f7feabaf1e6dd4144dcefb942508379c5b0d97871a5d4b5a21d9056fc2a0aa9f6230de8cbca748ab", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/27bfff010dabc8eb33adbcbf9d0f31b32ca831b63e58c2e2516fb6c49329c84f5978b886174c004022246292977886dea029f5c0f3f5aa5c7ac4221e4f71b88d", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c0ac8ffa43c78aaf4098d6cd26cab8ed0d7413d2b6f8f0f39a33a26b04472aeef3ff7d3e3d5e75490f335fa11b4923eafa6d9dc2a47eeed999edadf2525f3a07", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3029de8e2f98b10cda43504281e0396a39d46e89928bf7021ae04d63c1b2e82fc60431ffd36bc1546d7edc87fc0941403f218646592ccca3a10ca94b2befd742"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-26 12:22:37.086158) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-26 12:22:37.086500) ========= 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=97facb8ba2ac28944cc45e6b000001d4-1506452519.880506-810758685 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004629 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-26 12:22:37.168896) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-26 12:22:37.169274) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-26 12:22:37.169608) ========= ========= Total master_lag: 0.19 =========