builder: mozilla-central_ubuntu64_hw_qr_test-dromaeojs-e10s slave: talos-linux64-ix-050 starttime: 1505349024.09 results: success (0) revision: c15e2f280729b6503f9455cd4448ab2852eb5806 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-13 17:30:24.091014) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-13 17:30:24.091497) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-13 17:30:24.091778) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-13 17:30:24.109613) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-13 17:30:24.110002) ========= 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=ca6c2627acea03e666270f68000001e0-1505349022.485655-1085936112 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-13 17:30:24-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 46.3M=0s 2017-09-13 17:30:24 (46.3 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.180955 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-13 17:30:24.310934) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-13 17:30:24.311318) ========= 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=ca6c2627acea03e666270f68000001e0-1505349022.485655-1085936112 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.021187 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-13 17:30:24.361541) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-13 17:30:24.361932) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev c15e2f280729b6503f9455cd4448ab2852eb5806 --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 c15e2f280729b6503f9455cd4448ab2852eb5806 --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=ca6c2627acea03e666270f68000001e0-1505349022.485655-1085936112 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-13 17:30:24,441 truncating revision to first 12 chars 2017-09-13 17:30:24,441 Setting DEBUG logging. 2017-09-13 17:30:24,441 attempt 1/10 2017-09-13 17:30:24,441 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/c15e2f280729?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-13 17:30:24,805 unpacking tar archive at: mozilla-central-c15e2f280729/testing/mozharness/ program finished with exit code 0 elapsedTime=0.656624 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-13 17:30:25.040726) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-13 17:30:25.041121) ========= script_repo_revision: c15e2f280729b6503f9455cd4448ab2852eb5806 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-13 17:30:25.043532) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-13 17:30:25.043850) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-13 17:30:25.164772) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 44 secs) (at 2017-09-13 17:30:25.165259) ========= /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=ca6c2627acea03e666270f68000001e0-1505349022.485655-1085936112 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 17:30:25 INFO - MultiFileLogger online at 20170913 17:30:25 in /builds/slave/test 17:30:25 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 17:30:25 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 17:30:25 INFO - {'append_to_log': False, 17:30:25 INFO - 'base_work_dir': '/builds/slave/test', 17:30:25 INFO - 'blob_upload_branch': 'Firefox', 17:30:25 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 17:30:25 INFO - 'branch': 'Firefox', 17:30:25 INFO - 'buildbot_json_path': 'buildprops.json', 17:30:25 INFO - 'code_coverage': False, 17:30:25 INFO - 'config_files': ('talos/linux_config.py',), 17:30:25 INFO - 'default_actions': ('clobber', 17:30:25 INFO - 'read-buildbot-config', 17:30:25 INFO - 'download-and-extract', 17:30:25 INFO - 'populate-webroot', 17:30:25 INFO - 'create-virtualenv', 17:30:25 INFO - 'install', 17:30:25 INFO - 'setup-mitmproxy', 17:30:25 INFO - 'run-tests'), 17:30:25 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:30:25 INFO - 'disable_ccov_upload': False, 17:30:25 INFO - 'disable_stylo': False, 17:30:25 INFO - 'download_minidump_stackwalk': True, 17:30:25 INFO - 'download_symbols': 'ondemand', 17:30:25 INFO - 'enable_stylo': False, 17:30:25 INFO - 'enable_webrender': False, 17:30:25 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 17:30:25 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:30:25 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:30:25 INFO - 'gecko_profile': False, 17:30:25 INFO - 'gecko_profile_interval': 0, 17:30:25 INFO - 'installer_path': 'installer.exe', 17:30:25 INFO - 'log_level': 'info', 17:30:25 INFO - 'log_name': 'talos', 17:30:25 INFO - 'log_to_console': True, 17:30:25 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 17:30:25 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 17:30:25 INFO - 'opt_config_files': (), 17:30:25 INFO - 'pip_index': False, 17:30:25 INFO - 'suite': 'dromaeojs-e10s', 17:30:25 INFO - 'system_bits': '32', 17:30:25 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:30:25 INFO - 'title': 'talos-linux64-ix-050', 17:30:25 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:30:25 INFO - 'use_talos_json': True, 17:30:25 INFO - 'verify': 'False', 17:30:25 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 17:30:25 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:30:25 INFO - 'work_dir': 'build'} 17:30:25 INFO - [mozharness: 2017-09-14 00:30:25.386361Z] Running clobber step. 17:30:25 INFO - Running pre-action listener: _resource_record_pre_action 17:30:25 INFO - Running main action method: clobber 17:30:25 INFO - rmtree: /builds/slave/test/build 17:30:25 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 17:30:26 INFO - Running post-action listener: _resource_record_post_action 17:30:26 INFO - [mozharness: 2017-09-14 00:30:26.403425Z] Finished clobber step (success) 17:30:26 INFO - [mozharness: 2017-09-14 00:30:26.403522Z] Running read-buildbot-config step. 17:30:26 INFO - Running pre-action listener: _resource_record_pre_action 17:30:26 INFO - Running main action method: read_buildbot_config 17:30:26 INFO - Using buildbot properties: 17:30:26 INFO - { 17:30:26 INFO - "project": "", 17:30:26 INFO - "product": "firefox", 17:30:26 INFO - "who": "ryanvm@gmail.com", 17:30:26 INFO - "installer_path": "public/build/target.tar.bz2", 17:30:26 INFO - "builddir": "mozilla-central_ubuntu64_hw_qr_test-dromaeojs-e10s", 17:30:26 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 17:30:26 INFO - "buildername": "Ubuntu HW 12.04 x64 qr mozilla-central talos dromaeojs-e10s", 17:30:26 INFO - "stage_platform": "linux64-qr", 17:30:26 INFO - "basedir": "/builds/slave/test", 17:30:26 INFO - "buildnumber": 50, 17:30:26 INFO - "platform": "ubuntu64_hw_qr", 17:30:26 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 17:30:26 INFO - "slavebuilddir": "test", 17:30:26 INFO - "taskId": "bvugQ3KaQvuuzR8pmGCl2A", 17:30:26 INFO - "branch": "mozilla-central", 17:30:26 INFO - "script_repo_revision": "production", 17:30:26 INFO - "revision": "c15e2f280729b6503f9455cd4448ab2852eb5806", 17:30:26 INFO - "slavename": "talos-linux64-ix-050", 17:30:26 INFO - "repo_path": "mozilla-central" 17:30:26 INFO - } 17:30:26 INFO - Finding installer, test and symbols from parent task. 17:30:26 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bvugQ3KaQvuuzR8pmGCl2A'}, attempt #1 17:30:26 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bvugQ3KaQvuuzR8pmGCl2A'}, attempt #1 17:30:27 INFO - Task dependencies: GhKihdsTQkWhAO3N2_oAlA 17:30:27 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GhKihdsTQkWhAO3N2_oAlA'}, attempt #1 17:30:27 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/GhKihdsTQkWhAO3N2_oAlA/artifacts/public/build/target.tar.bz2 17:30:27 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/GhKihdsTQkWhAO3N2_oAlA/artifacts/public/build/target.test_packages.json 17:30:27 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/GhKihdsTQkWhAO3N2_oAlA/artifacts/public/build/target.crashreporter-symbols.zip 17:30:27 INFO - Running post-action listener: _resource_record_post_action 17:30:27 INFO - [mozharness: 2017-09-14 00:30:27.829783Z] Finished read-buildbot-config step (success) 17:30:27 INFO - [mozharness: 2017-09-14 00:30:27.830000Z] Running download-and-extract step. 17:30:27 INFO - Running pre-action listener: _resource_record_pre_action 17:30:27 INFO - Running main action method: download_and_extract 17:30:27 INFO - mkdir: /builds/slave/test/build/tests 17:30:27 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')]} 17:30:27 INFO - trying https://queue.taskcluster.net/v1/task/GhKihdsTQkWhAO3N2_oAlA/artifacts/public/build/target.test_packages.json 17:30:27 INFO - Downloading https://queue.taskcluster.net/v1/task/GhKihdsTQkWhAO3N2_oAlA/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 17:30:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GhKihdsTQkWhAO3N2_oAlA/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 17:30:28 INFO - Downloaded 989 bytes. 17:30:28 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 17:30:28 INFO - Using the following test package requirements: 17:30:28 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 17:30:28 INFO - u'common': [u'target.common.tests.zip'], 17:30:28 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 17:30:28 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 17:30:28 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 17:30:28 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 17:30:28 INFO - u'mozbase': [u'target.common.tests.zip'], 17:30:28 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 17:30:28 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 17:30:28 INFO - u'web-platform': [u'target.common.tests.zip', 17:30:28 INFO - u'target.web-platform.tests.tar.gz'], 17:30:28 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 17:30:28 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 17:30:28 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GhKihdsTQkWhAO3N2_oAlA/artifacts/public/build/target.common.tests.zip 17:30:28 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GhKihdsTQkWhAO3N2_oAlA/artifacts/public/build/target.common.tests.zip'}, attempt #1 17:30:28 INFO - Fetch https://queue.taskcluster.net/v1/task/GhKihdsTQkWhAO3N2_oAlA/artifacts/public/build/target.common.tests.zip into memory 17:30:29 INFO - Content-Length response header: 54572603 17:30:29 INFO - Bytes received: 54572603 17:30:40 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GhKihdsTQkWhAO3N2_oAlA/artifacts/public/build/target.talos.tests.zip 17:30:40 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GhKihdsTQkWhAO3N2_oAlA/artifacts/public/build/target.talos.tests.zip'}, attempt #1 17:30:40 INFO - Fetch https://queue.taskcluster.net/v1/task/GhKihdsTQkWhAO3N2_oAlA/artifacts/public/build/target.talos.tests.zip into memory 17:30:40 INFO - Content-Length response header: 14500356 17:30:40 INFO - Bytes received: 14500356 17:30:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:30:41 INFO - trying https://queue.taskcluster.net/v1/task/GhKihdsTQkWhAO3N2_oAlA/artifacts/public/build/target.tar.bz2 17:30:41 INFO - Downloading https://queue.taskcluster.net/v1/task/GhKihdsTQkWhAO3N2_oAlA/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 17:30:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GhKihdsTQkWhAO3N2_oAlA/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 17:30:42 INFO - Downloaded 61426292 bytes. 17:30:42 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/GhKihdsTQkWhAO3N2_oAlA/artifacts/public/build/target.tar.bz2 17:30:42 INFO - mkdir: /builds/slave/test/properties 17:30:42 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 17:30:42 INFO - Writing to file /builds/slave/test/properties/build_url 17:30:42 INFO - Contents: 17:30:42 INFO - build_url:https://queue.taskcluster.net/v1/task/GhKihdsTQkWhAO3N2_oAlA/artifacts/public/build/target.tar.bz2 17:30:42 INFO - Running post-action listener: _resource_record_post_action 17:30:42 INFO - Running post-action listener: find_tests_for_verification 17:30:42 INFO - Running post-action listener: set_extra_try_arguments 17:30:42 INFO - [mozharness: 2017-09-14 00:30:42.229197Z] Finished download-and-extract step (success) 17:30:42 INFO - [mozharness: 2017-09-14 00:30:42.229295Z] Running populate-webroot step. 17:30:42 INFO - Running pre-action listener: _resource_record_pre_action 17:30:42 INFO - Running main action method: populate_webroot 17:30:42 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 17:30:42 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 17:30:42 INFO - 'tests': ['tresize', 17:30:42 INFO - 'tcanvasmark']}, 17:30:42 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 17:30:42 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 17:30:42 INFO - 'tests': ['dromaeo_css', 17:30:42 INFO - 'kraken']}, 17:30:42 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 17:30:42 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:30:42 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 17:30:42 INFO - 'talos_options': ['--disable-stylo'], 17:30:42 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:30:42 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 17:30:42 INFO - 'tests': ['damp', 'tps']}, 17:30:42 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 17:30:42 INFO - 'talos_options': ['--disable-stylo'], 17:30:42 INFO - 'tests': ['damp', 'tps']}, 17:30:42 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:30:42 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 17:30:42 INFO - 'tests': ['dromaeo_dom']}, 17:30:42 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 17:30:42 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 17:30:42 INFO - 'tests': ['basic_compositor_video', 17:30:42 INFO - 'glvideo']}, 17:30:42 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 17:30:42 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 17:30:42 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 17:30:42 INFO - 'talos_options': ['--disable-stylo'], 17:30:42 INFO - 'tests': ['ts_paint_webext', 17:30:42 INFO - 'tp5o_webext']}, 17:30:42 INFO - 'other-e10s': {'tests': ['a11yr', 17:30:42 INFO - 'ts_paint', 17:30:42 INFO - 'tpaint', 17:30:42 INFO - 'sessionrestore', 17:30:42 INFO - 'sessionrestore_many_windows', 17:30:42 INFO - 'sessionrestore_no_auto_restore', 17:30:42 INFO - 'tabpaint']}, 17:30:42 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 17:30:42 INFO - 'tests': ['a11yr', 17:30:42 INFO - 'ts_paint', 17:30:42 INFO - 'tpaint', 17:30:42 INFO - 'sessionrestore', 17:30:42 INFO - 'sessionrestore_many_windows', 17:30:42 INFO - 'sessionrestore_no_auto_restore', 17:30:42 INFO - 'tabpaint']}, 17:30:42 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 17:30:42 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 17:30:42 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 17:30:42 INFO - 'tests': ['bloom_basic_singleton']}, 17:30:42 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 17:30:42 INFO - 'tests': ['bloom_basic']}, 17:30:42 INFO - 'svgr-e10s': {'tests': ['tsvgx', 17:30:42 INFO - 'tsvgr_opacity', 17:30:42 INFO - 'tart', 17:30:42 INFO - 'tscrollx', 17:30:42 INFO - 'tsvg_static']}, 17:30:42 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 17:30:42 INFO - 'tests': ['tsvgx', 17:30:42 INFO - 'tsvgr_opacity', 17:30:42 INFO - 'tart', 17:30:42 INFO - 'tscrollx', 17:30:42 INFO - 'tsvg_static']}, 17:30:42 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 17:30:42 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 17:30:42 INFO - 'talos_options': ['--disable-stylo'], 17:30:42 INFO - 'tests': ['tp5o']}, 17:30:42 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 17:30:42 INFO - 'talos_options': ['--mitmproxy', 17:30:42 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 17:30:42 INFO - '--firstNonBlankPaint'], 17:30:42 INFO - 'tests': ['tp6_google', 17:30:42 INFO - 'tp6_youtube', 17:30:42 INFO - 'tp6_amazon', 17:30:42 INFO - 'tp6_facebook']}, 17:30:42 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 17:30:42 INFO - 'talos_options': ['--disable-stylo', 17:30:42 INFO - '--mitmproxy', 17:30:42 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 17:30:42 INFO - '--firstNonBlankPaint'], 17:30:42 INFO - 'tests': ['tp6_google', 17:30:42 INFO - 'tp6_youtube', 17:30:42 INFO - 'tp6_amazon', 17:30:42 INFO - 'tp6_facebook']}, 17:30:42 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 17:30:42 INFO - 'talos_options': ['--stylo-threads=1', 17:30:42 INFO - '--mitmproxy', 17:30:42 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 17:30:42 INFO - '--firstNonBlankPaint'], 17:30:42 INFO - 'tests': ['tp6_google', 17:30:42 INFO - 'tp6_youtube', 17:30:42 INFO - 'tp6_amazon', 17:30:42 INFO - 'tp6_facebook']}, 17:30:42 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 17:30:42 INFO - 'talos_options': ['--xperf_path', 17:30:42 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:30:42 INFO - 'tests': ['tp5n']}, 17:30:42 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 17:30:42 INFO - 'talos_options': ['--disable-stylo', 17:30:42 INFO - '--xperf_path', 17:30:42 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:30:42 INFO - 'tests': ['tp5n']}}} 17:30:42 INFO - Running post-action listener: _resource_record_post_action 17:30:42 INFO - [mozharness: 2017-09-14 00:30:42.244924Z] Finished populate-webroot step (success) 17:30:42 INFO - [mozharness: 2017-09-14 00:30:42.245031Z] Running create-virtualenv step. 17:30:42 INFO - Running pre-action listener: _resource_record_pre_action 17:30:42 INFO - Running main action method: create_virtualenv 17:30:42 INFO - Creating virtualenv /builds/slave/test/build/venv 17:30:42 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 17:30:42 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 17:30:42 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 17:30:42 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:30:42 INFO - New python executable in /builds/slave/test/build/venv/bin/python 17:30:42 INFO - Installing distribute.............................................................................................................................................................................................done. 17:30:43 INFO - Installing pip...............done. 17:30:43 INFO - Return code: 0 17:30:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 17:30:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 17:30:43 INFO - Reading from file tmpfile_stdout 17:30:43 INFO - Output received: 17:30:43 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 17:30:43 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 17:30:43 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')]} 17:30:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:30:43 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')]} 17:30:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:30:43 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 0xaf31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcbca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe5fa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe63090>, '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 0xe63d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe5df90>, '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': 'ca6c2627acea03e666270f68000001e0-1505349022.485655-1085936112', '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 17:30:43 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 17:30:43 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 17:30:43 INFO - Using env: {'DISPLAY': ':0', 17:30:43 INFO - 'HOME': '/home/cltbld', 17:30:43 INFO - 'LANG': 'en_US.UTF-8', 17:30:43 INFO - 'LANGUAGE': 'en_US:en', 17:30:43 INFO - 'LOGNAME': 'cltbld', 17:30:43 INFO - 'MAIL': '/var/mail/cltbld', 17:30:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:30:43 INFO - 'MOZ_NO_REMOTE': '1', 17:30:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:30:43 INFO - 'NO_EM_RESTART': '1', 17:30:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:30:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:30:43 INFO - 'PWD': '/builds/slave/test', 17:30:43 INFO - 'SHELL': '/bin/bash', 17:30:43 INFO - 'SHLVL': '1', 17:30:43 INFO - 'TERM': 'linux', 17:30:43 INFO - 'TMOUT': '86400', 17:30:43 INFO - 'USER': 'cltbld', 17:30:43 INFO - 'XDG_SESSION_COOKIE': 'ca6c2627acea03e666270f68000001e0-1505349022.485655-1085936112', 17:30:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:30:43 INFO - '_': '/tools/buildbot/bin/python'} 17:30:44 INFO - Ignoring indexes: http://pypi.python.org/simple/ 17:30:44 INFO - Downloading/unpacking pip>=1.5 17:30:44 INFO - Running setup.py egg_info for package pip 17:30:44 INFO - warning: no files found matching 'pip/cacert.pem' 17:30:44 INFO - warning: no files found matching '*.html' under directory 'docs' 17:30:44 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 17:30:44 INFO - no previously-included directories found matching 'docs/_build/_sources' 17:30:44 INFO - Installing collected packages: pip 17:30:44 INFO - Found existing installation: pip 1.1 17:30:44 INFO - Uninstalling pip: 17:30:44 INFO - Successfully uninstalled pip 17:30:44 INFO - Running setup.py install for pip 17:30:44 INFO - warning: no files found matching 'pip/cacert.pem' 17:30:44 INFO - warning: no files found matching '*.html' under directory 'docs' 17:30:44 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 17:30:44 INFO - no previously-included directories found matching 'docs/_build/_sources' 17:30:44 INFO - Installing pip script to /builds/slave/test/build/venv/bin 17:30:44 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 17:30:44 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 17:30:44 INFO - Successfully installed pip 17:30:44 INFO - Cleaning up... 17:30:44 INFO - Return code: 0 17:30:44 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 17:30:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:30:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:30:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:30:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:30:44 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0xaf31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcbca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe5fa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe63090>, '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 0xe63d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe5df90>, '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': 'ca6c2627acea03e666270f68000001e0-1505349022.485655-1085936112', '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 17:30:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 17:30:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 17:30:44 INFO - Using env: {'DISPLAY': ':0', 17:30:44 INFO - 'HOME': '/home/cltbld', 17:30:44 INFO - 'LANG': 'en_US.UTF-8', 17:30:44 INFO - 'LANGUAGE': 'en_US:en', 17:30:44 INFO - 'LOGNAME': 'cltbld', 17:30:44 INFO - 'MAIL': '/var/mail/cltbld', 17:30:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:30:44 INFO - 'MOZ_NO_REMOTE': '1', 17:30:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:30:44 INFO - 'NO_EM_RESTART': '1', 17:30:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:30:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:30:44 INFO - 'PWD': '/builds/slave/test', 17:30:44 INFO - 'SHELL': '/bin/bash', 17:30:44 INFO - 'SHLVL': '1', 17:30:44 INFO - 'TERM': 'linux', 17:30:44 INFO - 'TMOUT': '86400', 17:30:44 INFO - 'USER': 'cltbld', 17:30:44 INFO - 'XDG_SESSION_COOKIE': 'ca6c2627acea03e666270f68000001e0-1505349022.485655-1085936112', 17:30:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:30:44 INFO - '_': '/tools/buildbot/bin/python'} 17:30:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:30:44 INFO - Downloading/unpacking psutil>=3.1.1 17:30:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:30:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:30:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:30:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:30:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 17:30:45 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:30:45 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:30:45 INFO - Installing collected packages: psutil 17:30:45 INFO - Running setup.py install for psutil 17:30:45 INFO - building 'psutil._psutil_linux' extension 17:30:45 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 17:30:46 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 17:30:46 INFO - building 'psutil._psutil_posix' extension 17:30:46 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 17:30:46 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 17:30:46 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:30:46 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:30:46 INFO - Successfully installed psutil 17:30:46 INFO - Cleaning up... 17:30:46 INFO - Return code: 0 17:30:46 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 17:30:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:30:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:30:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:30:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:30:46 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xaf31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcbca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe5fa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe63090>, '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 0xe63d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe5df90>, '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': 'ca6c2627acea03e666270f68000001e0-1505349022.485655-1085936112', '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 17:30:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 17:30:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 17:30:46 INFO - Using env: {'DISPLAY': ':0', 17:30:46 INFO - 'HOME': '/home/cltbld', 17:30:46 INFO - 'LANG': 'en_US.UTF-8', 17:30:46 INFO - 'LANGUAGE': 'en_US:en', 17:30:46 INFO - 'LOGNAME': 'cltbld', 17:30:46 INFO - 'MAIL': '/var/mail/cltbld', 17:30:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:30:46 INFO - 'MOZ_NO_REMOTE': '1', 17:30:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:30:46 INFO - 'NO_EM_RESTART': '1', 17:30:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:30:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:30:46 INFO - 'PWD': '/builds/slave/test', 17:30:46 INFO - 'SHELL': '/bin/bash', 17:30:46 INFO - 'SHLVL': '1', 17:30:46 INFO - 'TERM': 'linux', 17:30:46 INFO - 'TMOUT': '86400', 17:30:46 INFO - 'USER': 'cltbld', 17:30:46 INFO - 'XDG_SESSION_COOKIE': 'ca6c2627acea03e666270f68000001e0-1505349022.485655-1085936112', 17:30:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:30:46 INFO - '_': '/tools/buildbot/bin/python'} 17:30:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:30:46 INFO - Downloading/unpacking mozsystemmonitor==0.3 17:30:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:30:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:30:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:30:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:30:47 INFO - Downloading mozsystemmonitor-0.3.tar.gz 17:30:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:30:47 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 17:30:47 INFO - Installing collected packages: mozsystemmonitor 17:30:47 INFO - Running setup.py install for mozsystemmonitor 17:30:47 INFO - Successfully installed mozsystemmonitor 17:30:47 INFO - Cleaning up... 17:30:48 INFO - Return code: 0 17:30:48 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 17:30:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:30:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:30:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:30:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:30:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0xaf31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcbca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe5fa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe63090>, '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 0xe63d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe5df90>, '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': 'ca6c2627acea03e666270f68000001e0-1505349022.485655-1085936112', '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 17:30:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 17:30:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 17:30:48 INFO - Using env: {'DISPLAY': ':0', 17:30:48 INFO - 'HOME': '/home/cltbld', 17:30:48 INFO - 'LANG': 'en_US.UTF-8', 17:30:48 INFO - 'LANGUAGE': 'en_US:en', 17:30:48 INFO - 'LOGNAME': 'cltbld', 17:30:48 INFO - 'MAIL': '/var/mail/cltbld', 17:30:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:30:48 INFO - 'MOZ_NO_REMOTE': '1', 17:30:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:30:48 INFO - 'NO_EM_RESTART': '1', 17:30:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:30:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:30:48 INFO - 'PWD': '/builds/slave/test', 17:30:48 INFO - 'SHELL': '/bin/bash', 17:30:48 INFO - 'SHLVL': '1', 17:30:48 INFO - 'TERM': 'linux', 17:30:48 INFO - 'TMOUT': '86400', 17:30:48 INFO - 'USER': 'cltbld', 17:30:48 INFO - 'XDG_SESSION_COOKIE': 'ca6c2627acea03e666270f68000001e0-1505349022.485655-1085936112', 17:30:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:30:48 INFO - '_': '/tools/buildbot/bin/python'} 17:30:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:30:48 INFO - Downloading/unpacking jsonschema==2.5.1 17:30:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:30:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:30:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:30:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:30:50 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:30:50 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 17:30:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:30:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:30:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:30:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:30:51 INFO - Downloading functools32-3.2.3-2.tar.gz 17:30:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 17:30:51 INFO - warning: no files found matching '*.txt' 17:30:51 INFO - no previously-included directories found matching 'build' 17:30:51 INFO - no previously-included directories found matching 'dist' 17:30:51 INFO - no previously-included directories found matching '.git*' 17:30:51 INFO - Installing collected packages: jsonschema, functools32 17:30:51 INFO - Running setup.py install for functools32 17:30:51 INFO - warning: no files found matching '*.txt' 17:30:51 INFO - no previously-included directories found matching 'build' 17:30:51 INFO - no previously-included directories found matching 'dist' 17:30:51 INFO - no previously-included directories found matching '.git*' 17:30:51 INFO - Successfully installed jsonschema functools32 17:30:51 INFO - Cleaning up... 17:30:51 INFO - Return code: 0 17:30:51 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 17:30:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:30:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:30:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:30:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:30:51 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 0xaf31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcbca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe5fa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe63090>, '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 0xe63d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe5df90>, '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': 'ca6c2627acea03e666270f68000001e0-1505349022.485655-1085936112', '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 17:30:51 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 17:30:51 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 17:30:51 INFO - Using env: {'DISPLAY': ':0', 17:30:51 INFO - 'HOME': '/home/cltbld', 17:30:51 INFO - 'LANG': 'en_US.UTF-8', 17:30:51 INFO - 'LANGUAGE': 'en_US:en', 17:30:51 INFO - 'LOGNAME': 'cltbld', 17:30:51 INFO - 'MAIL': '/var/mail/cltbld', 17:30:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:30:51 INFO - 'MOZ_NO_REMOTE': '1', 17:30:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:30:51 INFO - 'NO_EM_RESTART': '1', 17:30:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:30:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:30:51 INFO - 'PWD': '/builds/slave/test', 17:30:51 INFO - 'SHELL': '/bin/bash', 17:30:51 INFO - 'SHLVL': '1', 17:30:51 INFO - 'TERM': 'linux', 17:30:51 INFO - 'TMOUT': '86400', 17:30:51 INFO - 'USER': 'cltbld', 17:30:51 INFO - 'XDG_SESSION_COOKIE': 'ca6c2627acea03e666270f68000001e0-1505349022.485655-1085936112', 17:30:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:30:51 INFO - '_': '/tools/buildbot/bin/python'} 17:30:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:30:51 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 17:30:51 INFO - Cleaning up... 17:30:51 INFO - Return code: 0 17:30:51 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 17:30:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:30:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:30:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:30:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:30:51 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 0xaf31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcbca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe5fa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe63090>, '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 0xe63d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe5df90>, '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': 'ca6c2627acea03e666270f68000001e0-1505349022.485655-1085936112', '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 17:30:51 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 17:30:51 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 17:30:51 INFO - Using env: {'DISPLAY': ':0', 17:30:51 INFO - 'HOME': '/home/cltbld', 17:30:51 INFO - 'LANG': 'en_US.UTF-8', 17:30:51 INFO - 'LANGUAGE': 'en_US:en', 17:30:51 INFO - 'LOGNAME': 'cltbld', 17:30:51 INFO - 'MAIL': '/var/mail/cltbld', 17:30:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:30:51 INFO - 'MOZ_NO_REMOTE': '1', 17:30:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:30:51 INFO - 'NO_EM_RESTART': '1', 17:30:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:30:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:30:51 INFO - 'PWD': '/builds/slave/test', 17:30:51 INFO - 'SHELL': '/bin/bash', 17:30:51 INFO - 'SHLVL': '1', 17:30:51 INFO - 'TERM': 'linux', 17:30:51 INFO - 'TMOUT': '86400', 17:30:51 INFO - 'USER': 'cltbld', 17:30:51 INFO - 'XDG_SESSION_COOKIE': 'ca6c2627acea03e666270f68000001e0-1505349022.485655-1085936112', 17:30:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:30:51 INFO - '_': '/tools/buildbot/bin/python'} 17:30:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:30:51 INFO - Downloading/unpacking blobuploader==1.2.4 17:30:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:30:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:30:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:30:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:30:52 INFO - Downloading blobuploader-1.2.4.tar.gz 17:30:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:30:52 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:30:52 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 17:30:52 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 17:30:52 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 17:30:52 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 17:30:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 17:30:53 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:30:53 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 17:30:53 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 17:30:53 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 17:30:53 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 17:30:53 INFO - Downloading docopt-0.6.1.tar.gz 17:30:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 17:30:53 INFO - Installing collected packages: blobuploader, requests, docopt 17:30:53 INFO - Running setup.py install for blobuploader 17:30:53 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:30:53 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 17:30:53 INFO - Running setup.py install for requests 17:30:53 INFO - Running setup.py install for docopt 17:30:54 INFO - Successfully installed blobuploader requests docopt 17:30:54 INFO - Cleaning up... 17:30:54 INFO - Return code: 0 17:30:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:30:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:30:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:30:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:30:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:30:54 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 0xaf31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcbca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe5fa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe63090>, '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 0xe63d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe5df90>, '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': 'ca6c2627acea03e666270f68000001e0-1505349022.485655-1085936112', '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 17:30:54 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 17:30:54 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 17:30:54 INFO - Using env: {'DISPLAY': ':0', 17:30:54 INFO - 'HOME': '/home/cltbld', 17:30:54 INFO - 'LANG': 'en_US.UTF-8', 17:30:54 INFO - 'LANGUAGE': 'en_US:en', 17:30:54 INFO - 'LOGNAME': 'cltbld', 17:30:54 INFO - 'MAIL': '/var/mail/cltbld', 17:30:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:30:54 INFO - 'MOZ_NO_REMOTE': '1', 17:30:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:30:54 INFO - 'NO_EM_RESTART': '1', 17:30:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:30:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:30:54 INFO - 'PWD': '/builds/slave/test', 17:30:54 INFO - 'SHELL': '/bin/bash', 17:30:54 INFO - 'SHLVL': '1', 17:30:54 INFO - 'TERM': 'linux', 17:30:54 INFO - 'TMOUT': '86400', 17:30:54 INFO - 'USER': 'cltbld', 17:30:54 INFO - 'XDG_SESSION_COOKIE': 'ca6c2627acea03e666270f68000001e0-1505349022.485655-1085936112', 17:30:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:30:54 INFO - '_': '/tools/buildbot/bin/python'} 17:30:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:30:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:30:54 INFO - Running setup.py (path:/tmp/pip-rYHmMd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:30:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:30:54 INFO - Running setup.py (path:/tmp/pip-OrMRT6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:30:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:30:54 INFO - Running setup.py (path:/tmp/pip-ob6RTq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:30:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:30:54 INFO - Running setup.py (path:/tmp/pip-popzFi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:30:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:30:54 INFO - Running setup.py (path:/tmp/pip-IzqOK9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:30:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:30:54 INFO - Running setup.py (path:/tmp/pip-nhgl0L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:30:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:30:54 INFO - Running setup.py (path:/tmp/pip-425fuW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:30:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:30:54 INFO - Running setup.py (path:/tmp/pip-wKlBDZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:30:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:30:54 INFO - Running setup.py (path:/tmp/pip-Qm_y5n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:30:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:30:54 INFO - Running setup.py (path:/tmp/pip-_YzEzX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:30:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:30:54 INFO - Running setup.py (path:/tmp/pip-cIQXtx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:30:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:30:55 INFO - Running setup.py (path:/tmp/pip-YX56gw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:30:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:30:55 INFO - Running setup.py (path:/tmp/pip-66Opet-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:30:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:30:55 INFO - Running setup.py (path:/tmp/pip-OO3LNe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:30:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:30:55 INFO - Running setup.py (path:/tmp/pip-_c3fjl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:30:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:30:55 INFO - Running setup.py (path:/tmp/pip-Raj_Mv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:30:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:30:55 INFO - Running setup.py (path:/tmp/pip-KqCwPV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:30:55 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:30:55 INFO - Running setup.py install for manifestparser 17:30:55 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 17:30:55 INFO - Running setup.py install for mozcrash 17:30:55 INFO - Running setup.py install for mozdebug 17:30:55 INFO - Running setup.py install for mozdevice 17:30:55 INFO - Installing dm script to /builds/slave/test/build/venv/bin 17:30:55 INFO - Running setup.py install for mozfile 17:30:55 INFO - Running setup.py install for mozhttpd 17:30:56 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 17:30:56 INFO - Running setup.py install for mozinfo 17:30:56 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 17:30:56 INFO - Running setup.py install for mozInstall 17:30:56 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 17:30:56 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 17:30:56 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 17:30:56 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 17:30:56 INFO - Running setup.py install for mozleak 17:30:56 INFO - Running setup.py install for mozlog 17:30:56 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 17:30:56 INFO - Running setup.py install for moznetwork 17:30:56 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 17:30:56 INFO - Running setup.py install for mozprocess 17:30:56 INFO - Running setup.py install for mozprofile 17:30:56 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 17:30:56 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 17:30:56 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 17:30:56 INFO - Running setup.py install for mozrunner 17:30:56 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 17:30:56 INFO - Running setup.py install for mozscreenshot 17:30:57 INFO - Running setup.py install for moztest 17:30:57 INFO - Running setup.py install for mozversion 17:30:57 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 17:30:57 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:30:57 INFO - Cleaning up... 17:30:57 INFO - Return code: 0 17:30:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:30:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:30:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:30:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:30:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:30:57 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 0xaf31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcbca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe5fa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe63090>, '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 0xe63d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe5df90>, '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': 'ca6c2627acea03e666270f68000001e0-1505349022.485655-1085936112', '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 17:30:57 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 17:30:57 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 17:30:57 INFO - Using env: {'DISPLAY': ':0', 17:30:57 INFO - 'HOME': '/home/cltbld', 17:30:57 INFO - 'LANG': 'en_US.UTF-8', 17:30:57 INFO - 'LANGUAGE': 'en_US:en', 17:30:57 INFO - 'LOGNAME': 'cltbld', 17:30:57 INFO - 'MAIL': '/var/mail/cltbld', 17:30:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:30:57 INFO - 'MOZ_NO_REMOTE': '1', 17:30:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:30:57 INFO - 'NO_EM_RESTART': '1', 17:30:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:30:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:30:57 INFO - 'PWD': '/builds/slave/test', 17:30:57 INFO - 'SHELL': '/bin/bash', 17:30:57 INFO - 'SHLVL': '1', 17:30:57 INFO - 'TERM': 'linux', 17:30:57 INFO - 'TMOUT': '86400', 17:30:57 INFO - 'USER': 'cltbld', 17:30:57 INFO - 'XDG_SESSION_COOKIE': 'ca6c2627acea03e666270f68000001e0-1505349022.485655-1085936112', 17:30:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:30:57 INFO - '_': '/tools/buildbot/bin/python'} 17:30:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:30:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:30:57 INFO - Running setup.py (path:/tmp/pip-RDehCf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:30:57 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)) 17:30:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:30:57 INFO - Running setup.py (path:/tmp/pip-BLYgVG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:30:57 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)) 17:30:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:30:57 INFO - Running setup.py (path:/tmp/pip-NWv6Fd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:30:57 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)) 17:30:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:30:57 INFO - Running setup.py (path:/tmp/pip-t59BEK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:30:57 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)) 17:30:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:30:57 INFO - Running setup.py (path:/tmp/pip-Q7UU8L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:30:57 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)) 17:30:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:30:57 INFO - Running setup.py (path:/tmp/pip-Gz9zic-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:30:57 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)) 17:30:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:30:57 INFO - Running setup.py (path:/tmp/pip-htd_PR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:30:57 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)) 17:30:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:30:57 INFO - Running setup.py (path:/tmp/pip-RZQHM_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:30:58 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)) 17:30:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:30:58 INFO - Running setup.py (path:/tmp/pip-JOP0XE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:30:58 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)) 17:30:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:30:58 INFO - Running setup.py (path:/tmp/pip-rwkRUT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:30:58 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)) 17:30:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:30:58 INFO - Running setup.py (path:/tmp/pip-EoAPXI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:30:58 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)) 17:30:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:30:58 INFO - Running setup.py (path:/tmp/pip-QqFaMZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:30:58 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)) 17:30:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:30:58 INFO - Running setup.py (path:/tmp/pip-AiOWl0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:30:58 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)) 17:30:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:30:58 INFO - Running setup.py (path:/tmp/pip-38A8bk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:30:58 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)) 17:30:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:30:58 INFO - Running setup.py (path:/tmp/pip-BjrgMG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:30:58 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)) 17:30:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:30:58 INFO - Running setup.py (path:/tmp/pip-eGgpPX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:30:58 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)) 17:30:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:30:58 INFO - Running setup.py (path:/tmp/pip-ibuIa6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:30:58 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)) 17:30:58 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)) 17:30:58 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)) 17:30:58 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)) 17:30:58 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)) 17:30:58 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)) 17:30:58 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)) 17:30:58 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:30:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:30:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:30:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:30:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:30:59 INFO - Downloading blessings-1.6.tar.gz 17:30:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 17:30:59 INFO - Installing collected packages: blessings 17:30:59 INFO - Running setup.py install for blessings 17:30:59 INFO - Successfully installed blessings 17:30:59 INFO - Cleaning up... 17:31:00 INFO - Return code: 0 17:31:00 INFO - Done creating virtualenv /builds/slave/test/build/venv. 17:31:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:31:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:31:00 INFO - Reading from file tmpfile_stdout 17:31:00 INFO - Current package versions: 17:31:00 INFO - argparse == 1.2.1 17:31:00 INFO - blessings == 1.6 17:31:00 INFO - blobuploader == 1.2.4 17:31:00 INFO - docopt == 0.6.1 17:31:00 INFO - functools32 == 3.2.3-2 17:31:00 INFO - jsonschema == 2.5.1 17:31:00 INFO - manifestparser == 1.1 17:31:00 INFO - mozInstall == 1.13 17:31:00 INFO - mozcrash == 1.0 17:31:00 INFO - mozdebug == 0.1 17:31:00 INFO - mozdevice == 0.51 17:31:00 INFO - mozfile == 1.2 17:31:00 INFO - mozhttpd == 0.7 17:31:00 INFO - mozinfo == 0.10 17:31:00 INFO - mozleak == 0.1 17:31:00 INFO - mozlog == 3.5 17:31:00 INFO - moznetwork == 0.27 17:31:00 INFO - mozprocess == 0.25 17:31:00 INFO - mozprofile == 0.28 17:31:00 INFO - mozrunner == 6.13 17:31:00 INFO - mozscreenshot == 0.1 17:31:00 INFO - mozsystemmonitor == 0.3 17:31:00 INFO - moztest == 0.8 17:31:00 INFO - mozversion == 1.4 17:31:00 INFO - psutil == 3.1.1 17:31:00 INFO - requests == 1.2.3 17:31:00 INFO - wsgiref == 0.1.2 17:31:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:31:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:31:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:31:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:31:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:31:00 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 0xaf31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcbca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe5fa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe63090>, '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 0xe63d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe5df90>, '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': 'ca6c2627acea03e666270f68000001e0-1505349022.485655-1085936112', '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 17:31:00 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 17:31:00 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 17:31:00 INFO - Using env: {'DISPLAY': ':0', 17:31:00 INFO - 'HOME': '/home/cltbld', 17:31:00 INFO - 'LANG': 'en_US.UTF-8', 17:31:00 INFO - 'LANGUAGE': 'en_US:en', 17:31:00 INFO - 'LOGNAME': 'cltbld', 17:31:00 INFO - 'MAIL': '/var/mail/cltbld', 17:31:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:31:00 INFO - 'MOZ_NO_REMOTE': '1', 17:31:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:31:00 INFO - 'NO_EM_RESTART': '1', 17:31:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:31:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:31:00 INFO - 'PWD': '/builds/slave/test', 17:31:00 INFO - 'SHELL': '/bin/bash', 17:31:00 INFO - 'SHLVL': '1', 17:31:00 INFO - 'TERM': 'linux', 17:31:00 INFO - 'TMOUT': '86400', 17:31:00 INFO - 'USER': 'cltbld', 17:31:00 INFO - 'XDG_SESSION_COOKIE': 'ca6c2627acea03e666270f68000001e0-1505349022.485655-1085936112', 17:31:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:31:00 INFO - '_': '/tools/buildbot/bin/python'} 17:31:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:31:00 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)) 17:31:00 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)) 17:31:00 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)) 17:31:00 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)) 17:31:00 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)) 17:31:00 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)) 17:31:00 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)) 17:31:00 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)) 17:31:00 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)) 17:31:00 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 17:31:00 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 17:31:00 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 17:31:00 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 17:31:00 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 17:31:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 17:31:01 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)) 17:31:01 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)) 17:31:01 INFO - Installing collected packages: simplejson 17:31:01 INFO - Running setup.py install for simplejson 17:31:01 INFO - building 'simplejson._speedups' extension 17:31:01 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 17:31:02 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 17:31:02 INFO - Successfully installed simplejson 17:31:02 INFO - Cleaning up... 17:31:02 INFO - Return code: 0 17:31:02 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 17:31:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:31:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:31:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:31:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:31:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xaf31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcbca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe5fa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe63090>, '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 0xe63d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe5df90>, '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': 'ca6c2627acea03e666270f68000001e0-1505349022.485655-1085936112', '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 17:31:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 17:31:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 17:31:02 INFO - Using env: {'DISPLAY': ':0', 17:31:02 INFO - 'HOME': '/home/cltbld', 17:31:02 INFO - 'LANG': 'en_US.UTF-8', 17:31:02 INFO - 'LANGUAGE': 'en_US:en', 17:31:02 INFO - 'LOGNAME': 'cltbld', 17:31:02 INFO - 'MAIL': '/var/mail/cltbld', 17:31:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:31:02 INFO - 'MOZ_NO_REMOTE': '1', 17:31:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:31:02 INFO - 'NO_EM_RESTART': '1', 17:31:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:31:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:31:02 INFO - 'PWD': '/builds/slave/test', 17:31:02 INFO - 'SHELL': '/bin/bash', 17:31:02 INFO - 'SHLVL': '1', 17:31:02 INFO - 'TERM': 'linux', 17:31:02 INFO - 'TMOUT': '86400', 17:31:02 INFO - 'USER': 'cltbld', 17:31:02 INFO - 'XDG_SESSION_COOKIE': 'ca6c2627acea03e666270f68000001e0-1505349022.485655-1085936112', 17:31:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:31:02 INFO - '_': '/tools/buildbot/bin/python'} 17:31:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:31:02 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 17:31:02 INFO - Cleaning up... 17:31:02 INFO - Return code: 0 17:31:02 INFO - Running post-action listener: _resource_record_post_action 17:31:02 INFO - Running post-action listener: _start_resource_monitoring 17:31:02 INFO - Starting resource monitoring. 17:31:02 INFO - [mozharness: 2017-09-14 00:31:02.949077Z] Finished create-virtualenv step (success) 17:31:02 INFO - [mozharness: 2017-09-14 00:31:02.949597Z] Running install step. 17:31:02 INFO - Running pre-action listener: _resource_record_pre_action 17:31:02 INFO - Running main action method: install 17:31:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:31:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:31:03 INFO - Reading from file tmpfile_stdout 17:31:03 INFO - Detecting whether we're running mozinstall >=1.0... 17:31:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 17:31:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 17:31:03 INFO - Reading from file tmpfile_stdout 17:31:03 INFO - Output received: 17:31:03 INFO - Usage: mozinstall [options] installer 17:31:03 INFO - Options: 17:31:03 INFO - -h, --help show this help message and exit 17:31:03 INFO - -d DEST, --destination=DEST 17:31:03 INFO - Directory to install application into. [default: 17:31:03 INFO - "/builds/slave/test"] 17:31:03 INFO - --app=APP Application being installed. [default: firefox] 17:31:03 INFO - mkdir: /builds/slave/test/build/application 17:31:03 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'] 17:31:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 17:31:21 INFO - Reading from file tmpfile_stdout 17:31:21 INFO - Output received: 17:31:21 INFO - /builds/slave/test/build/application/firefox/firefox 17:31:21 INFO - Running post-action listener: _resource_record_post_action 17:31:21 INFO - [mozharness: 2017-09-14 00:31:21.034898Z] Finished install step (success) 17:31:21 INFO - [mozharness: 2017-09-14 00:31:21.035147Z] Running setup-mitmproxy step. 17:31:21 INFO - Running pre-action listener: _resource_record_pre_action 17:31:21 INFO - Running main action method: setup_mitmproxy 17:31:21 INFO - Skipping: mitmproxy is not required 17:31:21 INFO - Running post-action listener: _resource_record_post_action 17:31:21 INFO - [mozharness: 2017-09-14 00:31:21.036265Z] Finished setup-mitmproxy step (success) 17:31:21 INFO - [mozharness: 2017-09-14 00:31:21.036484Z] Running run-tests step. 17:31:21 INFO - Running pre-action listener: _resource_record_pre_action 17:31:21 INFO - Running pre-action listener: _set_gcov_prefix 17:31:21 INFO - Running main action method: run_tests 17:31:21 WARNING - Try message not found. 17:31:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 17:31:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 17:31:21 INFO - Python 2.7.3 17:31:21 INFO - Return code: 0 17:31:21 INFO - grabbing minidump binary from tooltool 17:31:21 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')]} 17:31:21 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 0xe63090>, '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 0xe63d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe5df90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 17:31:21 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 17:31:21 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 17:31:21 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 17:31:21 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:31:21 INFO - Return code: 0 17:31:21 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 17:31:21 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 17:31:21 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 17:31:21 INFO - ENV: RUST_BACKTRACE is now full 17:31:21 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 17:31:21 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 17:31:21 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/GhKihdsTQkWhAO3N2_oAlA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-050', '--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 17:31:21 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/GhKihdsTQkWhAO3N2_oAlA/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-050 --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 17:31:21 INFO - Using env: {'DISPLAY': ':0', 17:31:21 INFO - 'HOME': '/home/cltbld', 17:31:21 INFO - 'LANG': 'en_US.UTF-8', 17:31:21 INFO - 'LANGUAGE': 'en_US:en', 17:31:21 INFO - 'LOGNAME': 'cltbld', 17:31:21 INFO - 'MAIL': '/var/mail/cltbld', 17:31:21 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:31:21 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 17:31:21 INFO - 'MOZ_ACCELERATED': '1', 17:31:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:31:21 INFO - 'MOZ_NO_REMOTE': '1', 17:31:21 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:31:21 INFO - 'MOZ_WEBRENDER': '1', 17:31:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:31:21 INFO - 'NO_EM_RESTART': '1', 17:31:21 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:31:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:31:21 INFO - 'PWD': '/builds/slave/test', 17:31:21 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:31:21 INFO - 'RUST_BACKTRACE': 'full', 17:31:21 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 17:31:21 INFO - 'SHELL': '/bin/bash', 17:31:21 INFO - 'SHLVL': '1', 17:31:21 INFO - 'TERM': 'linux', 17:31:21 INFO - 'TMOUT': '86400', 17:31:21 INFO - 'USER': 'cltbld', 17:31:21 INFO - 'XDG_SESSION_COOKIE': 'ca6c2627acea03e666270f68000001e0-1505349022.485655-1085936112', 17:31:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:31:21 INFO - '_': '/tools/buildbot/bin/python'} 17:31:21 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/GhKihdsTQkWhAO3N2_oAlA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-050', '--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 17:31:21 INFO - mozversion application_buildid: 20170913233914 17:31:21 INFO - mozversion application_changeset: c15e2f280729b6503f9455cd4448ab2852eb5806 17:31:21 INFO - mozversion application_display_name: Nightly 17:31:21 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:31:21 INFO - mozversion application_name: Firefox 17:31:21 INFO - mozversion application_remotingname: firefox 17:31:21 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 17:31:21 INFO - mozversion application_vendor: Mozilla 17:31:21 INFO - mozversion application_version: 57.0a1 17:31:21 INFO - mozversion platform_buildid: 20170913233914 17:31:21 INFO - mozversion platform_changeset: c15e2f280729b6503f9455cd4448ab2852eb5806 17:31:21 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 17:31:21 INFO - mozversion platform_version: 57.0a1 17:31:21 INFO - using testdate: 1505349081 17:31:21 INFO - actual date: 1505349081 17:31:21 INFO - starting webserver on 'localhost:59207' 17:31:21 INFO - SUITE-START | Running 2 tests 17:31:21 INFO - TEST-START | dromaeo_css 17:31:21 INFO - Initialising browser for dromaeo_css test... 17:31:21 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:59207/getInfo.html -profile /tmp/tmpKxEWVm/profile 17:31:21 INFO - TEST-INFO | started process 24432 (/builds/slave/test/build/application/firefox/firefox http://localhost:59207/getInfo.html) 17:31:27 INFO - TEST-INFO | 24432: exit 0 17:31:28 INFO - Browser initialized. 17:31:28 INFO - Running cycle 1/1 for dromaeo_css test... 17:31:28 INFO - Using env: {'DISPLAY': ':0', 17:31:28 INFO - 'HOME': '/home/cltbld', 17:31:28 INFO - 'JSGC_DISABLE_POISONING': '1', 17:31:28 INFO - 'LANG': 'en_US.UTF-8', 17:31:28 INFO - 'LANGUAGE': 'en_US:en', 17:31:28 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 17:31:28 INFO - 'LOGNAME': 'cltbld', 17:31:28 INFO - 'MAIL': '/var/mail/cltbld', 17:31:28 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:31:28 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 17:31:28 INFO - 'MOZ_ACCELERATED': '1', 17:31:28 INFO - 'MOZ_CRASHREPORTER': '1', 17:31:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:31:28 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 17:31:28 INFO - 'MOZ_NO_REMOTE': '1', 17:31:28 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:31:28 INFO - 'MOZ_WEBRENDER': '1', 17:31:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:31:28 INFO - 'NO_EM_RESTART': '1', 17:31:28 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:31:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:31:28 INFO - 'PWD': '/builds/slave/test', 17:31:28 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:31:28 INFO - 'RUST_BACKTRACE': 'full', 17:31:28 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 17:31:28 INFO - 'SHELL': '/bin/bash', 17:31:28 INFO - 'SHLVL': '1', 17:31:28 INFO - 'TERM': 'linux', 17:31:28 INFO - 'TMOUT': '86400', 17:31:28 INFO - 'USER': 'cltbld', 17:31:28 INFO - 'XDG_SESSION_COOKIE': 'ca6c2627acea03e666270f68000001e0-1505349022.485655-1085936112', 17:31:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:31:28 INFO - '_': '/tools/buildbot/bin/python'} 17:31:28 INFO - TEST-INFO | started process 24679 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpKxEWVm/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 17:31:28 INFO - PID 24679 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 17:31:29 INFO - PID 24679 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 17:31:29 INFO - PID 24679 | 17:31:29 INFO - PID 24679 | (/builds/slave/test/build/application/firefox/firefox:24739): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 17:31:29 INFO - PID 24679 | 17:31:39 INFO - PID 24679 | RSS: Main: 218025984 17:31:39 INFO - PID 24679 | 17:34:37 INFO - PID 24679 | Cycle 1(1): loaded http://localhost:59207/tests/dromaeo/cssquery-dojo.html (next: http://localhost:59207/tests/dromaeo/cssquery-ext.html) 17:34:39 INFO - PID 24679 | RSS: Main: 228503552 17:34:39 INFO - PID 24679 | 17:37:41 INFO - PID 24679 | Cycle 1(1): loaded http://localhost:59207/tests/dromaeo/cssquery-ext.html (next: http://localhost:59207/tests/dromaeo/cssquery-jquery.html) 17:37:42 INFO - PID 24679 | RSS: Main: 227639296 17:37:42 INFO - PID 24679 | 17:40:44 INFO - PID 24679 | Cycle 1(1): loaded http://localhost:59207/tests/dromaeo/cssquery-jquery.html (next: http://localhost:59207/tests/dromaeo/cssquery-mootools.html) 17:40:44 INFO - PID 24679 | RSS: Main: 232935424 17:40:44 INFO - PID 24679 | 17:43:46 INFO - PID 24679 | Cycle 1(1): loaded http://localhost:59207/tests/dromaeo/cssquery-mootools.html (next: http://localhost:59207/tests/dromaeo/cssquery-prototype.html) 17:43:47 INFO - PID 24679 | RSS: Main: 228605952 17:43:47 INFO - PID 24679 | 17:46:50 INFO - PID 24679 | Cycle 1(1): loaded http://localhost:59207/tests/dromaeo/cssquery-prototype.html (next: http://localhost:59207/tests/dromaeo/cssquery-yui.html) 17:46:51 INFO - PID 24679 | RSS: Main: 229945344 17:46:51 INFO - PID 24679 | 17:49:53 INFO - PID 24679 | Cycle 1(1): loaded http://localhost:59207/tests/dromaeo/cssquery-yui.html (next: http://localhost:59207/tests/dromaeo/cssquery-dojo.html) 17:49:53 INFO - PID 24679 | RSS: Main: 233074688 17:49:53 INFO - PID 24679 | 17:49:53 INFO - PID 24679 | __start_tp_report 17:49:53 INFO - PID 24679 | _x_x_mozilla_page_load 17:49:53 INFO - PID 24679 | _x_x_mozilla_page_load_details 17:49:53 INFO - PID 24679 | |i|pagename|runs| 17:49:53 INFO - PID 24679 | |0;dojo.html;3149.0710240479057;3193.5848339715835;3197.8880739174133;3205.390975714614;3210.8715651373946;6159.3224745277985;6273.592216505929;6301.873962520753;6467.611943283407;6470.38531339522;6258.311585725566;6271.404216599427;6285.025820997753;6312.210973628296;6320.11518387425;5745.827625171251;5757.625754325955;5771.595988280822;5829.621074630136;5855.326637436686;5868.853278668024;5913.408659134096;5947.613405128653;5995.340512543617;5996.7601295948325;5827.125931110325;5837.6497410155525;5930.762769489215;5950.137230101633;5960.52315814736;5627.774889004435;5696.572757043238;5704.344000439961;5854.619449735754;5893.764249430018;5763;5783.652980821164;5836.854078648025;5846.444587764198;5854.7072646367515;6178.474829639486;6313.842153946142;6343.492520598341;6352.936470635281;6402.807915762534;6670.533062685614;6712.865742685168;6713.362230588086;6720.126383570154;6747.628880411576;6785.321467853225;6799;6819.829504262383;6827.5562088464585;6985.860282794367;6296.6851657417865;6355.142055822497;6424.646644434554;6461.386168313956;6464.515161362917;6940.09778728762;6997.475189360818;7007.824804379931;7112;7136.107986501687;6584.308647591934;6661.833454163588;6706.128203333536;6728.461723062143;6835.077264569319;6845.691943862538;6877.690503927335;6877.690503927335;6880.5527640704195;6886.48351373649;6801;6825.965870170618;6866.485013623998;6883.036374907517;6906.585604863624;6793.932060679429;6833.69248383814;6918.584884906922;6922.06552115468;6923.342282483156;5191.325127733446;5273.604479664041;5289.2859463972645;5293.788248470018;5384.9461505385225;4675.392199014106;4693.530646935279;4696.530346965276;4744.217204161275;4747.928781068286;5776.364599894008;5834.299884013945;5851.444112809277;5865.912011319833;5908.852278693067;6850.691718872663;6855.451563874878;6898.620575868375;6929.203141638675;6929.965350173217;5803.593748437654;5812.38969908162;5851.444112809277;5891;5893.497158224711;5709.885802283931;5798.797042103591;5799.072148456226;5809.24479817621;5815.069588865761;6542.54202205841;6565.540412171198;6648.634325112165;6649.900251496231;6667.86664266712;6923.826904327432;6936.514443988873;6943.618101004493;6943.652817359213;6980.7905762827195;6624.668766561749;6628.5691430056895;6639.468842492685;6678;6737.090492783412;6545.476361891133;6551.639659818756;6556.246031706224;6726.529142959946;6850.760223392157;6743;6778.525503214728;6784.932150678627;6871.278515755774;6879.0025446311;6669.09967154428;6733.663316834237;6750.459963202834;6751.93248067513;6751.932480675327;6634.237062737847;6659.700313485808;6725.630090345078;6735.932640673726;6741.157355330583;6395;6522.804315870531;6583.111279977142;6640.9335906642245;6675.833104172434;6486.4486518646445;6567.310432404529;6574.901376479453;6579.4736421085245;6683.832904177434;6441.71012304438;6463.353664633499;6477.578957367756;6482.675866206577;6522.771702990372 17:49:53 INFO - PID 24679 | |1;ext.html;816.9877451838223;837.5142417397902;838.2413915406555;844.8225872566761;847.6397531049305;36183.63816361836;36669.26661466772;39152;39235.41146882795;39255.41116883249;36939.26121477569;37275.254494910085;37395.25209495809;37553.43669844954;37582.24835503288;6946.131733533321;7071.752488662898;7088.929110708904;7099.6450177490915;7723.806904827368;7660.119086305062;7714.807129821743;7727.845443091135;7774.922250777505;7787.104482984472;7735.30382265596;7804.2585954334245;7820.921790782077;7822.608869556528;7824;7732.536047837148;7757.069151701776;7770.456068075208;7782.105057918356;7811.140774514799;7575.734849280304;7609.6956121755065;7684.346830519413;7731.072271327476;7745.806354841174;36339.273214535824;36574.08564785875;36705.26589468196;36730.26539469222;36761;3486.581610206766;3518.894433167009;3524.7004004659634;3528.59421166564;3531.2584357285;30859;30872.53691194634;31000;31284;31373;5035.0685123252315;5052.393712754456;5103.8213662522;5154.098032844275;5213.244079608474;189181;189584;189746;189944;190912.9999999986;41998.1600367997;48895;49062.75468622634;49078.26382604264;49084.26373604399;45143;48414.88473479723;51342;51749;51749.2237616436;16168.919155404148;16360.34558617642;16496.17519124039;16506.504804855875;16508.917455412648;13363.599092027254;13379.732405351839;13380.866191338157;13382.464701411835;13383.263920484467;14295;14322.57032289033;14384.35270412834;14436.566902992927;14609.561713148623;5747.827565172968;5795.594308398372;5830.5627077968475;5867.911981320283;5969.52243820502;962.6438217859297;969.2246203037541;984.601236499219;1007.2848277722752;1009.2834087797746;22115.225967091075;22370.32889013332;22384.888075559516;22546.436339091655;22563.323100307018;1064.1114669251263;1097.160672085855;1104.679642903549;1109.7059279290995;1120.9439528023565;11928.522859085713;12044.277343359427;12046.337451440255;12081.697957551132;12136.696582585506;221785;223061;224014;224174;224654;51496;51964;52094.73952630288;52148;52696.473035269155;1447.2835946206485;1450.187894778927;1474.3512854344053;1475.4688312207597;1479.0460153201125;21682.783172168078;22068.668969965143;22078.88960555251;22559.88720056389;22835.657465138367;36480.63519364772;36500.81749591235;36538.63461365352;36643.81678091593;36661.450078248316;200597;201716;202282;202309;202317;32999.17502062468;33331;34986.82506587451;35110.64889351176;35313;44264;45841.77079114583;46092.76953615344;46412;47001.529984699715;32991.175220619676;33220.66779332176;33349.33301334008;33521;33838.49242261319;32019;32166.19584510406;32427.35145297128;32678.019659410256;33111.50332745059;36773;37402.25195496021;37465.625343746215;37516;37521.24957500889;32136.35727285488;32368;32371.676283236866;32402.35195296128;33514.329713406085;23354.299371018897;23584.646230306214;23829.404264893517;23982.160624378062;24566.385840353418 17:49:53 INFO - PID 24679 | |2;jquery.html;538.376793966192;548.5173047718008;568.981523073698;569.1746966897999;583.938686437924;22057.779422205793;22809;22835.42911427213;22840.65739013912;22864.085436582536;24527;24575.877120614372;24737.257882263562;24850.130245441414;25291.62062569063;6360.554761166732;6520.510961677869;6546.378094081078;6559.508036897228;6584.637844918528;6506.316836732146;6514.283428822826;6546.607203567777;6554.803355899307;6569.441597464222;6177.227846519185;6399.072134540489;6405.903911441332;6420.646864422455;6428.389303016206;6414.454771344442;6449.806785744652;6454.774082907099;6477.481801455872;6491.448226900719;5839.474447299721;6007.309159446676;6015.699215039274;6016.75932962681;6032.065029920369;29556.556651650244;30005.549916751264;30012.099637010924;30053.849730751208;30143;2010.2461576908659;2033.4814622271226;2036.3585470576868;2040.4490787487443;2049.3442098528476;18526.444206673823;18691.71962420565;19055.142518586697;19179.712304315446;19282.32511862092;2313.935589628773;2379.8572085674914;2385.8449200801892;2392.736798952114;2397.113068164773;115672;122126;123087.38456307662;127775.36112319378;132259.33870330683;14371.78442323366;14425.278736063156;14426.927865360605;14436.278186090654;14635.560933172022;22259.554808903733;22370.776292237195;22468.438289042577;22524.436889077577;22536;9232.723018309462;9437.716868493957;9735.41587504752;9782.021797820162;9822.26333025026;8948.284137269004;8997.460152390877;9000.749812546863;9091.090890910857;9101.135392137736;8758.956205219061;8947.284217262604;8949.149830766068;9006.504642244608;9015.413998090104;2521.1932181701677;2633.289011966758;2665.7067722550505;2676.2774051006118;2801.6217810595717;927.3879239701764;943.7876477792441;964.8408012677829;969.5782334684294;972.3388096094582;19642.50893727668;19710.70433943492;19716.802831971498;19832.40502784919;20098.296559620343;1013.4274135113637;1014.2899970021015;1015.1827778638119;1038.1435315864442;1046.7173863056933;10949.288296260718;11137.944310278397;11167.162462815322;11267.380294083903;11337.943310283395;452552;459535;464692.3826904287;470492;476566;107770;110964;110966;111160;111457;1107.8227483602745;1132.4677401621225;1134.319408354997;1139.9031082358092;1144.1476100305326;17104.828951710322;17218.65562688764;17527.561810954827;17618.295268189642;17924.193411296263;54529;54599.72700136633;55946.44053559412;56356.43643563512;56972.430275696715;66093;66877;67003.3299666997;67477.32522674906;67612.66193669;13054.08621396493;13163.802542961672;13182.736345273233;13197.736045278849;13389;19771.802281977572;20251.898740506793;20448;20545.48636284105;20576.074076666286;15992.120433376276;16007.599810004378;16031.198440077716;16202.189890505191;16261.105639189958;13906;14000.929995349958;14644.487442939446;14677.55967320982;14808.481703140338;16567.66864662724;16623.50129496117;16863.74704379411;16933.49199524016;17029.744553831453;13273.800892986419;13298.268595227355;13341.866581334063;13382.13016153947;13470.730585388434;15909.68180636404;16056.357745690042;16189;16221.270042847864;16303.510894673178 17:49:53 INFO - PID 24679 | |3;mootools.html;1257.8679238679938;1355.7695191817384;1355.9525416610418;1357.640225340285;1357.816694746209;15385;15836.366545338202;15872.206389680527;15875.841241587581;15903.28435220415;15319.846801531981;15453.22733863308;15487.612809679764;15628.140452275149;15650.060996340228;2696.865156742165;2713.2402927180374;2755.2423083652056;2756.944861102787;2760.544510155822;2670.799690023256;2713.6879258885183;2722.659667541557;2735.5076086304457;2737.8220415673018;2608.9086881959133;2747.3955729739523;2790.4419116176746;2801.215659615311;2801.453716525278;2667.439837634099;2703.9324016899536;2720.2519307190482;2724.101046654599;2736.0560606590693;2707.986460067707;2712.593111033341;2719.02115238514;2731.3717844895787;2736.4390299988454;14391.784123238054;14576.344064517016;14653.70692586153;14673.633159170999;14681.412743490248;7879.566623835687;8112.351011919032;8155.673773049071;8166.265036146775;8181.8363632727605;5229.477052294778;5237.687451456822;5300.496452836975;5304.92042619361;5334.893302133974;1907.551725344543;1920.5486710622993;1952.9218831246733;1965.7837637859825;1967.2426115945464;26428.999999999807;26445.33886652849;26497.337566560796;26787.33031674204;26798.196054118212;6691.230508491586;6795.694193761293;6854.897176542356;7037.507374483839;7115.75094871677;17139.571510712332;17147.228374723167;17260.309587616604;17310.221040053228;17326.826731732774;7603.125640551297;7796.181400952932;7848.13670496245;7864.2922137007945;7875.645595948196;7152.785416437515;7233.240509746506;7341.302576255247;7399.112106547249;7408.740694075682;4990.975045124751;5253.579713622901;5280.339957505312;5302.522772950453;5316.388615309212;1343.7446885091802;1369.6233535777542;1581.0908727481744;1588.094785971985;1600.591849078501;865.5499140446932;883.7878909061778;895.8477058900003;904.9411788233743;913.1279627955287;8587.95706021466;8672.306215502746;8684.696035638723;8697.869531957025;8705.303575713928;725.4740313272835;797.8125140591957;979.3389462113046;1016.3190662256306;1016.6289304403839;5318.44156363584;5438.374586922476;5445.455454454523;5532.6680399176175;5577.776888924398;21176.15295388198;21828;21960.45098872509;22120;22141.889290553765;20924.895375523025;21470.677939830915;21544.892275538834;21712.891435542722;21943;10996.95528924751;11244.831327530093;11324.207305488702;11349.829752553553;11437.142214333959;8138.470999385021;8465;8474.66101355939;8492.915070849212;8600.526971016654;18377.632447351247;18427.53931151732;18536.16587253604;18609;18748.437546873614;18770.906145469184;18820.52948676294;18907.243710251434;19143.52141196481;19485.415437536896;10916;11125.721856953642;11134.220604557604;11148.052415544445;11171.608993685182;14003.719925601634;14082.78875816843;14170.07894486855;14234.35945382481;14271.714565708835;10468.581256749645;10894.291871028357;11150.776984460428;11216.270942389045;11283;10905.509252083517;10914.836277456003;11007.284526505751;11239.943800280946;11308.660740177807;9384.577694003923;9460.290478214023;9561.665341713007;9570.760730981781;9574.377665451724;7291.197968223385;7298.63506824646;7333.230010848997;7369.889451658337;7384.784564630697;7145.714171433085;7175.964120179366;7205;7287.417006639562;7310 17:49:53 INFO - PID 24679 | |4;prototype.html;501.94227663818674;505.0830168311487;520.0950346397269;527.4672580693504;529.9427641854501;6213.782517611885;6253.24961004679;6266.811995640127;6270.27891792444;6328.5886417382835;6113.174721412608;6127.63234205948;6140.478059364959;6214.689265536716;6253.59351642143;5042.974785126069;5061.822836200734;5081.872953176182;5099.923501147486;5111.539961403473;5078.238264260372;5082.745862706868;5088.26998820401;5091.643584949055;5130.358705161855;4706.505816889228;4813.181759100961;4928.708150709919;5028.7988480460735;5048.646594738351;4844.539768721966;4867.464578896354;4905.8773530661665;4909.288153217799;4946.802127914879;4331.155424692186;4340.8480703175555;4380.89047773805;4398.846040388571;4411.669124815651;5897.380775018647;6051.608130130051;6064.9090263646085;6124.142620033199;6140.570160088796;5479.369872464637;5554.416786237427;5560.302398904723;5606.467385598362;5637.492625663669;6484.740610375579;6532;6535.379138981789;6562.212534495891;6565.409113179789;4482.663800214997;4767.952320476786;4789.976050119727;4846.321514987904;4852.374454557692;6670.4663626910215;6688.966555167193;6733.595984240961;6758.932410675929;6837.213720422165;4854.199057155601;4919.508049195123;4926.679765815211;4942.406911170682;4953.405591328991;4932.53140951609;4989.351384320088;4993.400791904995;5007.7245751484015;5036.269740887551;5530.723463826793;5541.750621221974;5606.747696353592;5617.578681598897;5663.405342439067;6571.094382628984;6590.670466476657;6634.601923884582;6657.733690652417;6676.332366763285;1711.5293294344156;1719.6818588561157;1737.3832289537286;1750.6761249168944;1750.8706884059966;1138.9430528473542;1183.8694047184918;1192.4872304908972;1192.5885569478517;1201.4773573495556;5614.9157762633595;5625.324961004706;5625.887482250414;5685.289338832646;5694.5159661428415;6626.668666566652;6629.326324940905;6730.9326906731285;6747.190337159573;6771.408718951043;5499.1476321170285;5592;5593.552515798727;5638.573719630071;5707.6290041147195;3462.79223246606;3483.1814523586936;3486.3550243205086;3487.773294735834;3510.8069056202153;6336.904946425807;6350.904736428957;6373.649449280241;6385.744570217233;6443.29123796382;6729.730810767613;6776.390124888685;6818;6882.139732533434;6960.37356637905;6260.937390626126;6349.174607301022;6372.203474565679;6440.710168042449;6444.194475690539;6019.428154325332;6598.736050558116;6610.3720146586;6632.933670663231;6669.7665581704405;6428.710708018057;6457.321981192095;6495.285518593046;6617.702203400762;6639.605682806665;6231.345708700521;6326.279699729161;6375.171227740364;6386.9361306386345;6387.457066149429;4757.738324392191;4811.951880481291;4863.197572400514;4878.414590249051;4883.194272944925;6428.55000149985;6510.609363438209;6526.487302388519;6604.735810567524;6630.701618427279;6351.68241587928;6356.5232607555545;6470.48236141117;6472.320406357265;6537.803865884031;6278.277998030285;6366.554341196072;6407.647579382992;6420.325865784025;6547.247066587213;6350.491960643045;6368.42684158428;6426.164598602152;6466;6499.28507864144;6387.712552935036;6566.573172743756;6566.9343306568235;6569.441597464269;6614.966925165343;6274.435300822765;6283.905741413791;6302.432781049728;6354.17395738551;6386.584871983307 17:49:53 INFO - PID 24679 | |5;yui.html;886.2998231397197;888.1873086126191;914.5610107148566;914.7164379042493;918.5958178401503;18571.814281857176;19251.807481925178;19330.93679847608;19381.32165374212;19404.417867463962;16417.097059661715;16674;16718.498445046636;16755.413560525383;16825.495235142935;947.5215016416699;960.0591420408005;963.6579014449875;965.3869792681628;970.5244430229172;951.700214432455;952.7046615549202;952.9475878826662;953.0755167487526;960.2414092866627;966.2704657983213;1013.5996281468815;1027.5889644142364;1031.9587216511368;1034.0745033195285;1009.9495025248782;1013.15401639631;1014.523174108168;1014.7260239735267;1016.3089099412397;740.304114132719;740.6111791309552;746.0413368821094;747.8093086263023;752.1463139336837;19040.52398690044;19811;20198.697019544717;20373.592528149355;20511.282105126247;21287.787122128735;21608.783912160834;21669.45826354338;21990.450238744;22064.11743530273;17044.48866534006;17143.057131857742;17255.82744172555;17280.049597272144;17547;3710.053936246267;3728.1611637381643;3729.8881033569037;3730.8694195703292;3742.6257374262627;99581.39349011978;104861.47569262181;135356.32321838426;139040;141298.29350853284;17262.395816146374;17719.114044297734;17850.5537361567;17858.28566857337;17878.28486860537;31831.681683183335;32266.193345166557;32451.02646920596;32491.675083249338;32520.83739581334;8866.202041816268;8997.19025287714;9071.954640226888;9082;9114.908850911539;4101.52832424269;4105.34314509677;4142.627163555295;4147.91704165915;4175.331946888544;5401.3248343957;5419.620626556103;5459.3994660587305;5525.613207075467;5577.944220557823;1092.3009274064605;1093.327603523827;1100.6422912553292;1103.0954617213968;1118.2172479264548;682.2086379799508;702.7399862051074;704.8907419350008;717.1573401253506;719.8769918926491;12259.632211033684;12432;12460.626181214579;12622.558210462774;12879.871201288055;684.0730809752713;688.2635579929528;704.7709494414336;710.8329542557495;718.0485856240398;5478.287822583039;5491.395946445968;5535.750891209906;5547.195656629766;5584.134459158837;124805;125804.37097814635;126013.36993314975;126695;127552;103065;106255.46872265743;107367;110572.4471377638;111092.4445377768;96299;96855;97373;97686.5115674417;98067.01932980721;10180.134688551556;10188.235882309005;10191.235657325878;10318.329308594917;10514.106300964198;42199.57800422079;43319.78340108405;43403.565964339956;44339.11321773611;44503.332450012625;55798.721006394706;56064.71967640299;56554.7172264136;57643.423565765486;57748;15177.696446071237;15299.694006120038;15517.45688900883;15609.141497217763;15631.84368156304;17312.826871731122;17404.912975435043;17563;17596.472105836845;17670.02814845196;15986;16107.758383624021;16141.59646008859;16233.10717910479;16281;15914;16142;16187.10970896612;16228.269727862511;16299.348026078824;15671.294791734628;15697.921510392374;15698;15863.762043569586;15895.602609934844;11587.8261826071;11768.117391195523;11872.168948173545;11967.581134660246;11969.64091077269;12003.579874704343;12008.699782505508;12103.213291136048;12136.211146275464;12306.953908917842 17:49:53 INFO - PID 24679 | __end_tp_report 17:49:53 INFO - PID 24679 | __start_cc_report 17:49:53 INFO - PID 24679 | _x_x_mozilla_cycle_collect,379 17:49:53 INFO - PID 24679 | __end_cc_report 17:49:53 INFO - PID 24679 | __startTimestamp1505350193823__endTimestamp 17:49:53 INFO - PID 24679 | 17:49:53 INFO - PID 24679 | ------- Summary: start ------- 17:49:53 INFO - PID 24679 | Number of tests: 6 17:49:53 INFO - PID 24679 | 17:49:53 INFO - PID 24679 | [#0] dojo.html Cycles:165 Average:6265.33 Median:6522.80 stddev:763.08 (11.7%) stddev-sans-first:725.21 17:49:53 INFO - PID 24679 | Values: 3149.1 3193.6 3197.9 3205.4 3210.9 6159.3 6273.6 6301.9 6467.6 6470.4 6258.3 6271.4 6285.0 6312.2 6320.1 5745.8 5757.6 5771.6 5829.6 5855.3 5868.9 5913.4 5947.6 5995.3 5996.8 5827.1 5837.6 5930.8 5950.1 5960.5 5627.8 5696.6 5704.3 5854.6 5893.8 5763.0 5783.7 5836.9 5846.4 5854.7 6178.5 6313.8 6343.5 6352.9 6402.8 6670.5 6712.9 6713.4 6720.1 6747.6 6785.3 6799.0 6819.8 6827.6 6985.9 6296.7 6355.1 6424.6 6461.4 6464.5 6940.1 6997.5 7007.8 7112.0 7136.1 6584.3 6661.8 6706.1 6728.5 6835.1 6845.7 6877.7 6877.7 6880.6 6886.5 6801.0 6826.0 6866.5 6883.0 6906.6 6793.9 6833.7 6918.6 6922.1 6923.3 5191.3 5273.6 5289.3 5293.8 5384.9 4675.4 4693.5 4696.5 4744.2 4747.9 5776.4 5834.3 5851.4 5865.9 5908.9 6850.7 6855.5 6898.6 6929.2 6930.0 5803.6 5812.4 5851.4 5891.0 5893.5 5709.9 5798.8 5799.1 5809.2 5815.1 6542.5 6565.5 6648.6 6649.9 6667.9 6923.8 6936.5 6943.6 6943.7 6980.8 6624.7 6628.6 6639.5 6678.0 6737.1 6545.5 6551.6 6556.2 6726.5 6850.8 6743.0 6778.5 6784.9 6871.3 6879.0 6669.1 6733.7 6750.5 6751.9 6751.9 6634.2 6659.7 6725.6 6735.9 6741.2 6395.0 6522.8 6583.1 6640.9 6675.8 6486.4 6567.3 6574.9 6579.5 6683.8 6441.7 6463.4 6477.6 6482.7 6522.8 17:49:53 INFO - PID 24679 | 17:49:53 INFO - PID 24679 | [#1] ext.html Cycles:180 Average:37281.93 Median:19422.13 stddev:53202.66 (273.9%) stddev-sans-first:53281.45 17:49:53 INFO - PID 24679 | Values: 817.0 837.5 838.2 844.8 847.6 36183.6 36669.3 39152.0 39235.4 39255.4 36939.3 37275.3 37395.3 37553.4 37582.2 6946.1 7071.8 7088.9 7099.6 7723.8 7660.1 7714.8 7727.8 7774.9 7787.1 7735.3 7804.3 7820.9 7822.6 7824.0 7732.5 7757.1 7770.5 7782.1 7811.1 7575.7 7609.7 7684.3 7731.1 7745.8 36339.3 36574.1 36705.3 36730.3 36761.0 3486.6 3518.9 3524.7 3528.6 3531.3 30859.0 30872.5 31000.0 31284.0 31373.0 5035.1 5052.4 5103.8 5154.1 5213.2 189181.0 189584.0 189746.0 189944.0 190913.0 41998.2 48895.0 49062.8 49078.3 49084.3 45143.0 48414.9 51342.0 51749.0 51749.2 16168.9 16360.3 16496.2 16506.5 16508.9 13363.6 13379.7 13380.9 13382.5 13383.3 14295.0 14322.6 14384.4 14436.6 14609.6 5747.8 5795.6 5830.6 5867.9 5969.5 962.6 969.2 984.6 1007.3 1009.3 22115.2 22370.3 22384.9 22546.4 22563.3 1064.1 1097.2 1104.7 1109.7 1120.9 11928.5 12044.3 12046.3 12081.7 12136.7 221785.0 223061.0 224014.0 224174.0 224654.0 51496.0 51964.0 52094.7 52148.0 52696.5 1447.3 1450.2 1474.4 1475.5 1479.0 21682.8 22068.7 22078.9 22559.9 22835.7 36480.6 36500.8 36538.6 36643.8 36661.5 200597.0 201716.0 202282.0 202309.0 202317.0 32999.2 33331.0 34986.8 35110.6 35313.0 44264.0 45841.8 46092.8 46412.0 47001.5 32991.2 33220.7 33349.3 33521.0 33838.5 32019.0 32166.2 32427.4 32678.0 33111.5 36773.0 37402.3 37465.6 37516.0 37521.2 32136.4 32368.0 32371.7 32402.4 33514.3 23354.3 23584.6 23829.4 23982.2 24566.4 17:49:53 INFO - PID 24679 | 17:49:53 INFO - PID 24679 | [#2] jquery.html Cycles:180 Average:32897.52 Median:22672.50 stddev:78131.06 (344.6%) stddev-sans-first:78312.47 17:49:53 INFO - PID 24679 | Values: 538.4 548.5 569.0 569.2 583.9 22057.8 22809.0 22835.4 22840.7 22864.1 24527.0 24575.9 24737.3 24850.1 25291.6 6360.6 6520.5 6546.4 6559.5 6584.6 6506.3 6514.3 6546.6 6554.8 6569.4 6177.2 6399.1 6405.9 6420.6 6428.4 6414.5 6449.8 6454.8 6477.5 6491.4 5839.5 6007.3 6015.7 6016.8 6032.1 29556.6 30005.5 30012.1 30053.8 30143.0 2010.2 2033.5 2036.4 2040.4 2049.3 18526.4 18691.7 19055.1 19179.7 19282.3 2313.9 2379.9 2385.8 2392.7 2397.1 115672.0 122126.0 123087.4 127775.4 132259.3 14371.8 14425.3 14426.9 14436.3 14635.6 22259.6 22370.8 22468.4 22524.4 22536.0 9232.7 9437.7 9735.4 9782.0 9822.3 8948.3 8997.5 9000.7 9091.1 9101.1 8759.0 8947.3 8949.1 9006.5 9015.4 2521.2 2633.3 2665.7 2676.3 2801.6 927.4 943.8 964.8 969.6 972.3 19642.5 19710.7 19716.8 19832.4 20098.3 1013.4 1014.3 1015.2 1038.1 1046.7 10949.3 11137.9 11167.2 11267.4 11337.9 452552.0 459535.0 464692.4 470492.0 476566.0 107770.0 110964.0 110966.0 111160.0 111457.0 1107.8 1132.5 1134.3 1139.9 1144.1 17104.8 17218.7 17527.6 17618.3 17924.2 54529.0 54599.7 55946.4 56356.4 56972.4 66093.0 66877.0 67003.3 67477.3 67612.7 13054.1 13163.8 13182.7 13197.7 13389.0 19771.8 20251.9 20448.0 20545.5 20576.1 15992.1 16007.6 16031.2 16202.2 16261.1 13906.0 14000.9 14644.5 14677.6 14808.5 16567.7 16623.5 16863.7 16933.5 17029.7 13273.8 13298.3 13341.9 13382.1 13470.7 15909.7 16056.4 16189.0 16221.3 16303.5 17:49:53 INFO - PID 24679 | 17:49:53 INFO - PID 24679 | [#3] mootools.html Cycles:180 Average:9352.68 Median:2700.40 stddev:6685.93 (247.6%) stddev-sans-first:6677.03 17:49:53 INFO - PID 24679 | Values: 1257.9 1355.8 1356.0 1357.6 1357.8 15385.0 15836.4 15872.2 15875.8 15903.3 15319.8 15453.2 15487.6 15628.1 15650.1 2696.9 2713.2 2755.2 2756.9 2760.5 2670.8 2713.7 2722.7 2735.5 2737.8 2608.9 2747.4 2790.4 2801.2 2801.5 2667.4 2703.9 2720.3 2724.1 2736.1 2708.0 2712.6 2719.0 2731.4 2736.4 14391.8 14576.3 14653.7 14673.6 14681.4 7879.6 8112.4 8155.7 8166.3 8181.8 5229.5 5237.7 5300.5 5304.9 5334.9 1907.6 1920.5 1952.9 1965.8 1967.2 26429.0 26445.3 26497.3 26787.3 26798.2 6691.2 6795.7 6854.9 7037.5 7115.8 17139.6 17147.2 17260.3 17310.2 17326.8 7603.1 7796.2 7848.1 7864.3 7875.6 7152.8 7233.2 7341.3 7399.1 7408.7 4991.0 5253.6 5280.3 5302.5 5316.4 1343.7 1369.6 1581.1 1588.1 1600.6 865.5 883.8 895.8 904.9 913.1 8588.0 8672.3 8684.7 8697.9 8705.3 725.5 797.8 979.3 1016.3 1016.6 5318.4 5438.4 5445.5 5532.7 5577.8 21176.2 21828.0 21960.5 22120.0 22141.9 20924.9 21470.7 21544.9 21712.9 21943.0 10997.0 11244.8 11324.2 11349.8 11437.1 8138.5 8465.0 8474.7 8492.9 8600.5 18377.6 18427.5 18536.2 18609.0 18748.4 18770.9 18820.5 18907.2 19143.5 19485.4 10916.0 11125.7 11134.2 11148.1 11171.6 14003.7 14082.8 14170.1 14234.4 14271.7 10468.6 10894.3 11150.8 11216.3 11283.0 10905.5 10914.8 11007.3 11239.9 11308.7 9384.6 9460.3 9561.7 9570.8 9574.4 7291.2 7298.6 7333.2 7369.9 7384.8 7145.7 7176.0 7205.0 7287.4 7310.0 17:49:53 INFO - PID 24679 | 17:49:53 INFO - PID 24679 | [#4] prototype.html Cycles:180 Average:5441.59 Median:6125.89 stddev:1530.83 (25.0%) stddev-sans-first:1489.55 17:49:53 INFO - PID 24679 | Values: 501.9 505.1 520.1 527.5 529.9 6213.8 6253.2 6266.8 6270.3 6328.6 6113.2 6127.6 6140.5 6214.7 6253.6 5043.0 5061.8 5081.9 5099.9 5111.5 5078.2 5082.7 5088.3 5091.6 5130.4 4706.5 4813.2 4928.7 5028.8 5048.6 4844.5 4867.5 4905.9 4909.3 4946.8 4331.2 4340.8 4380.9 4398.8 4411.7 5897.4 6051.6 6064.9 6124.1 6140.6 5479.4 5554.4 5560.3 5606.5 5637.5 6484.7 6532.0 6535.4 6562.2 6565.4 4482.7 4768.0 4790.0 4846.3 4852.4 6670.5 6689.0 6733.6 6758.9 6837.2 4854.2 4919.5 4926.7 4942.4 4953.4 4932.5 4989.4 4993.4 5007.7 5036.3 5530.7 5541.8 5606.7 5617.6 5663.4 6571.1 6590.7 6634.6 6657.7 6676.3 1711.5 1719.7 1737.4 1750.7 1750.9 1138.9 1183.9 1192.5 1192.6 1201.5 5614.9 5625.3 5625.9 5685.3 5694.5 6626.7 6629.3 6730.9 6747.2 6771.4 5499.1 5592.0 5593.6 5638.6 5707.6 3462.8 3483.2 3486.4 3487.8 3510.8 6336.9 6350.9 6373.6 6385.7 6443.3 6729.7 6776.4 6818.0 6882.1 6960.4 6260.9 6349.2 6372.2 6440.7 6444.2 6019.4 6598.7 6610.4 6632.9 6669.8 6428.7 6457.3 6495.3 6617.7 6639.6 6231.3 6326.3 6375.2 6386.9 6387.5 4757.7 4812.0 4863.2 4878.4 4883.2 6428.6 6510.6 6526.5 6604.7 6630.7 6351.7 6356.5 6470.5 6472.3 6537.8 6278.3 6366.6 6407.6 6420.3 6547.2 6350.5 6368.4 6426.2 6466.0 6499.3 6387.7 6566.6 6566.9 6569.4 6615.0 6274.4 6283.9 6302.4 6354.2 6386.6 17:49:53 INFO - PID 24679 | 17:49:53 INFO - PID 24679 | [#5] yui.html Cycles:180 Average:24002.11 Median:19291.37 stddev:34368.88 (178.2%) stddev-sans-first:34421.46 17:49:53 INFO - PID 24679 | Values: 886.3 888.2 914.6 914.7 918.6 18571.8 19251.8 19330.9 19381.3 19404.4 16417.1 16674.0 16718.5 16755.4 16825.5 947.5 960.1 963.7 965.4 970.5 951.7 952.7 952.9 953.1 960.2 966.3 1013.6 1027.6 1032.0 1034.1 1009.9 1013.2 1014.5 1014.7 1016.3 740.3 740.6 746.0 747.8 752.1 19040.5 19811.0 20198.7 20373.6 20511.3 21287.8 21608.8 21669.5 21990.5 22064.1 17044.5 17143.1 17255.8 17280.0 17547.0 3710.1 3728.2 3729.9 3730.9 3742.6 99581.4 104861.5 135356.3 139040.0 141298.3 17262.4 17719.1 17850.6 17858.3 17878.3 31831.7 32266.2 32451.0 32491.7 32520.8 8866.2 8997.2 9072.0 9082.0 9114.9 4101.5 4105.3 4142.6 4147.9 4175.3 5401.3 5419.6 5459.4 5525.6 5577.9 1092.3 1093.3 1100.6 1103.1 1118.2 682.2 702.7 704.9 717.2 719.9 12259.6 12432.0 12460.6 12622.6 12879.9 684.1 688.3 704.8 710.8 718.0 5478.3 5491.4 5535.8 5547.2 5584.1 124805.0 125804.4 126013.4 126695.0 127552.0 103065.0 106255.5 107367.0 110572.4 111092.4 96299.0 96855.0 97373.0 97686.5 98067.0 10180.1 10188.2 10191.2 10318.3 10514.1 42199.6 43319.8 43403.6 44339.1 44503.3 55798.7 56064.7 56554.7 57643.4 57748.0 15177.7 15299.7 15517.5 15609.1 15631.8 17312.8 17404.9 17563.0 17596.5 17670.0 15986.0 16107.8 16141.6 16233.1 16281.0 15914.0 16142.0 16187.1 16228.3 16299.3 15671.3 15697.9 15698.0 15863.8 15895.6 11587.8 11768.1 11872.2 11967.6 11969.6 12003.6 12008.7 12103.2 12136.2 12307.0 17:49:53 INFO - PID 24679 | -------- Summary: end -------- 17:49:53 INFO - PID 24679 | 17:49:54 INFO - TEST-INFO | 24679: exit 0 17:49:54 INFO - TEST-OK | dromaeo_css | took 1113383ms 17:49:54 INFO - TEST-START | kraken 17:49:54 INFO - Initialising browser for kraken test... 17:49:54 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:59207/getInfo.html -profile /tmp/tmpKnSwpQ/profile 17:49:54 INFO - TEST-INFO | started process 25062 (/builds/slave/test/build/application/firefox/firefox http://localhost:59207/getInfo.html) 17:50:00 INFO - TEST-INFO | 25062: exit 0 17:50:00 INFO - Browser initialized. 17:50:00 INFO - Running cycle 1/1 for kraken test... 17:50:00 INFO - Using env: {'DISPLAY': ':0', 17:50:00 INFO - 'HOME': '/home/cltbld', 17:50:00 INFO - 'JSGC_DISABLE_POISONING': '1', 17:50:00 INFO - 'LANG': 'en_US.UTF-8', 17:50:00 INFO - 'LANGUAGE': 'en_US:en', 17:50:00 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 17:50:00 INFO - 'LOGNAME': 'cltbld', 17:50:00 INFO - 'MAIL': '/var/mail/cltbld', 17:50:00 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:50:00 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 17:50:00 INFO - 'MOZ_ACCELERATED': '1', 17:50:00 INFO - 'MOZ_CRASHREPORTER': '1', 17:50:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:50:00 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 17:50:00 INFO - 'MOZ_NO_REMOTE': '1', 17:50:00 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:50:00 INFO - 'MOZ_WEBRENDER': '1', 17:50:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:50:00 INFO - 'NO_EM_RESTART': '1', 17:50:00 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:50:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:50:00 INFO - 'PWD': '/builds/slave/test', 17:50:00 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:50:00 INFO - 'RUST_BACKTRACE': 'full', 17:50:00 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 17:50:00 INFO - 'SHELL': '/bin/bash', 17:50:00 INFO - 'SHLVL': '1', 17:50:00 INFO - 'TERM': 'linux', 17:50:00 INFO - 'TMOUT': '86400', 17:50:00 INFO - 'USER': 'cltbld', 17:50:00 INFO - 'XDG_SESSION_COOKIE': 'ca6c2627acea03e666270f68000001e0-1505349022.485655-1085936112', 17:50:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:50:00 INFO - '_': '/tools/buildbot/bin/python'} 17:50:00 INFO - TEST-INFO | started process 25306 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpKnSwpQ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 17:50:01 INFO - PID 25306 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 17:50:01 INFO - PID 25306 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 17:50:01 INFO - PID 25306 | 17:50:01 INFO - PID 25306 | (/builds/slave/test/build/application/firefox/firefox:25365): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 17:50:01 INFO - PID 25306 | 17:50:12 INFO - PID 25306 | RSS: Main: 222691328 17:50:12 INFO - PID 25306 | 17:50:15 INFO - PID 25306 | Cycle 1(1): loaded http://localhost:59207/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:59207/tests/kraken/driver.html?testName=audio-beat-detection) 17:50:16 INFO - PID 25306 | RSS: Main: 228655104 17:50:16 INFO - PID 25306 | 17:50:18 INFO - PID 25306 | Cycle 1(1): loaded http://localhost:59207/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:59207/tests/kraken/driver.html?testName=audio-dft) 17:50:19 INFO - PID 25306 | RSS: Main: 228917248 17:50:19 INFO - PID 25306 | 17:50:22 INFO - PID 25306 | Cycle 1(1): loaded http://localhost:59207/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:59207/tests/kraken/driver.html?testName=audio-fft) 17:50:23 INFO - PID 25306 | RSS: Main: 224157696 17:50:23 INFO - PID 25306 | 17:50:25 INFO - PID 25306 | Cycle 1(1): loaded http://localhost:59207/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:59207/tests/kraken/driver.html?testName=audio-oscillator) 17:50:25 INFO - PID 25306 | RSS: Main: 225918976 17:50:25 INFO - PID 25306 | 17:50:28 INFO - PID 25306 | Cycle 1(1): loaded http://localhost:59207/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:59207/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:50:28 INFO - PID 25306 | RSS: Main: 225869824 17:50:28 INFO - PID 25306 | 17:50:31 INFO - PID 25306 | Cycle 1(1): loaded http://localhost:59207/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:59207/tests/kraken/driver.html?testName=imaging-darkroom) 17:50:32 INFO - PID 25306 | RSS: Main: 228560896 17:50:32 INFO - PID 25306 | 17:50:36 INFO - PID 25306 | Cycle 1(1): loaded http://localhost:59207/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:59207/tests/kraken/driver.html?testName=imaging-desaturate) 17:50:36 INFO - PID 25306 | RSS: Main: 228397056 17:50:36 INFO - PID 25306 | 17:50:39 INFO - PID 25306 | Cycle 1(1): loaded http://localhost:59207/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:59207/tests/kraken/driver.html?testName=json-parse-financial) 17:50:40 INFO - PID 25306 | RSS: Main: 228102144 17:50:40 INFO - PID 25306 | 17:50:42 INFO - PID 25306 | Cycle 1(1): loaded http://localhost:59207/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:59207/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:50:42 INFO - PID 25306 | RSS: Main: 228130816 17:50:42 INFO - PID 25306 | 17:50:44 INFO - PID 25306 | Cycle 1(1): loaded http://localhost:59207/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:59207/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:50:45 INFO - PID 25306 | RSS: Main: 224772096 17:50:45 INFO - PID 25306 | 17:50:47 INFO - PID 25306 | Cycle 1(1): loaded http://localhost:59207/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:59207/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:50:48 INFO - PID 25306 | RSS: Main: 224886784 17:50:48 INFO - PID 25306 | 17:50:50 INFO - PID 25306 | Cycle 1(1): loaded http://localhost:59207/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:59207/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:50:51 INFO - PID 25306 | RSS: Main: 225918976 17:50:51 INFO - PID 25306 | 17:50:54 INFO - PID 25306 | Cycle 1(1): loaded http://localhost:59207/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:59207/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:50:55 INFO - PID 25306 | RSS: Main: 226070528 17:50:55 INFO - PID 25306 | 17:50:56 INFO - PID 25306 | Cycle 1(1): loaded http://localhost:59207/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:59207/tests/kraken/driver.html?testName=ai-astar) 17:50:57 INFO - PID 25306 | RSS: Main: 228544512 17:50:57 INFO - PID 25306 | 17:50:57 INFO - PID 25306 | __start_tp_report 17:50:57 INFO - PID 25306 | _x_x_mozilla_page_load 17:50:57 INFO - PID 25306 | _x_x_mozilla_page_load_details 17:50:57 INFO - PID 25306 | |i|pagename|runs| 17:50:57 INFO - PID 25306 | |0;ai-astar;110;111;109;111;107;111;115;111;120;111 17:50:57 INFO - PID 25306 | |1;audio-beat-detection;118;126;117;119;116;132;123;125;124;120 17:50:57 INFO - PID 25306 | |2;audio-dft;166;168;163;164;175;172;167;164;172;164 17:50:57 INFO - PID 25306 | |3;audio-fft;86;75;73;75;77;73;74;75;85;77 17:50:57 INFO - PID 25306 | |4;audio-oscillator;79;85;82;91;79;85;84;80;80;79 17:50:57 INFO - PID 25306 | |5;imaging-gaussian-blur;115;120;114;113;116;118;116;117;117;115 17:50:57 INFO - PID 25306 | |6;imaging-darkroom;153;150;148;148;148;156;153;147;153;150 17:50:57 INFO - PID 25306 | |7;imaging-desaturate;94;94;94;94;94;102;94;97;94;94 17:50:57 INFO - PID 25306 | |8;json-parse-financial;58;57;53;54;54;57;55;54;60;54 17:50:57 INFO - PID 25306 | |9;json-stringify-tinderbox;40;46;48;44;47;38;39;39;39;53 17:50:57 INFO - PID 25306 | |10;stanford-crypto-aes;78;82;82;79;85;76;80;86;83;72 17:50:57 INFO - PID 25306 | |11;stanford-crypto-ccm;112;117;129;119;115;122;112;114;127;120 17:50:57 INFO - PID 25306 | |12;stanford-crypto-pbkdf2;181;176;177;176;175;178;177;170;168;174 17:50:57 INFO - PID 25306 | |13;stanford-crypto-sha256-iterative;75;67;66;66;65;68;67;68;67;66 17:50:57 INFO - PID 25306 | __end_tp_report 17:50:57 INFO - PID 25306 | __start_cc_report 17:50:57 INFO - PID 25306 | _x_x_mozilla_cycle_collect,978 17:50:57 INFO - PID 25306 | __end_cc_report 17:50:57 INFO - PID 25306 | __startTimestamp1505350257233__endTimestamp 17:50:57 INFO - PID 25306 | 17:50:57 INFO - PID 25306 | ------- Summary: start ------- 17:50:57 INFO - PID 25306 | Number of tests: 14 17:50:57 INFO - PID 25306 | 17:50:57 INFO - PID 25306 | [#0] ai-astar Cycles:10 Average:111.60 Median:111.00 stddev:3.57 (3.2%) stddev-sans-first:3.73 17:50:57 INFO - PID 25306 | Values: 110.0 111.0 109.0 111.0 107.0 111.0 115.0 111.0 120.0 111.0 17:50:57 INFO - PID 25306 | 17:50:57 INFO - PID 25306 | [#1] audio-beat-detection Cycles:10 Average:122.00 Median:123.50 stddev:4.94 (4.0%) stddev-sans-first:5.03 17:50:57 INFO - PID 25306 | Values: 118.0 126.0 117.0 119.0 116.0 132.0 123.0 125.0 124.0 120.0 17:50:57 INFO - PID 25306 | 17:50:57 INFO - PID 25306 | [#2] audio-dft Cycles:10 Average:167.50 Median:167.50 stddev:4.17 (2.5%) stddev-sans-first:4.39 17:50:57 INFO - PID 25306 | Values: 166.0 168.0 163.0 164.0 175.0 172.0 167.0 164.0 172.0 164.0 17:50:57 INFO - PID 25306 | 17:50:57 INFO - PID 25306 | [#3] audio-fft Cycles:10 Average:77.00 Median:76.00 stddev:4.69 (6.2%) stddev-sans-first:3.67 17:50:57 INFO - PID 25306 | Values: 86.0 75.0 73.0 75.0 77.0 73.0 74.0 75.0 85.0 77.0 17:50:57 INFO - PID 25306 | 17:50:57 INFO - PID 25306 | [#4] audio-oscillator Cycles:10 Average:82.40 Median:83.00 stddev:3.89 (4.7%) stddev-sans-first:3.93 17:50:57 INFO - PID 25306 | Values: 79.0 85.0 82.0 91.0 79.0 85.0 84.0 80.0 80.0 79.0 17:50:57 INFO - PID 25306 | 17:50:57 INFO - PID 25306 | [#5] imaging-gaussian-blur Cycles:10 Average:116.10 Median:116.50 stddev:2.02 (1.7%) stddev-sans-first:2.11 17:50:57 INFO - PID 25306 | Values: 115.0 120.0 114.0 113.0 116.0 118.0 116.0 117.0 117.0 115.0 17:50:57 INFO - PID 25306 | 17:50:57 INFO - PID 25306 | [#6] imaging-darkroom Cycles:10 Average:150.60 Median:151.50 stddev:2.99 (2.0%) stddev-sans-first:3.04 17:50:57 INFO - PID 25306 | Values: 153.0 150.0 148.0 148.0 148.0 156.0 153.0 147.0 153.0 150.0 17:50:57 INFO - PID 25306 | 17:50:57 INFO - PID 25306 | [#7] imaging-desaturate Cycles:10 Average:95.10 Median:94.00 stddev:2.60 (2.8%) stddev-sans-first:2.73 17:50:57 INFO - PID 25306 | Values: 94.0 94.0 94.0 94.0 94.0 102.0 94.0 97.0 94.0 94.0 17:50:57 INFO - PID 25306 | 17:50:57 INFO - PID 25306 | [#8] json-parse-financial Cycles:10 Average:55.60 Median:56.00 stddev:2.27 (4.1%) stddev-sans-first:2.24 17:50:57 INFO - PID 25306 | Values: 58.0 57.0 53.0 54.0 54.0 57.0 55.0 54.0 60.0 54.0 17:50:57 INFO - PID 25306 | 17:50:57 INFO - PID 25306 | [#9] json-stringify-tinderbox Cycles:10 Average:43.30 Median:45.00 stddev:5.08 (11.3%) stddev-sans-first:5.24 17:50:57 INFO - PID 25306 | Values: 40.0 46.0 48.0 44.0 47.0 38.0 39.0 39.0 39.0 53.0 17:50:57 INFO - PID 25306 | 17:50:57 INFO - PID 25306 | [#10] stanford-crypto-aes Cycles:10 Average:80.30 Median:82.00 stddev:4.24 (5.2%) stddev-sans-first:4.42 17:50:57 INFO - PID 25306 | Values: 78.0 82.0 82.0 79.0 85.0 76.0 80.0 86.0 83.0 72.0 17:50:57 INFO - PID 25306 | 17:50:57 INFO - PID 25306 | [#11] stanford-crypto-ccm Cycles:10 Average:118.70 Median:119.50 stddev:5.93 (5.0%) stddev-sans-first:5.77 17:50:57 INFO - PID 25306 | Values: 112.0 117.0 129.0 119.0 115.0 122.0 112.0 114.0 127.0 120.0 17:50:57 INFO - PID 25306 | 17:50:57 INFO - PID 25306 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:175.20 Median:176.50 stddev:3.79 (2.1%) stddev-sans-first:3.40 17:50:57 INFO - PID 25306 | Values: 181.0 176.0 177.0 176.0 175.0 178.0 177.0 170.0 168.0 174.0 17:50:57 INFO - PID 25306 | 17:50:57 INFO - PID 25306 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.50 Median:67.00 stddev:2.80 (4.2%) stddev-sans-first:1.00 17:50:57 INFO - PID 25306 | Values: 75.0 67.0 66.0 66.0 65.0 68.0 67.0 68.0 67.0 66.0 17:50:57 INFO - PID 25306 | -------- Summary: end -------- 17:50:57 INFO - PID 25306 | 17:50:57 INFO - PID 25306 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 17:50:58 INFO - TEST-INFO | 25306: exit 0 17:50:58 INFO - TEST-OK | kraken | took 63421ms 17:50:58 INFO - SUITE-END | took 1176s 17:50:58 INFO - Completed test suite (00:19:36) 17:50:58 INFO - javascript benchmark 17:50:58 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8438.606429451529, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3149.0710240479057, 3193.5848339715835, 3197.8880739174133, 3205.390975714614, 3210.8715651373946, 6159.3224745277985, 6273.592216505929, 6301.873962520753, 6467.611943283407, 6470.38531339522, 6258.311585725566, 6271.404216599427, 6285.025820997753, 6312.210973628296, 6320.11518387425, 5745.827625171251, 5757.625754325955, 5771.595988280822, 5829.621074630136, 5855.326637436686, 5868.853278668024, 5913.408659134096, 5947.613405128653, 5995.340512543617, 5996.7601295948325, 5827.125931110325, 5837.6497410155525, 5930.762769489215, 5950.137230101633, 5960.52315814736, 5627.774889004435, 5696.572757043238, 5704.344000439961, 5854.619449735754, 5893.764249430018, 5763.0, 5783.652980821164, 5836.854078648025, 5846.444587764198, 5854.7072646367515, 6178.474829639486, 6313.842153946142, 6343.492520598341, 6352.936470635281, 6402.807915762534, 6670.533062685614, 6712.865742685168, 6713.362230588086, 6720.126383570154, 6747.628880411576, 6785.321467853225, 6799.0, 6819.829504262383, 6827.5562088464585, 6985.860282794367, 6296.6851657417865, 6355.142055822497, 6424.646644434554, 6461.386168313956, 6464.515161362917, 6940.09778728762, 6997.475189360818, 7007.824804379931, 7112.0, 7136.107986501687, 6584.308647591934, 6661.833454163588, 6706.128203333536, 6728.461723062143, 6835.077264569319, 6845.691943862538, 6877.690503927335, 6877.690503927335, 6880.5527640704195, 6886.48351373649, 6801.0, 6825.965870170618, 6866.485013623998, 6883.036374907517, 6906.585604863624, 6793.932060679429, 6833.69248383814, 6918.584884906922, 6922.06552115468, 6923.342282483156, 5191.325127733446, 5273.604479664041, 5289.2859463972645, 5293.788248470018, 5384.9461505385225, 4675.392199014106, 4693.530646935279, 4696.530346965276, 4744.217204161275, 4747.928781068286, 5776.364599894008, 5834.299884013945, 5851.444112809277, 5865.912011319833, 5908.852278693067, 6850.691718872663, 6855.451563874878, 6898.620575868375, 6929.203141638675, 6929.965350173217, 5803.593748437654, 5812.38969908162, 5851.444112809277, 5891.0, 5893.497158224711, 5709.885802283931, 5798.797042103591, 5799.072148456226, 5809.24479817621, 5815.069588865761, 6542.54202205841, 6565.540412171198, 6648.634325112165, 6649.900251496231, 6667.86664266712, 6923.826904327432, 6936.514443988873, 6943.618101004493, 6943.652817359213, 6980.7905762827195, 6624.668766561749, 6628.5691430056895, 6639.468842492685, 6678.0, 6737.090492783412, 6545.476361891133, 6551.639659818756, 6556.246031706224, 6726.529142959946, 6850.760223392157, 6743.0, 6778.525503214728, 6784.932150678627, 6871.278515755774, 6879.0025446311, 6669.09967154428, 6733.663316834237, 6750.459963202834, 6751.93248067513, 6751.932480675327, 6634.237062737847, 6659.700313485808, 6725.630090345078, 6735.932640673726, 6741.157355330583, 6395.0, 6522.804315870531, 6583.111279977142, 6640.9335906642245, 6675.833104172434, 6486.4486518646445, 6567.310432404529, 6574.901376479453, 6579.4736421085245, 6683.832904177434, 6441.71012304438, 6463.353664633499, 6477.578957367756, 6482.675866206577, 6522.771702990372], "value": 6206.63190089967, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [816.9877451838223, 837.5142417397902, 838.2413915406555, 844.8225872566761, 847.6397531049305, 36183.63816361836, 36669.26661466772, 39152.0, 39235.41146882795, 39255.41116883249, 36939.26121477569, 37275.254494910085, 37395.25209495809, 37553.43669844954, 37582.24835503288, 6946.131733533321, 7071.752488662898, 7088.929110708904, 7099.6450177490915, 7723.806904827368, 7660.119086305062, 7714.807129821743, 7727.845443091135, 7774.922250777505, 7787.104482984472, 7735.30382265596, 7804.2585954334245, 7820.921790782077, 7822.608869556528, 7824.0, 7732.536047837148, 7757.069151701776, 7770.456068075208, 7782.105057918356, 7811.140774514799, 7575.734849280304, 7609.6956121755065, 7684.346830519413, 7731.072271327476, 7745.806354841174, 36339.273214535824, 36574.08564785875, 36705.26589468196, 36730.26539469222, 36761.0, 3486.581610206766, 3518.894433167009, 3524.7004004659634, 3528.59421166564, 3531.2584357285, 30859.0, 30872.53691194634, 31000.0, 31284.0, 31373.0, 5035.0685123252315, 5052.393712754456, 5103.8213662522, 5154.098032844275, 5213.244079608474, 189181.0, 189584.0, 189746.0, 189944.0, 190912.9999999986, 41998.1600367997, 48895.0, 49062.75468622634, 49078.26382604264, 49084.26373604399, 45143.0, 48414.88473479723, 51342.0, 51749.0, 51749.2237616436, 16168.919155404148, 16360.34558617642, 16496.17519124039, 16506.504804855875, 16508.917455412648, 13363.599092027254, 13379.732405351839, 13380.866191338157, 13382.464701411835, 13383.263920484467, 14295.0, 14322.57032289033, 14384.35270412834, 14436.566902992927, 14609.561713148623, 5747.827565172968, 5795.594308398372, 5830.5627077968475, 5867.911981320283, 5969.52243820502, 962.6438217859297, 969.2246203037541, 984.601236499219, 1007.2848277722752, 1009.2834087797746, 22115.225967091075, 22370.32889013332, 22384.888075559516, 22546.436339091655, 22563.323100307018, 1064.1114669251263, 1097.160672085855, 1104.679642903549, 1109.7059279290995, 1120.9439528023565, 11928.522859085713, 12044.277343359427, 12046.337451440255, 12081.697957551132, 12136.696582585506, 221785.0, 223061.0, 224014.0, 224174.0, 224654.0, 51496.0, 51964.0, 52094.73952630288, 52148.0, 52696.473035269155, 1447.2835946206485, 1450.187894778927, 1474.3512854344053, 1475.4688312207597, 1479.0460153201125, 21682.783172168078, 22068.668969965143, 22078.88960555251, 22559.88720056389, 22835.657465138367, 36480.63519364772, 36500.81749591235, 36538.63461365352, 36643.81678091593, 36661.450078248316, 200597.0, 201716.0, 202282.0, 202309.0, 202317.0, 32999.17502062468, 33331.0, 34986.82506587451, 35110.64889351176, 35313.0, 44264.0, 45841.77079114583, 46092.76953615344, 46412.0, 47001.529984699715, 32991.175220619676, 33220.66779332176, 33349.33301334008, 33521.0, 33838.49242261319, 32019.0, 32166.19584510406, 32427.35145297128, 32678.019659410256, 33111.50332745059, 36773.0, 37402.25195496021, 37465.625343746215, 37516.0, 37521.24957500889, 32136.35727285488, 32368.0, 32371.676283236866, 32402.35195296128, 33514.329713406085, 23354.299371018897, 23584.646230306214, 23829.404264893517, 23982.160624378062, 24566.385840353418], "value": 17035.98930815487, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [538.376793966192, 548.5173047718008, 568.981523073698, 569.1746966897999, 583.938686437924, 22057.779422205793, 22809.0, 22835.42911427213, 22840.65739013912, 22864.085436582536, 24527.0, 24575.877120614372, 24737.257882263562, 24850.130245441414, 25291.62062569063, 6360.554761166732, 6520.510961677869, 6546.378094081078, 6559.508036897228, 6584.637844918528, 6506.316836732146, 6514.283428822826, 6546.607203567777, 6554.803355899307, 6569.441597464222, 6177.227846519185, 6399.072134540489, 6405.903911441332, 6420.646864422455, 6428.389303016206, 6414.454771344442, 6449.806785744652, 6454.774082907099, 6477.481801455872, 6491.448226900719, 5839.474447299721, 6007.309159446676, 6015.699215039274, 6016.75932962681, 6032.065029920369, 29556.556651650244, 30005.549916751264, 30012.099637010924, 30053.849730751208, 30143.0, 2010.2461576908659, 2033.4814622271226, 2036.3585470576868, 2040.4490787487443, 2049.3442098528476, 18526.444206673823, 18691.71962420565, 19055.142518586697, 19179.712304315446, 19282.32511862092, 2313.935589628773, 2379.8572085674914, 2385.8449200801892, 2392.736798952114, 2397.113068164773, 115672.0, 122126.0, 123087.38456307662, 127775.36112319378, 132259.33870330683, 14371.78442323366, 14425.278736063156, 14426.927865360605, 14436.278186090654, 14635.560933172022, 22259.554808903733, 22370.776292237195, 22468.438289042577, 22524.436889077577, 22536.0, 9232.723018309462, 9437.716868493957, 9735.41587504752, 9782.021797820162, 9822.26333025026, 8948.284137269004, 8997.460152390877, 9000.749812546863, 9091.090890910857, 9101.135392137736, 8758.956205219061, 8947.284217262604, 8949.149830766068, 9006.504642244608, 9015.413998090104, 2521.1932181701677, 2633.289011966758, 2665.7067722550505, 2676.2774051006118, 2801.6217810595717, 927.3879239701764, 943.7876477792441, 964.8408012677829, 969.5782334684294, 972.3388096094582, 19642.50893727668, 19710.70433943492, 19716.802831971498, 19832.40502784919, 20098.296559620343, 1013.4274135113637, 1014.2899970021015, 1015.1827778638119, 1038.1435315864442, 1046.7173863056933, 10949.288296260718, 11137.944310278397, 11167.162462815322, 11267.380294083903, 11337.943310283395, 452552.0, 459535.0, 464692.3826904287, 470492.0, 476566.0, 107770.0, 110964.0, 110966.0, 111160.0, 111457.0, 1107.8227483602745, 1132.4677401621225, 1134.319408354997, 1139.9031082358092, 1144.1476100305326, 17104.828951710322, 17218.65562688764, 17527.561810954827, 17618.295268189642, 17924.193411296263, 54529.0, 54599.72700136633, 55946.44053559412, 56356.43643563512, 56972.430275696715, 66093.0, 66877.0, 67003.3299666997, 67477.32522674906, 67612.66193669, 13054.08621396493, 13163.802542961672, 13182.736345273233, 13197.736045278849, 13389.0, 19771.802281977572, 20251.898740506793, 20448.0, 20545.48636284105, 20576.074076666286, 15992.120433376276, 16007.599810004378, 16031.198440077716, 16202.189890505191, 16261.105639189958, 13906.0, 14000.929995349958, 14644.487442939446, 14677.55967320982, 14808.481703140338, 16567.66864662724, 16623.50129496117, 16863.74704379411, 16933.49199524016, 17029.744553831453, 13273.800892986419, 13298.268595227355, 13341.866581334063, 13382.13016153947, 13470.730585388434, 15909.68180636404, 16056.357745690042, 16189.0, 16221.270042847864, 16303.510894673178], "value": 11471.145298883295, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1257.8679238679938, 1355.7695191817384, 1355.9525416610418, 1357.640225340285, 1357.816694746209, 15385.0, 15836.366545338202, 15872.206389680527, 15875.841241587581, 15903.28435220415, 15319.846801531981, 15453.22733863308, 15487.612809679764, 15628.140452275149, 15650.060996340228, 2696.865156742165, 2713.2402927180374, 2755.2423083652056, 2756.944861102787, 2760.544510155822, 2670.799690023256, 2713.6879258885183, 2722.659667541557, 2735.5076086304457, 2737.8220415673018, 2608.9086881959133, 2747.3955729739523, 2790.4419116176746, 2801.215659615311, 2801.453716525278, 2667.439837634099, 2703.9324016899536, 2720.2519307190482, 2724.101046654599, 2736.0560606590693, 2707.986460067707, 2712.593111033341, 2719.02115238514, 2731.3717844895787, 2736.4390299988454, 14391.784123238054, 14576.344064517016, 14653.70692586153, 14673.633159170999, 14681.412743490248, 7879.566623835687, 8112.351011919032, 8155.673773049071, 8166.265036146775, 8181.8363632727605, 5229.477052294778, 5237.687451456822, 5300.496452836975, 5304.92042619361, 5334.893302133974, 1907.551725344543, 1920.5486710622993, 1952.9218831246733, 1965.7837637859825, 1967.2426115945464, 26428.999999999807, 26445.33886652849, 26497.337566560796, 26787.33031674204, 26798.196054118212, 6691.230508491586, 6795.694193761293, 6854.897176542356, 7037.507374483839, 7115.75094871677, 17139.571510712332, 17147.228374723167, 17260.309587616604, 17310.221040053228, 17326.826731732774, 7603.125640551297, 7796.181400952932, 7848.13670496245, 7864.2922137007945, 7875.645595948196, 7152.785416437515, 7233.240509746506, 7341.302576255247, 7399.112106547249, 7408.740694075682, 4990.975045124751, 5253.579713622901, 5280.339957505312, 5302.522772950453, 5316.388615309212, 1343.7446885091802, 1369.6233535777542, 1581.0908727481744, 1588.094785971985, 1600.591849078501, 865.5499140446932, 883.7878909061778, 895.8477058900003, 904.9411788233743, 913.1279627955287, 8587.95706021466, 8672.306215502746, 8684.696035638723, 8697.869531957025, 8705.303575713928, 725.4740313272835, 797.8125140591957, 979.3389462113046, 1016.3190662256306, 1016.6289304403839, 5318.44156363584, 5438.374586922476, 5445.455454454523, 5532.6680399176175, 5577.776888924398, 21176.15295388198, 21828.0, 21960.45098872509, 22120.0, 22141.889290553765, 20924.895375523025, 21470.677939830915, 21544.892275538834, 21712.891435542722, 21943.0, 10996.95528924751, 11244.831327530093, 11324.207305488702, 11349.829752553553, 11437.142214333959, 8138.470999385021, 8465.0, 8474.66101355939, 8492.915070849212, 8600.526971016654, 18377.632447351247, 18427.53931151732, 18536.16587253604, 18609.0, 18748.437546873614, 18770.906145469184, 18820.52948676294, 18907.243710251434, 19143.52141196481, 19485.415437536896, 10916.0, 11125.721856953642, 11134.220604557604, 11148.052415544445, 11171.608993685182, 14003.719925601634, 14082.78875816843, 14170.07894486855, 14234.35945382481, 14271.714565708835, 10468.581256749645, 10894.291871028357, 11150.776984460428, 11216.270942389045, 11283.0, 10905.509252083517, 10914.836277456003, 11007.284526505751, 11239.943800280946, 11308.660740177807, 9384.577694003923, 9460.290478214023, 9561.665341713007, 9570.760730981781, 9574.377665451724, 7291.197968223385, 7298.63506824646, 7333.230010848997, 7369.889451658337, 7384.784564630697, 7145.714171433085, 7175.964120179366, 7205.0, 7287.417006639562, 7310.0], "value": 6680.517708238174, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [501.94227663818674, 505.0830168311487, 520.0950346397269, 527.4672580693504, 529.9427641854501, 6213.782517611885, 6253.24961004679, 6266.811995640127, 6270.27891792444, 6328.5886417382835, 6113.174721412608, 6127.63234205948, 6140.478059364959, 6214.689265536716, 6253.59351642143, 5042.974785126069, 5061.822836200734, 5081.872953176182, 5099.923501147486, 5111.539961403473, 5078.238264260372, 5082.745862706868, 5088.26998820401, 5091.643584949055, 5130.358705161855, 4706.505816889228, 4813.181759100961, 4928.708150709919, 5028.7988480460735, 5048.646594738351, 4844.539768721966, 4867.464578896354, 4905.8773530661665, 4909.288153217799, 4946.802127914879, 4331.155424692186, 4340.8480703175555, 4380.89047773805, 4398.846040388571, 4411.669124815651, 5897.380775018647, 6051.608130130051, 6064.9090263646085, 6124.142620033199, 6140.570160088796, 5479.369872464637, 5554.416786237427, 5560.302398904723, 5606.467385598362, 5637.492625663669, 6484.740610375579, 6532.0, 6535.379138981789, 6562.212534495891, 6565.409113179789, 4482.663800214997, 4767.952320476786, 4789.976050119727, 4846.321514987904, 4852.374454557692, 6670.4663626910215, 6688.966555167193, 6733.595984240961, 6758.932410675929, 6837.213720422165, 4854.199057155601, 4919.508049195123, 4926.679765815211, 4942.406911170682, 4953.405591328991, 4932.53140951609, 4989.351384320088, 4993.400791904995, 5007.7245751484015, 5036.269740887551, 5530.723463826793, 5541.750621221974, 5606.747696353592, 5617.578681598897, 5663.405342439067, 6571.094382628984, 6590.670466476657, 6634.601923884582, 6657.733690652417, 6676.332366763285, 1711.5293294344156, 1719.6818588561157, 1737.3832289537286, 1750.6761249168944, 1750.8706884059966, 1138.9430528473542, 1183.8694047184918, 1192.4872304908972, 1192.5885569478517, 1201.4773573495556, 5614.9157762633595, 5625.324961004706, 5625.887482250414, 5685.289338832646, 5694.5159661428415, 6626.668666566652, 6629.326324940905, 6730.9326906731285, 6747.190337159573, 6771.408718951043, 5499.1476321170285, 5592.0, 5593.552515798727, 5638.573719630071, 5707.6290041147195, 3462.79223246606, 3483.1814523586936, 3486.3550243205086, 3487.773294735834, 3510.8069056202153, 6336.904946425807, 6350.904736428957, 6373.649449280241, 6385.744570217233, 6443.29123796382, 6729.730810767613, 6776.390124888685, 6818.0, 6882.139732533434, 6960.37356637905, 6260.937390626126, 6349.174607301022, 6372.203474565679, 6440.710168042449, 6444.194475690539, 6019.428154325332, 6598.736050558116, 6610.3720146586, 6632.933670663231, 6669.7665581704405, 6428.710708018057, 6457.321981192095, 6495.285518593046, 6617.702203400762, 6639.605682806665, 6231.345708700521, 6326.279699729161, 6375.171227740364, 6386.9361306386345, 6387.457066149429, 4757.738324392191, 4811.951880481291, 4863.197572400514, 4878.414590249051, 4883.194272944925, 6428.55000149985, 6510.609363438209, 6526.487302388519, 6604.735810567524, 6630.701618427279, 6351.68241587928, 6356.5232607555545, 6470.48236141117, 6472.320406357265, 6537.803865884031, 6278.277998030285, 6366.554341196072, 6407.647579382992, 6420.325865784025, 6547.247066587213, 6350.491960643045, 6368.42684158428, 6426.164598602152, 6466.0, 6499.28507864144, 6387.712552935036, 6566.573172743756, 6566.9343306568235, 6569.441597464269, 6614.966925165343, 6274.435300822765, 6283.905741413791, 6302.432781049728, 6354.17395738551, 6386.584871983307], "value": 4991.750731903892, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [886.2998231397197, 888.1873086126191, 914.5610107148566, 914.7164379042493, 918.5958178401503, 18571.814281857176, 19251.807481925178, 19330.93679847608, 19381.32165374212, 19404.417867463962, 16417.097059661715, 16674.0, 16718.498445046636, 16755.413560525383, 16825.495235142935, 947.5215016416699, 960.0591420408005, 963.6579014449875, 965.3869792681628, 970.5244430229172, 951.700214432455, 952.7046615549202, 952.9475878826662, 953.0755167487526, 960.2414092866627, 966.2704657983213, 1013.5996281468815, 1027.5889644142364, 1031.9587216511368, 1034.0745033195285, 1009.9495025248782, 1013.15401639631, 1014.523174108168, 1014.7260239735267, 1016.3089099412397, 740.304114132719, 740.6111791309552, 746.0413368821094, 747.8093086263023, 752.1463139336837, 19040.52398690044, 19811.0, 20198.697019544717, 20373.592528149355, 20511.282105126247, 21287.787122128735, 21608.783912160834, 21669.45826354338, 21990.450238744, 22064.11743530273, 17044.48866534006, 17143.057131857742, 17255.82744172555, 17280.049597272144, 17547.0, 3710.053936246267, 3728.1611637381643, 3729.8881033569037, 3730.8694195703292, 3742.6257374262627, 99581.39349011978, 104861.47569262181, 135356.32321838426, 139040.0, 141298.29350853284, 17262.395816146374, 17719.114044297734, 17850.5537361567, 17858.28566857337, 17878.28486860537, 31831.681683183335, 32266.193345166557, 32451.02646920596, 32491.675083249338, 32520.83739581334, 8866.202041816268, 8997.19025287714, 9071.954640226888, 9082.0, 9114.908850911539, 4101.52832424269, 4105.34314509677, 4142.627163555295, 4147.91704165915, 4175.331946888544, 5401.3248343957, 5419.620626556103, 5459.3994660587305, 5525.613207075467, 5577.944220557823, 1092.3009274064605, 1093.327603523827, 1100.6422912553292, 1103.0954617213968, 1118.2172479264548, 682.2086379799508, 702.7399862051074, 704.8907419350008, 717.1573401253506, 719.8769918926491, 12259.632211033684, 12432.0, 12460.626181214579, 12622.558210462774, 12879.871201288055, 684.0730809752713, 688.2635579929528, 704.7709494414336, 710.8329542557495, 718.0485856240398, 5478.287822583039, 5491.395946445968, 5535.750891209906, 5547.195656629766, 5584.134459158837, 124805.0, 125804.37097814635, 126013.36993314975, 126695.0, 127552.0, 103065.0, 106255.46872265743, 107367.0, 110572.4471377638, 111092.4445377768, 96299.0, 96855.0, 97373.0, 97686.5115674417, 98067.01932980721, 10180.134688551556, 10188.235882309005, 10191.235657325878, 10318.329308594917, 10514.106300964198, 42199.57800422079, 43319.78340108405, 43403.565964339956, 44339.11321773611, 44503.332450012625, 55798.721006394706, 56064.71967640299, 56554.7172264136, 57643.423565765486, 57748.0, 15177.696446071237, 15299.694006120038, 15517.45688900883, 15609.141497217763, 15631.84368156304, 17312.826871731122, 17404.912975435043, 17563.0, 17596.472105836845, 17670.02814845196, 15986.0, 16107.758383624021, 16141.59646008859, 16233.10717910479, 16281.0, 15914.0, 16142.0, 16187.10970896612, 16228.269727862511, 16299.348026078824, 15671.294791734628, 15697.921510392374, 15698.0, 15863.762043569586, 15895.602609934844, 11587.8261826071, 11768.117391195523, 11872.168948173545, 11967.581134660246, 11969.64091077269, 12003.579874704343, 12008.699782505508, 12103.213291136048, 12136.211146275464, 12306.953908917842], "value": 8927.010906043512, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1462.9, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 111.0, 109.0, 111.0, 107.0, 111.0, 115.0, 111.0, 120.0, 111.0], "value": 111.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 126.0, 117.0, 119.0, 116.0, 132.0, 123.0, 125.0, 124.0, 120.0], "value": 122.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 168.0, 163.0, 164.0, 175.0, 172.0, 167.0, 164.0, 172.0, 164.0], "value": 167.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 75.0, 73.0, 75.0, 77.0, 73.0, 74.0, 75.0, 85.0, 77.0], "value": 77.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 85.0, 82.0, 91.0, 79.0, 85.0, 84.0, 80.0, 80.0, 79.0], "value": 82.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 120.0, 114.0, 113.0, 116.0, 118.0, 116.0, 117.0, 117.0, 115.0], "value": 116.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 150.0, 148.0, 148.0, 148.0, 156.0, 153.0, 147.0, 153.0, 150.0], "value": 150.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 94.0, 94.0, 102.0, 94.0, 97.0, 94.0, 94.0], "value": 95.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 57.0, 53.0, 54.0, 54.0, 57.0, 55.0, 54.0, 60.0, 54.0], "value": 55.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [40.0, 46.0, 48.0, 44.0, 47.0, 38.0, 39.0, 39.0, 39.0, 53.0], "value": 43.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 82.0, 82.0, 79.0, 85.0, 76.0, 80.0, 86.0, 83.0, 72.0], "value": 80.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 117.0, 129.0, 119.0, 115.0, 122.0, 112.0, 114.0, 127.0, 120.0], "value": 118.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [181.0, 176.0, 177.0, 176.0, 175.0, 178.0, 177.0, 170.0, 168.0, 174.0], "value": 175.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 67.0, 66.0, 66.0, 65.0, 68.0, 67.0, 68.0, 67.0, 66.0], "value": 67.5, "unit": "score"}]}]} 17:50:58 INFO - Return code: 0 17:50:58 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 17:50:58 INFO - # TBPL SUCCESS # 17:50:58 INFO - Running post-action listener: _package_coverage_data 17:50:58 INFO - Running post-action listener: _resource_record_post_action 17:50:58 INFO - [mozharness: 2017-09-14 00:50:58.524559Z] Finished run-tests step (success) 17:50:58 INFO - Running post-run listener: _resource_record_post_run 17:50:58 INFO - Total resource usage - Wall time: 1195s; CPU: 13.0%; Read bytes: 4096; Write bytes: 343273472; Read time: 12; Write time: 204204 17:50:58 INFO - TinderboxPrint: CPU usage
13.3% 17:50:58 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 17:50:58 INFO - TinderboxPrint: I/O write bytes / time
343,273,472 / 204,204 17:50:58 INFO - TinderboxPrint: CPU idle
8,258.1 (86.7%) 17:50:58 INFO - TinderboxPrint: CPU user
1,203.3 (12.6%) 17:50:58 INFO - TinderboxPrint: Swap in / out
0 / 0 17:50:58 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 13316096; Read time: 0; Write time: 2780 17:50:58 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 17:50:58 INFO - run-tests - Wall time: 1177s; CPU: 13.0%; Read bytes: 0; Write bytes: 329957376; Read time: 0; Write time: 201424 17:50:59 INFO - Running post-run listener: _upload_blobber_files 17:50:59 INFO - Blob upload gear active. 17:50:59 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:50:59 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 17:50:59 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'] 17:50:59 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 17:50:59 INFO - (blobuploader) - INFO - Open directory for files ... 17:50:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 17:50:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:50:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:51:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 17:51:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:51:00 INFO - (blobuploader) - INFO - Done attempting. 17:51:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 17:51:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:51:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:51:07 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 17:51:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:51:07 INFO - (blobuploader) - INFO - Done attempting. 17:51:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 17:51:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:51:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:51:09 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 17:51:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:51:09 INFO - (blobuploader) - INFO - Done attempting. 17:51:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 17:51:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:51:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:51:10 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 17:51:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:51:10 INFO - (blobuploader) - INFO - Done attempting. 17:51:10 INFO - (blobuploader) - INFO - Iteration through files over. 17:51:10 INFO - Return code: 0 17:51:10 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:51:10 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:51:10 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1e712647ce7cf245ccfd1f5abb58a3f678a7da155fd5422be5e6641798d7ac1f071d9cfbe99eba717178826746b226408fadfb13f7787ed60439bf8f54547cb8", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/be8771411f6744eaf2f997caca839aebff94af61c7e226d0e815a5ec096d2673d8075029e23b22ace592348cb1eb7e7986fe6a68d477974c57f1138bf5b80958", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/426cd4bf8e94d6f3c36a8e79f95ceec537024bb23026d3115f1717e871dbaacdedbc81dd1d90b89cf92c69969dc45b0c9ff39f732eb855f984ea7a046f2f7087", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/80c479d9ff6b74b430d2f6532c37f93c2615b5b7b0341800b1db3b43a85ad4abf8905b63df7819b9311bbb834c74a89fc2e1f6775f16bf425100bc86d0f78147"} 17:51:10 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:51:10 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:51:10 INFO - Contents: 17:51:10 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1e712647ce7cf245ccfd1f5abb58a3f678a7da155fd5422be5e6641798d7ac1f071d9cfbe99eba717178826746b226408fadfb13f7787ed60439bf8f54547cb8", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/be8771411f6744eaf2f997caca839aebff94af61c7e226d0e815a5ec096d2673d8075029e23b22ace592348cb1eb7e7986fe6a68d477974c57f1138bf5b80958", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/426cd4bf8e94d6f3c36a8e79f95ceec537024bb23026d3115f1717e871dbaacdedbc81dd1d90b89cf92c69969dc45b0c9ff39f732eb855f984ea7a046f2f7087", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/80c479d9ff6b74b430d2f6532c37f93c2615b5b7b0341800b1db3b43a85ad4abf8905b63df7819b9311bbb834c74a89fc2e1f6775f16bf425100bc86d0f78147"} 17:51:10 INFO - Running post-run listener: copy_logs_to_upload_dir 17:51:10 INFO - Copying logs to upload dir... 17:51:10 INFO - mkdir: /builds/slave/test/build/upload/logs 17:51:10 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1244.987669 ========= master_lag: -0.06 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 44 secs) (at 2017-09-13 17:51:10.091940) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-13 17:51:10.095766) ========= 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=ca6c2627acea03e666270f68000001e0-1505349022.485655-1085936112 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1e712647ce7cf245ccfd1f5abb58a3f678a7da155fd5422be5e6641798d7ac1f071d9cfbe99eba717178826746b226408fadfb13f7787ed60439bf8f54547cb8", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/be8771411f6744eaf2f997caca839aebff94af61c7e226d0e815a5ec096d2673d8075029e23b22ace592348cb1eb7e7986fe6a68d477974c57f1138bf5b80958", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/426cd4bf8e94d6f3c36a8e79f95ceec537024bb23026d3115f1717e871dbaacdedbc81dd1d90b89cf92c69969dc45b0c9ff39f732eb855f984ea7a046f2f7087", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/80c479d9ff6b74b430d2f6532c37f93c2615b5b7b0341800b1db3b43a85ad4abf8905b63df7819b9311bbb834c74a89fc2e1f6775f16bf425100bc86d0f78147"} build_url:https://queue.taskcluster.net/v1/task/GhKihdsTQkWhAO3N2_oAlA/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011311 build_url: 'https://queue.taskcluster.net/v1/task/GhKihdsTQkWhAO3N2_oAlA/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1e712647ce7cf245ccfd1f5abb58a3f678a7da155fd5422be5e6641798d7ac1f071d9cfbe99eba717178826746b226408fadfb13f7787ed60439bf8f54547cb8", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/be8771411f6744eaf2f997caca839aebff94af61c7e226d0e815a5ec096d2673d8075029e23b22ace592348cb1eb7e7986fe6a68d477974c57f1138bf5b80958", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/426cd4bf8e94d6f3c36a8e79f95ceec537024bb23026d3115f1717e871dbaacdedbc81dd1d90b89cf92c69969dc45b0c9ff39f732eb855f984ea7a046f2f7087", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/80c479d9ff6b74b430d2f6532c37f93c2615b5b7b0341800b1db3b43a85ad4abf8905b63df7819b9311bbb834c74a89fc2e1f6775f16bf425100bc86d0f78147"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-13 17:51:10.130775) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-13 17:51:10.131096) ========= 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=ca6c2627acea03e666270f68000001e0-1505349022.485655-1085936112 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004778 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-13 17:51:10.183080) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-13 17:51:10.186401) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-13 17:51:10.186699) ========= ========= Total master_lag: 0.08 =========