builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-018 starttime: 1506640862.92 results: success (0) revision: 307a7a34013060a6a1e87dfbb911f058d0781a2e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-28 16:21:02.923539) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-28 16:21:02.923923) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-28 16:21:02.924206) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-28 16:21:02.996211) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-28 16:21:02.996532) ========= 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=62c568580acbfdaac3eec7be000001dc-1506639858.562470-1115674979 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-28 16:21:03-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 36.2M=0s 2017-09-28 16:21:03 (36.2 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.155822 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-28 16:21:03.174524) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-28 16:21:03.174858) ========= 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=62c568580acbfdaac3eec7be000001dc-1506639858.562470-1115674979 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020439 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-28 16:21:03.224016) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-28 16:21:03.224320) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 307a7a34013060a6a1e87dfbb911f058d0781a2e --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 307a7a34013060a6a1e87dfbb911f058d0781a2e --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=62c568580acbfdaac3eec7be000001dc-1506639858.562470-1115674979 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-28 16:21:03,647 truncating revision to first 12 chars 2017-09-28 16:21:03,647 Setting DEBUG logging. 2017-09-28 16:21:03,647 attempt 1/10 2017-09-28 16:21:03,647 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/307a7a340130?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-28 16:21:04,052 unpacking tar archive at: mozilla-central-307a7a340130/testing/mozharness/ program finished with exit code 0 elapsedTime=0.718571 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-28 16:21:03.966182) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-28 16:21:03.966473) ========= script_repo_revision: 307a7a34013060a6a1e87dfbb911f058d0781a2e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-28 16:21:03.969854) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-28 16:21:03.970110) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-28 16:21:03.986086) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 37 secs) (at 2017-09-28 16:21:03.986365) ========= /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=62c568580acbfdaac3eec7be000001dc-1506639858.562470-1115674979 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 16:21:04 INFO - MultiFileLogger online at 20170928 16:21:04 in /builds/slave/test 16:21:04 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 16:21:04 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:21:04 INFO - {'append_to_log': False, 16:21:04 INFO - 'base_work_dir': '/builds/slave/test', 16:21:04 INFO - 'blob_upload_branch': 'Firefox', 16:21:04 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:21:04 INFO - 'branch': 'Firefox', 16:21:04 INFO - 'buildbot_json_path': 'buildprops.json', 16:21:04 INFO - 'code_coverage': False, 16:21:04 INFO - 'config_files': ('talos/linux_config.py',), 16:21:04 INFO - 'default_actions': ('clobber', 16:21:04 INFO - 'read-buildbot-config', 16:21:04 INFO - 'download-and-extract', 16:21:04 INFO - 'populate-webroot', 16:21:04 INFO - 'create-virtualenv', 16:21:04 INFO - 'install', 16:21:04 INFO - 'setup-mitmproxy', 16:21:04 INFO - 'run-tests'), 16:21:04 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:21:04 INFO - 'disable_ccov_upload': False, 16:21:04 INFO - 'disable_stylo': False, 16:21:04 INFO - 'download_minidump_stackwalk': True, 16:21:04 INFO - 'download_symbols': 'ondemand', 16:21:04 INFO - 'enable_stylo': False, 16:21:04 INFO - 'enable_webrender': False, 16:21:04 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 16:21:04 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:21:04 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:21:04 INFO - 'gecko_profile': False, 16:21:04 INFO - 'gecko_profile_interval': 0, 16:21:04 INFO - 'installer_path': 'installer.exe', 16:21:04 INFO - 'log_level': 'info', 16:21:04 INFO - 'log_name': 'talos', 16:21:04 INFO - 'log_to_console': True, 16:21:04 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 16:21:04 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 16:21:04 INFO - 'opt_config_files': (), 16:21:04 INFO - 'pip_index': False, 16:21:04 INFO - 'suite': 'dromaeojs-e10s', 16:21:04 INFO - 'system_bits': '32', 16:21:04 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:21:04 INFO - 'title': 'talos-linux64-ix-018', 16:21:04 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:21:04 INFO - 'use_talos_json': True, 16:21:04 INFO - 'verify': 'False', 16:21:04 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:21:04 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:21:04 INFO - 'work_dir': 'build'} 16:21:04 INFO - [mozharness: 2017-09-28 23:21:04.543299Z] Running clobber step. 16:21:04 INFO - Running pre-action listener: _resource_record_pre_action 16:21:04 INFO - Running main action method: clobber 16:21:04 INFO - rmtree: /builds/slave/test/build 16:21:04 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:21:06 INFO - Running post-action listener: _resource_record_post_action 16:21:06 INFO - [mozharness: 2017-09-28 23:21:06.049931Z] Finished clobber step (success) 16:21:06 INFO - [mozharness: 2017-09-28 23:21:06.050028Z] Running read-buildbot-config step. 16:21:06 INFO - Running pre-action listener: _resource_record_pre_action 16:21:06 INFO - Running main action method: read_buildbot_config 16:21:06 INFO - Using buildbot properties: 16:21:06 INFO - { 16:21:06 INFO - "project": "", 16:21:06 INFO - "product": "firefox", 16:21:06 INFO - "who": "ryanvm@gmail.com", 16:21:06 INFO - "installer_path": "public/build/target.tar.bz2", 16:21:06 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 16:21:06 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 16:21:06 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs-e10s", 16:21:06 INFO - "stage_platform": "linux64-pgo", 16:21:06 INFO - "basedir": "/builds/slave/test", 16:21:06 INFO - "buildnumber": 215, 16:21:06 INFO - "platform": "ubuntu64_hw", 16:21:06 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 16:21:06 INFO - "slavebuilddir": "test", 16:21:06 INFO - "taskId": "cui9pLH2TgaH1e2pfMJvvQ", 16:21:06 INFO - "branch": "mozilla-central", 16:21:06 INFO - "script_repo_revision": "production", 16:21:06 INFO - "revision": "307a7a34013060a6a1e87dfbb911f058d0781a2e", 16:21:06 INFO - "slavename": "talos-linux64-ix-018", 16:21:06 INFO - "repo_path": "mozilla-central" 16:21:06 INFO - } 16:21:06 INFO - Finding installer, test and symbols from parent task. 16:21:06 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cui9pLH2TgaH1e2pfMJvvQ'}, attempt #1 16:21:06 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cui9pLH2TgaH1e2pfMJvvQ'}, attempt #1 16:21:07 INFO - Task dependencies: cmYlyjEDRk2IOAJ-a13Uqg 16:21:07 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cmYlyjEDRk2IOAJ-a13Uqg'}, attempt #1 16:21:07 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/cmYlyjEDRk2IOAJ-a13Uqg/artifacts/public/build/target.tar.bz2 16:21:07 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/cmYlyjEDRk2IOAJ-a13Uqg/artifacts/public/build/target.test_packages.json 16:21:07 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/cmYlyjEDRk2IOAJ-a13Uqg/artifacts/public/build/target.crashreporter-symbols.zip 16:21:07 INFO - Running post-action listener: _resource_record_post_action 16:21:07 INFO - [mozharness: 2017-09-28 23:21:07.695376Z] Finished read-buildbot-config step (success) 16:21:07 INFO - [mozharness: 2017-09-28 23:21:07.695595Z] Running download-and-extract step. 16:21:07 INFO - Running pre-action listener: _resource_record_pre_action 16:21:07 INFO - Running main action method: download_and_extract 16:21:07 INFO - mkdir: /builds/slave/test/build/tests 16:21:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:21:07 INFO - trying https://queue.taskcluster.net/v1/task/cmYlyjEDRk2IOAJ-a13Uqg/artifacts/public/build/target.test_packages.json 16:21:07 INFO - Downloading https://queue.taskcluster.net/v1/task/cmYlyjEDRk2IOAJ-a13Uqg/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 16:21:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cmYlyjEDRk2IOAJ-a13Uqg/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 16:21:08 INFO - Downloaded 989 bytes. 16:21:08 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 16:21:08 INFO - Using the following test package requirements: 16:21:08 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 16:21:08 INFO - u'common': [u'target.common.tests.zip'], 16:21:08 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 16:21:08 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 16:21:08 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 16:21:08 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 16:21:08 INFO - u'mozbase': [u'target.common.tests.zip'], 16:21:08 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 16:21:08 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 16:21:08 INFO - u'web-platform': [u'target.common.tests.zip', 16:21:08 INFO - u'target.web-platform.tests.tar.gz'], 16:21:08 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 16:21:08 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 16:21:08 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/cmYlyjEDRk2IOAJ-a13Uqg/artifacts/public/build/target.common.tests.zip 16:21:08 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cmYlyjEDRk2IOAJ-a13Uqg/artifacts/public/build/target.common.tests.zip'}, attempt #1 16:21:08 INFO - Fetch https://queue.taskcluster.net/v1/task/cmYlyjEDRk2IOAJ-a13Uqg/artifacts/public/build/target.common.tests.zip into memory 16:21:09 INFO - Content-Length response header: 55830420 16:21:09 INFO - Bytes received: 55830420 16:21:20 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/cmYlyjEDRk2IOAJ-a13Uqg/artifacts/public/build/target.talos.tests.zip 16:21:20 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cmYlyjEDRk2IOAJ-a13Uqg/artifacts/public/build/target.talos.tests.zip'}, attempt #1 16:21:20 INFO - Fetch https://queue.taskcluster.net/v1/task/cmYlyjEDRk2IOAJ-a13Uqg/artifacts/public/build/target.talos.tests.zip into memory 16:21:21 INFO - Content-Length response header: 14521162 16:21:21 INFO - Bytes received: 14521162 16:21: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')]} 16:21:21 INFO - trying https://queue.taskcluster.net/v1/task/cmYlyjEDRk2IOAJ-a13Uqg/artifacts/public/build/target.tar.bz2 16:21:21 INFO - Downloading https://queue.taskcluster.net/v1/task/cmYlyjEDRk2IOAJ-a13Uqg/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 16:21:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cmYlyjEDRk2IOAJ-a13Uqg/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 16:21:24 INFO - Downloaded 62432193 bytes. 16:21:24 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/cmYlyjEDRk2IOAJ-a13Uqg/artifacts/public/build/target.tar.bz2 16:21:24 INFO - mkdir: /builds/slave/test/properties 16:21:24 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:21:24 INFO - Writing to file /builds/slave/test/properties/build_url 16:21:24 INFO - Contents: 16:21:24 INFO - build_url:https://queue.taskcluster.net/v1/task/cmYlyjEDRk2IOAJ-a13Uqg/artifacts/public/build/target.tar.bz2 16:21:24 INFO - Running post-action listener: _resource_record_post_action 16:21:24 INFO - Running post-action listener: find_tests_for_verification 16:21:24 INFO - Running post-action listener: set_extra_try_arguments 16:21:24 INFO - [mozharness: 2017-09-28 23:21:24.796261Z] Finished download-and-extract step (success) 16:21:24 INFO - [mozharness: 2017-09-28 23:21:24.796360Z] Running populate-webroot step. 16:21:24 INFO - Running pre-action listener: _resource_record_pre_action 16:21:24 INFO - Running main action method: populate_webroot 16:21:24 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:21:24 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 16:21:24 INFO - 'tests': ['tresize', 16:21:24 INFO - 'tcanvasmark']}, 16:21:24 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:21:24 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 16:21:24 INFO - 'tests': ['dromaeo_css', 16:21:24 INFO - 'kraken']}, 16:21:24 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 16:21:24 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:21:24 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 16:21:24 INFO - 'talos_options': ['--disable-stylo'], 16:21:24 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:21:24 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 16:21:24 INFO - 'tests': ['damp', 'tps']}, 16:21:24 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 16:21:24 INFO - 'talos_options': ['--disable-stylo'], 16:21:24 INFO - 'tests': ['damp', 'tps']}, 16:21:24 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:21:24 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 16:21:24 INFO - 'tests': ['dromaeo_dom']}, 16:21:24 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 16:21:24 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 16:21:24 INFO - 'tests': ['basic_compositor_video', 16:21:24 INFO - 'glvideo']}, 16:21:24 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 16:21:24 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 16:21:24 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 16:21:24 INFO - 'talos_options': ['--disable-stylo'], 16:21:24 INFO - 'tests': ['ts_paint_webext', 16:21:24 INFO - 'tp5o_webext']}, 16:21:24 INFO - 'other-e10s': {'tests': ['a11yr', 16:21:24 INFO - 'ts_paint', 16:21:24 INFO - 'tpaint', 16:21:24 INFO - 'sessionrestore', 16:21:24 INFO - 'sessionrestore_many_windows', 16:21:24 INFO - 'sessionrestore_no_auto_restore', 16:21:24 INFO - 'tabpaint', 16:21:24 INFO - 'cpstartup']}, 16:21:24 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 16:21:24 INFO - 'tests': ['a11yr', 16:21:24 INFO - 'ts_paint', 16:21:24 INFO - 'tpaint', 16:21:24 INFO - 'sessionrestore', 16:21:24 INFO - 'sessionrestore_many_windows', 16:21:24 INFO - 'sessionrestore_no_auto_restore', 16:21:24 INFO - 'tabpaint', 16:21:24 INFO - 'cpstartup']}, 16:21:24 INFO - 'perf-reftest-e10s': {'tests': ['perf_reftest']}, 16:21:24 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']}, 16:21:24 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 16:21:24 INFO - 'tests': ['perf_reftest_singletons']}, 16:21:24 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 16:21:24 INFO - 'tests': ['perf_reftest']}, 16:21:24 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:21:24 INFO - 'tsvgr_opacity', 16:21:24 INFO - 'tart', 16:21:24 INFO - 'tscrollx', 16:21:24 INFO - 'tsvg_static']}, 16:21:24 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 16:21:24 INFO - 'tests': ['tsvgx', 16:21:24 INFO - 'tsvgr_opacity', 16:21:24 INFO - 'tart', 16:21:24 INFO - 'tscrollx', 16:21:24 INFO - 'tsvg_static']}, 16:21:24 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 16:21:24 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 16:21:24 INFO - 'talos_options': ['--disable-stylo'], 16:21:24 INFO - 'tests': ['tp5o']}, 16:21:24 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 16:21:24 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 16:21:24 INFO - 'talos_options': ['--mitmproxy', 16:21:24 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 16:21:24 INFO - '--firstNonBlankPaint'], 16:21:24 INFO - 'tests': ['tp6_google', 16:21:24 INFO - 'tp6_youtube', 16:21:24 INFO - 'tp6_amazon', 16:21:24 INFO - 'tp6_facebook']}, 16:21:24 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 16:21:24 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 16:21:24 INFO - 'talos_options': ['--disable-stylo', 16:21:24 INFO - '--mitmproxy', 16:21:24 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 16:21:24 INFO - '--firstNonBlankPaint'], 16:21:24 INFO - 'tests': ['tp6_google', 16:21:24 INFO - 'tp6_youtube', 16:21:24 INFO - 'tp6_amazon', 16:21:24 INFO - 'tp6_facebook']}, 16:21:24 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 16:21:24 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 16:21:24 INFO - 'talos_options': ['--stylo-threads=1', 16:21:24 INFO - '--mitmproxy', 16:21:24 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 16:21:24 INFO - '--firstNonBlankPaint'], 16:21:24 INFO - 'tests': ['tp6_google', 16:21:24 INFO - 'tp6_youtube', 16:21:24 INFO - 'tp6_amazon', 16:21:24 INFO - 'tp6_facebook']}, 16:21:24 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 16:21:24 INFO - 'talos_options': ['--xperf_path', 16:21:24 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:21:24 INFO - 'tests': ['tp5n']}, 16:21:24 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 16:21:24 INFO - 'talos_options': ['--disable-stylo', 16:21:24 INFO - '--xperf_path', 16:21:24 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:21:24 INFO - 'tests': ['tp5n']}}} 16:21:24 INFO - Running post-action listener: _resource_record_post_action 16:21:24 INFO - [mozharness: 2017-09-28 23:21:24.814590Z] Finished populate-webroot step (success) 16:21:24 INFO - [mozharness: 2017-09-28 23:21:24.814679Z] Running create-virtualenv step. 16:21:24 INFO - Running pre-action listener: _resource_record_pre_action 16:21:24 INFO - Running main action method: create_virtualenv 16:21:24 INFO - Creating virtualenv /builds/slave/test/build/venv 16:21:24 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 16:21:24 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 16:21:24 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 16:21:24 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:21:24 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:21:25 INFO - Installing distribute.............................................................................................................................................................................................done. 16:21:25 INFO - Installing pip...............done. 16:21:25 INFO - Return code: 0 16:21:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 16:21:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 16:21:25 INFO - Reading from file tmpfile_stdout 16:21:25 INFO - Output received: 16:21:25 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 16:21:25 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:21:25 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')]} 16:21:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:21:25 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')]} 16:21:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:21:25 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 0x242a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25f2b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27979c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2794d60>, '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 0x2795a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2795ef0>, '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': '62c568580acbfdaac3eec7be000001dc-1506639858.562470-1115674979', '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 16:21:25 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 16:21:25 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 16:21:25 INFO - Using env: {'DISPLAY': ':0', 16:21:25 INFO - 'HOME': '/home/cltbld', 16:21:25 INFO - 'LANG': 'en_US.UTF-8', 16:21:25 INFO - 'LANGUAGE': 'en_US:en', 16:21:25 INFO - 'LOGNAME': 'cltbld', 16:21:25 INFO - 'MAIL': '/var/mail/cltbld', 16:21:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:21:25 INFO - 'MOZ_NO_REMOTE': '1', 16:21:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:21:25 INFO - 'NO_EM_RESTART': '1', 16:21:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:21:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:21:25 INFO - 'PWD': '/builds/slave/test', 16:21:25 INFO - 'SHELL': '/bin/bash', 16:21:25 INFO - 'SHLVL': '1', 16:21:25 INFO - 'TERM': 'linux', 16:21:25 INFO - 'TMOUT': '86400', 16:21:25 INFO - 'USER': 'cltbld', 16:21:25 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1506639858.562470-1115674979', 16:21:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:21:25 INFO - '_': '/tools/buildbot/bin/python'} 16:21:27 INFO - Ignoring indexes: http://pypi.python.org/simple/ 16:21:27 INFO - Downloading/unpacking pip>=1.5 16:21:27 INFO - Running setup.py egg_info for package pip 16:21:27 INFO - warning: no files found matching 'pip/cacert.pem' 16:21:27 INFO - warning: no files found matching '*.html' under directory 'docs' 16:21:27 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 16:21:27 INFO - no previously-included directories found matching 'docs/_build/_sources' 16:21:27 INFO - Installing collected packages: pip 16:21:27 INFO - Found existing installation: pip 1.1 16:21:27 INFO - Uninstalling pip: 16:21:27 INFO - Successfully uninstalled pip 16:21:27 INFO - Running setup.py install for pip 16:21:27 INFO - warning: no files found matching 'pip/cacert.pem' 16:21:27 INFO - warning: no files found matching '*.html' under directory 'docs' 16:21:27 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 16:21:27 INFO - no previously-included directories found matching 'docs/_build/_sources' 16:21:27 INFO - Installing pip script to /builds/slave/test/build/venv/bin 16:21:27 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 16:21:27 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 16:21:27 INFO - Successfully installed pip 16:21:27 INFO - Cleaning up... 16:21:27 INFO - Return code: 0 16:21:27 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:21: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')]} 16:21:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:21: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')]} 16:21:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:21:27 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 0x242a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25f2b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27979c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2794d60>, '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 0x2795a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2795ef0>, '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': '62c568580acbfdaac3eec7be000001dc-1506639858.562470-1115674979', '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 16:21:27 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 16:21:27 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 16:21:27 INFO - Using env: {'DISPLAY': ':0', 16:21:27 INFO - 'HOME': '/home/cltbld', 16:21:27 INFO - 'LANG': 'en_US.UTF-8', 16:21:27 INFO - 'LANGUAGE': 'en_US:en', 16:21:27 INFO - 'LOGNAME': 'cltbld', 16:21:27 INFO - 'MAIL': '/var/mail/cltbld', 16:21:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:21:27 INFO - 'MOZ_NO_REMOTE': '1', 16:21:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:21:27 INFO - 'NO_EM_RESTART': '1', 16:21:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:21:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:21:27 INFO - 'PWD': '/builds/slave/test', 16:21:27 INFO - 'SHELL': '/bin/bash', 16:21:27 INFO - 'SHLVL': '1', 16:21:27 INFO - 'TERM': 'linux', 16:21:27 INFO - 'TMOUT': '86400', 16:21:27 INFO - 'USER': 'cltbld', 16:21:27 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1506639858.562470-1115674979', 16:21:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:21:27 INFO - '_': '/tools/buildbot/bin/python'} 16:21:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:21:27 INFO - Downloading/unpacking psutil>=3.1.1 16:21:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:21:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:21:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:21:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:21:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:21:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:21:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:21:28 INFO - Installing collected packages: psutil 16:21:28 INFO - Running setup.py install for psutil 16:21:28 INFO - building 'psutil._psutil_linux' extension 16:21:28 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 16:21:28 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 16:21:28 INFO - building 'psutil._psutil_posix' extension 16:21:28 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 16:21:28 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 16:21:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:21:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:21:28 INFO - Successfully installed psutil 16:21:28 INFO - Cleaning up... 16:21:29 INFO - Return code: 0 16:21:29 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:21:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:21:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:21:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:21:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:21:29 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 0x242a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25f2b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27979c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2794d60>, '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 0x2795a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2795ef0>, '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': '62c568580acbfdaac3eec7be000001dc-1506639858.562470-1115674979', '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 16:21:29 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 16:21:29 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 16:21:29 INFO - Using env: {'DISPLAY': ':0', 16:21:29 INFO - 'HOME': '/home/cltbld', 16:21:29 INFO - 'LANG': 'en_US.UTF-8', 16:21:29 INFO - 'LANGUAGE': 'en_US:en', 16:21:29 INFO - 'LOGNAME': 'cltbld', 16:21:29 INFO - 'MAIL': '/var/mail/cltbld', 16:21:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:21:29 INFO - 'MOZ_NO_REMOTE': '1', 16:21:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:21:29 INFO - 'NO_EM_RESTART': '1', 16:21:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:21:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:21:29 INFO - 'PWD': '/builds/slave/test', 16:21:29 INFO - 'SHELL': '/bin/bash', 16:21:29 INFO - 'SHLVL': '1', 16:21:29 INFO - 'TERM': 'linux', 16:21:29 INFO - 'TMOUT': '86400', 16:21:29 INFO - 'USER': 'cltbld', 16:21:29 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1506639858.562470-1115674979', 16:21:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:21:29 INFO - '_': '/tools/buildbot/bin/python'} 16:21:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:21:29 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:21:29 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 16:21:29 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 16:21:29 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 16:21:29 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 16:21:30 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:21:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:21:30 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:21:30 INFO - Installing collected packages: mozsystemmonitor 16:21:30 INFO - Running setup.py install for mozsystemmonitor 16:21:30 INFO - Successfully installed mozsystemmonitor 16:21:30 INFO - Cleaning up... 16:21:31 INFO - Return code: 0 16:21:31 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:21:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:21:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:21:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:21:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:21:31 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 0x242a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25f2b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27979c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2794d60>, '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 0x2795a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2795ef0>, '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': '62c568580acbfdaac3eec7be000001dc-1506639858.562470-1115674979', '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 16:21:31 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 16:21:31 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 16:21:31 INFO - Using env: {'DISPLAY': ':0', 16:21:31 INFO - 'HOME': '/home/cltbld', 16:21:31 INFO - 'LANG': 'en_US.UTF-8', 16:21:31 INFO - 'LANGUAGE': 'en_US:en', 16:21:31 INFO - 'LOGNAME': 'cltbld', 16:21:31 INFO - 'MAIL': '/var/mail/cltbld', 16:21:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:21:31 INFO - 'MOZ_NO_REMOTE': '1', 16:21:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:21:31 INFO - 'NO_EM_RESTART': '1', 16:21:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:21:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:21:31 INFO - 'PWD': '/builds/slave/test', 16:21:31 INFO - 'SHELL': '/bin/bash', 16:21:31 INFO - 'SHLVL': '1', 16:21:31 INFO - 'TERM': 'linux', 16:21:31 INFO - 'TMOUT': '86400', 16:21:31 INFO - 'USER': 'cltbld', 16:21:31 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1506639858.562470-1115674979', 16:21:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:21:31 INFO - '_': '/tools/buildbot/bin/python'} 16:21:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:21:31 INFO - Downloading/unpacking jsonschema==2.5.1 16:21:31 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 16:21:31 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 16:21:31 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 16:21:31 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 16:21:32 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:21:33 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:21:33 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 16:21:33 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 16:21:33 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 16:21:33 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 16:21:33 INFO - Downloading functools32-3.2.3-2.tar.gz 16:21:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:21:33 INFO - warning: no files found matching '*.txt' 16:21:33 INFO - no previously-included directories found matching 'build' 16:21:33 INFO - no previously-included directories found matching 'dist' 16:21:33 INFO - no previously-included directories found matching '.git*' 16:21:33 INFO - Installing collected packages: jsonschema, functools32 16:21:33 INFO - Running setup.py install for functools32 16:21:33 INFO - warning: no files found matching '*.txt' 16:21:33 INFO - no previously-included directories found matching 'build' 16:21:33 INFO - no previously-included directories found matching 'dist' 16:21:33 INFO - no previously-included directories found matching '.git*' 16:21:34 INFO - Successfully installed jsonschema functools32 16:21:34 INFO - Cleaning up... 16:21:34 INFO - Return code: 0 16:21:34 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:21:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:21:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:21:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:21:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:21:34 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 0x242a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25f2b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27979c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2794d60>, '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 0x2795a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2795ef0>, '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': '62c568580acbfdaac3eec7be000001dc-1506639858.562470-1115674979', '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 16:21:34 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 16:21:34 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 16:21:34 INFO - Using env: {'DISPLAY': ':0', 16:21:34 INFO - 'HOME': '/home/cltbld', 16:21:34 INFO - 'LANG': 'en_US.UTF-8', 16:21:34 INFO - 'LANGUAGE': 'en_US:en', 16:21:34 INFO - 'LOGNAME': 'cltbld', 16:21:34 INFO - 'MAIL': '/var/mail/cltbld', 16:21:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:21:34 INFO - 'MOZ_NO_REMOTE': '1', 16:21:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:21:34 INFO - 'NO_EM_RESTART': '1', 16:21:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:21:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:21:34 INFO - 'PWD': '/builds/slave/test', 16:21:34 INFO - 'SHELL': '/bin/bash', 16:21:34 INFO - 'SHLVL': '1', 16:21:34 INFO - 'TERM': 'linux', 16:21:34 INFO - 'TMOUT': '86400', 16:21:34 INFO - 'USER': 'cltbld', 16:21:34 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1506639858.562470-1115674979', 16:21:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:21:34 INFO - '_': '/tools/buildbot/bin/python'} 16:21:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:21:34 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:21:34 INFO - Cleaning up... 16:21:34 INFO - Return code: 0 16:21:34 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:21:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:21:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:21:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:21:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:21:34 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 0x242a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25f2b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27979c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2794d60>, '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 0x2795a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2795ef0>, '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': '62c568580acbfdaac3eec7be000001dc-1506639858.562470-1115674979', '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 16:21:34 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 16:21:34 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 16:21:34 INFO - Using env: {'DISPLAY': ':0', 16:21:34 INFO - 'HOME': '/home/cltbld', 16:21:34 INFO - 'LANG': 'en_US.UTF-8', 16:21:34 INFO - 'LANGUAGE': 'en_US:en', 16:21:34 INFO - 'LOGNAME': 'cltbld', 16:21:34 INFO - 'MAIL': '/var/mail/cltbld', 16:21:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:21:34 INFO - 'MOZ_NO_REMOTE': '1', 16:21:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:21:34 INFO - 'NO_EM_RESTART': '1', 16:21:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:21:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:21:34 INFO - 'PWD': '/builds/slave/test', 16:21:34 INFO - 'SHELL': '/bin/bash', 16:21:34 INFO - 'SHLVL': '1', 16:21:34 INFO - 'TERM': 'linux', 16:21:34 INFO - 'TMOUT': '86400', 16:21:34 INFO - 'USER': 'cltbld', 16:21:34 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1506639858.562470-1115674979', 16:21:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:21:34 INFO - '_': '/tools/buildbot/bin/python'} 16:21:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:21:34 INFO - Downloading/unpacking blobuploader==1.2.4 16:21:34 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 16:21:34 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 16:21:34 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 16:21:34 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 16:21:35 INFO - Downloading blobuploader-1.2.4.tar.gz 16:21:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:21:35 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:21:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:21:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:21:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:21:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:21:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:21:35 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:21:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:21:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:21:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:21:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:21:36 INFO - Downloading docopt-0.6.1.tar.gz 16:21:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:21:36 INFO - Installing collected packages: blobuploader, requests, docopt 16:21:36 INFO - Running setup.py install for blobuploader 16:21:36 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:21:36 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:21:36 INFO - Running setup.py install for requests 16:21:36 INFO - Running setup.py install for docopt 16:21:36 INFO - Successfully installed blobuploader requests docopt 16:21:36 INFO - Cleaning up... 16:21:36 INFO - Return code: 0 16:21:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:21:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:21:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:21:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:21:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:21:36 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 0x242a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25f2b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27979c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2794d60>, '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 0x2795a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2795ef0>, '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': '62c568580acbfdaac3eec7be000001dc-1506639858.562470-1115674979', '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 16:21:36 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 16:21:36 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 16:21:36 INFO - Using env: {'DISPLAY': ':0', 16:21:36 INFO - 'HOME': '/home/cltbld', 16:21:36 INFO - 'LANG': 'en_US.UTF-8', 16:21:36 INFO - 'LANGUAGE': 'en_US:en', 16:21:36 INFO - 'LOGNAME': 'cltbld', 16:21:36 INFO - 'MAIL': '/var/mail/cltbld', 16:21:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:21:36 INFO - 'MOZ_NO_REMOTE': '1', 16:21:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:21:36 INFO - 'NO_EM_RESTART': '1', 16:21:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:21:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:21:36 INFO - 'PWD': '/builds/slave/test', 16:21:36 INFO - 'SHELL': '/bin/bash', 16:21:36 INFO - 'SHLVL': '1', 16:21:36 INFO - 'TERM': 'linux', 16:21:36 INFO - 'TMOUT': '86400', 16:21:36 INFO - 'USER': 'cltbld', 16:21:36 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1506639858.562470-1115674979', 16:21:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:21:36 INFO - '_': '/tools/buildbot/bin/python'} 16:21:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:21:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:21:36 INFO - Running setup.py (path:/tmp/pip-vQ41wx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:21:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:21:37 INFO - Running setup.py (path:/tmp/pip-hGAkiF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:21:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:21:37 INFO - Running setup.py (path:/tmp/pip-EtSCTL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:21:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:21:37 INFO - Running setup.py (path:/tmp/pip-YjapsH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:21:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:21:37 INFO - Running setup.py (path:/tmp/pip-9tcABp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:21:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:21:37 INFO - Running setup.py (path:/tmp/pip-lL3UpA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:21:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:21:37 INFO - Running setup.py (path:/tmp/pip-XCiEAR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:21:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:21:37 INFO - Running setup.py (path:/tmp/pip-KlGakZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:21:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:21:37 INFO - Running setup.py (path:/tmp/pip-45qE1L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:21:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:21:37 INFO - Running setup.py (path:/tmp/pip-zLFNZq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:21:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:21:37 INFO - Running setup.py (path:/tmp/pip-wjLD49-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:21:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:21:37 INFO - Running setup.py (path:/tmp/pip-8gwJTg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:21:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:21:37 INFO - Running setup.py (path:/tmp/pip-VUOem2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:21:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:21:37 INFO - Running setup.py (path:/tmp/pip-MaK1UB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:21:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:21:37 INFO - Running setup.py (path:/tmp/pip-QZK38_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:21:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:21:38 INFO - Running setup.py (path:/tmp/pip-QdwNO0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:21:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:21:38 INFO - Running setup.py (path:/tmp/pip-3sgAcx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:21:38 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:21:38 INFO - Running setup.py install for manifestparser 16:21:38 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:21:38 INFO - Running setup.py install for mozcrash 16:21:38 INFO - Running setup.py install for mozdebug 16:21:38 INFO - Running setup.py install for mozdevice 16:21:38 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:21:38 INFO - Running setup.py install for mozfile 16:21:38 INFO - Running setup.py install for mozhttpd 16:21:38 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:21:38 INFO - Running setup.py install for mozinfo 16:21:38 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:21:38 INFO - Running setup.py install for mozInstall 16:21:38 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:21:38 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:21:38 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:21:38 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:21:38 INFO - Running setup.py install for mozleak 16:21:39 INFO - Running setup.py install for mozlog 16:21:39 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:21:39 INFO - Running setup.py install for moznetwork 16:21:39 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:21:39 INFO - Running setup.py install for mozprocess 16:21:39 INFO - Running setup.py install for mozprofile 16:21:39 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:21:39 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:21:39 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:21:39 INFO - Running setup.py install for mozrunner 16:21:39 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:21:39 INFO - Running setup.py install for mozscreenshot 16:21:39 INFO - Running setup.py install for moztest 16:21:39 INFO - Running setup.py install for mozversion 16:21:39 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:21:39 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:21:39 INFO - Cleaning up... 16:21:39 INFO - Return code: 0 16:21:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:21:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:21:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:21:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:21:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:21:39 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 0x242a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25f2b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27979c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2794d60>, '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 0x2795a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2795ef0>, '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': '62c568580acbfdaac3eec7be000001dc-1506639858.562470-1115674979', '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 16:21:39 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 16:21:39 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 16:21:39 INFO - Using env: {'DISPLAY': ':0', 16:21:39 INFO - 'HOME': '/home/cltbld', 16:21:39 INFO - 'LANG': 'en_US.UTF-8', 16:21:39 INFO - 'LANGUAGE': 'en_US:en', 16:21:39 INFO - 'LOGNAME': 'cltbld', 16:21:39 INFO - 'MAIL': '/var/mail/cltbld', 16:21:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:21:39 INFO - 'MOZ_NO_REMOTE': '1', 16:21:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:21:39 INFO - 'NO_EM_RESTART': '1', 16:21:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:21:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:21:39 INFO - 'PWD': '/builds/slave/test', 16:21:39 INFO - 'SHELL': '/bin/bash', 16:21:39 INFO - 'SHLVL': '1', 16:21:39 INFO - 'TERM': 'linux', 16:21:39 INFO - 'TMOUT': '86400', 16:21:39 INFO - 'USER': 'cltbld', 16:21:39 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1506639858.562470-1115674979', 16:21:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:21:39 INFO - '_': '/tools/buildbot/bin/python'} 16:21:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:21:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:21:40 INFO - Running setup.py (path:/tmp/pip-BADTy1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:21:40 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)) 16:21:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:21:40 INFO - Running setup.py (path:/tmp/pip-f7TKBw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:21:40 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)) 16:21:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:21:40 INFO - Running setup.py (path:/tmp/pip-kEPe_G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:21:40 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)) 16:21:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:21:40 INFO - Running setup.py (path:/tmp/pip-uRJSjB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:21:40 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)) 16:21:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:21:40 INFO - Running setup.py (path:/tmp/pip-33aMaL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:21:40 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)) 16:21:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:21:40 INFO - Running setup.py (path:/tmp/pip-24gEFF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:21:40 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)) 16:21:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:21:40 INFO - Running setup.py (path:/tmp/pip-uv_XQ9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:21:40 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)) 16:21:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:21:40 INFO - Running setup.py (path:/tmp/pip-mgTPf8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:21:40 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)) 16:21:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:21:40 INFO - Running setup.py (path:/tmp/pip-aGfC7l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:21:40 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)) 16:21:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:21:40 INFO - Running setup.py (path:/tmp/pip-JSWDHz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:21:40 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)) 16:21:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:21:40 INFO - Running setup.py (path:/tmp/pip-uNbOxm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:21:40 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)) 16:21:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:21:40 INFO - Running setup.py (path:/tmp/pip-GquJ_x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:21:40 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)) 16:21:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:21:41 INFO - Running setup.py (path:/tmp/pip-dYJezH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:21:41 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)) 16:21:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:21:41 INFO - Running setup.py (path:/tmp/pip-XLotNb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:21:41 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)) 16:21:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:21:41 INFO - Running setup.py (path:/tmp/pip-8KWluW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:21:41 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)) 16:21:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:21:41 INFO - Running setup.py (path:/tmp/pip-0TU76g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:21:41 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)) 16:21:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:21:41 INFO - Running setup.py (path:/tmp/pip-X0ga4X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:21:41 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)) 16:21:41 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)) 16:21:41 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)) 16:21:41 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)) 16:21:41 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)) 16:21:41 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)) 16:21:41 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)) 16:21:41 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:21:41 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 16:21:41 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 16:21:41 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 16:21:41 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 16:21:42 INFO - Downloading blessings-1.6.tar.gz 16:21:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:21:42 INFO - Installing collected packages: blessings 16:21:42 INFO - Running setup.py install for blessings 16:21:42 INFO - Successfully installed blessings 16:21:42 INFO - Cleaning up... 16:21:42 INFO - Return code: 0 16:21:42 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:21:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:21:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:21:43 INFO - Reading from file tmpfile_stdout 16:21:43 INFO - Current package versions: 16:21:43 INFO - argparse == 1.2.1 16:21:43 INFO - blessings == 1.6 16:21:43 INFO - blobuploader == 1.2.4 16:21:43 INFO - docopt == 0.6.1 16:21:43 INFO - functools32 == 3.2.3-2 16:21:43 INFO - jsonschema == 2.5.1 16:21:43 INFO - manifestparser == 1.1 16:21:43 INFO - mozInstall == 1.13 16:21:43 INFO - mozcrash == 1.0 16:21:43 INFO - mozdebug == 0.1 16:21:43 INFO - mozdevice == 0.51 16:21:43 INFO - mozfile == 1.2 16:21:43 INFO - mozhttpd == 0.7 16:21:43 INFO - mozinfo == 0.10 16:21:43 INFO - mozleak == 0.1 16:21:43 INFO - mozlog == 3.5 16:21:43 INFO - moznetwork == 0.27 16:21:43 INFO - mozprocess == 0.25 16:21:43 INFO - mozprofile == 0.28 16:21:43 INFO - mozrunner == 6.13 16:21:43 INFO - mozscreenshot == 0.1 16:21:43 INFO - mozsystemmonitor == 0.3 16:21:43 INFO - moztest == 0.8 16:21:43 INFO - mozversion == 1.4 16:21:43 INFO - psutil == 3.1.1 16:21:43 INFO - requests == 1.2.3 16:21:43 INFO - wsgiref == 0.1.2 16:21:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:21: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')]} 16:21:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:21: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')]} 16:21:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:21:43 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 0x242a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25f2b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27979c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2794d60>, '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 0x2795a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2795ef0>, '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': '62c568580acbfdaac3eec7be000001dc-1506639858.562470-1115674979', '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 16:21:43 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 16:21:43 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 16:21:43 INFO - Using env: {'DISPLAY': ':0', 16:21:43 INFO - 'HOME': '/home/cltbld', 16:21:43 INFO - 'LANG': 'en_US.UTF-8', 16:21:43 INFO - 'LANGUAGE': 'en_US:en', 16:21:43 INFO - 'LOGNAME': 'cltbld', 16:21:43 INFO - 'MAIL': '/var/mail/cltbld', 16:21:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:21:43 INFO - 'MOZ_NO_REMOTE': '1', 16:21:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:21:43 INFO - 'NO_EM_RESTART': '1', 16:21:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:21:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:21:43 INFO - 'PWD': '/builds/slave/test', 16:21:43 INFO - 'SHELL': '/bin/bash', 16:21:43 INFO - 'SHLVL': '1', 16:21:43 INFO - 'TERM': 'linux', 16:21:43 INFO - 'TMOUT': '86400', 16:21:43 INFO - 'USER': 'cltbld', 16:21:43 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1506639858.562470-1115674979', 16:21:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:21:43 INFO - '_': '/tools/buildbot/bin/python'} 16:21:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:21:43 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)) 16:21:43 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)) 16:21:43 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)) 16:21:43 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)) 16:21:43 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)) 16:21:43 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)) 16:21:43 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)) 16:21:43 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)) 16:21:43 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)) 16:21:43 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 16:21:43 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 16:21:43 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 16:21:43 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 16:21:43 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 16:21:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 16:21:44 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)) 16:21:44 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)) 16:21:44 INFO - Installing collected packages: simplejson 16:21:44 INFO - Running setup.py install for simplejson 16:21:44 INFO - building 'simplejson._speedups' extension 16:21:44 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 16:21:45 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 16:21:45 INFO - Successfully installed simplejson 16:21:45 INFO - Cleaning up... 16:21:45 INFO - Return code: 0 16:21:45 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:21:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:21:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:21:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:21:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:21:45 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x242a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25f2b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27979c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2794d60>, '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 0x2795a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2795ef0>, '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': '62c568580acbfdaac3eec7be000001dc-1506639858.562470-1115674979', '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 16:21:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 16:21:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 16:21:45 INFO - Using env: {'DISPLAY': ':0', 16:21:45 INFO - 'HOME': '/home/cltbld', 16:21:45 INFO - 'LANG': 'en_US.UTF-8', 16:21:45 INFO - 'LANGUAGE': 'en_US:en', 16:21:45 INFO - 'LOGNAME': 'cltbld', 16:21:45 INFO - 'MAIL': '/var/mail/cltbld', 16:21:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:21:45 INFO - 'MOZ_NO_REMOTE': '1', 16:21:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:21:45 INFO - 'NO_EM_RESTART': '1', 16:21:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:21:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:21:45 INFO - 'PWD': '/builds/slave/test', 16:21:45 INFO - 'SHELL': '/bin/bash', 16:21:45 INFO - 'SHLVL': '1', 16:21:45 INFO - 'TERM': 'linux', 16:21:45 INFO - 'TMOUT': '86400', 16:21:45 INFO - 'USER': 'cltbld', 16:21:45 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1506639858.562470-1115674979', 16:21:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:21:45 INFO - '_': '/tools/buildbot/bin/python'} 16:21:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:21:45 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:21:45 INFO - Cleaning up... 16:21:45 INFO - Return code: 0 16:21:45 INFO - Running post-action listener: _resource_record_post_action 16:21:45 INFO - Running post-action listener: _start_resource_monitoring 16:21:45 INFO - Starting resource monitoring. 16:21:45 INFO - [mozharness: 2017-09-28 23:21:45.774020Z] Finished create-virtualenv step (success) 16:21:45 INFO - [mozharness: 2017-09-28 23:21:45.774561Z] Running install step. 16:21:45 INFO - Running pre-action listener: _resource_record_pre_action 16:21:45 INFO - Running main action method: install 16:21:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:21:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:21:45 INFO - Reading from file tmpfile_stdout 16:21:45 INFO - Detecting whether we're running mozinstall >=1.0... 16:21:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:21:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:21:46 INFO - Reading from file tmpfile_stdout 16:21:46 INFO - Output received: 16:21:46 INFO - Usage: mozinstall [options] installer 16:21:46 INFO - Options: 16:21:46 INFO - -h, --help show this help message and exit 16:21:46 INFO - -d DEST, --destination=DEST 16:21:46 INFO - Directory to install application into. [default: 16:21:46 INFO - "/builds/slave/test"] 16:21:46 INFO - --app=APP Application being installed. [default: firefox] 16:21:46 INFO - mkdir: /builds/slave/test/build/application 16:21:46 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'] 16:21:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 16:22:03 INFO - Reading from file tmpfile_stdout 16:22:03 INFO - Output received: 16:22:03 INFO - /builds/slave/test/build/application/firefox/firefox 16:22:03 INFO - Running post-action listener: _resource_record_post_action 16:22:03 INFO - [mozharness: 2017-09-28 23:22:03.790118Z] Finished install step (success) 16:22:03 INFO - [mozharness: 2017-09-28 23:22:03.790378Z] Running setup-mitmproxy step. 16:22:03 INFO - Running pre-action listener: _resource_record_pre_action 16:22:03 INFO - Running main action method: setup_mitmproxy 16:22:03 INFO - Skipping: mitmproxy is not required 16:22:03 INFO - Running post-action listener: _resource_record_post_action 16:22:03 INFO - [mozharness: 2017-09-28 23:22:03.791508Z] Finished setup-mitmproxy step (success) 16:22:03 INFO - [mozharness: 2017-09-28 23:22:03.791744Z] Running run-tests step. 16:22:03 INFO - Running pre-action listener: _resource_record_pre_action 16:22:03 INFO - Running pre-action listener: _set_gcov_prefix 16:22:03 INFO - Running main action method: run_tests 16:22:03 WARNING - Try message not found. 16:22:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:22:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:22:03 INFO - Python 2.7.3 16:22:03 INFO - Return code: 0 16:22:03 INFO - grabbing minidump binary from tooltool 16:22:03 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')]} 16:22:03 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 0x2794d60>, '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 0x2795a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2795ef0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:22:03 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 16:22:03 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 16:22:03 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 16:22:03 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:22:03 INFO - Return code: 0 16:22:03 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 16:22:03 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:22:03 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:22:03 INFO - ENV: RUST_BACKTRACE is now full 16:22:03 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 16:22:03 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:22:03 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/cmYlyjEDRk2IOAJ-a13Uqg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-018', '--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 16:22:03 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/cmYlyjEDRk2IOAJ-a13Uqg/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-018 --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 16:22:03 INFO - Using env: {'DISPLAY': ':0', 16:22:03 INFO - 'HOME': '/home/cltbld', 16:22:03 INFO - 'LANG': 'en_US.UTF-8', 16:22:03 INFO - 'LANGUAGE': 'en_US:en', 16:22:03 INFO - 'LOGNAME': 'cltbld', 16:22:03 INFO - 'MAIL': '/var/mail/cltbld', 16:22:03 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:22:03 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:22:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:22:03 INFO - 'MOZ_NO_REMOTE': '1', 16:22:03 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:22:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:22:03 INFO - 'NO_EM_RESTART': '1', 16:22:03 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:22:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:22:03 INFO - 'PWD': '/builds/slave/test', 16:22:03 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:22:03 INFO - 'RUST_BACKTRACE': 'full', 16:22:03 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 16:22:03 INFO - 'SHELL': '/bin/bash', 16:22:03 INFO - 'SHLVL': '1', 16:22:03 INFO - 'TERM': 'linux', 16:22:03 INFO - 'TMOUT': '86400', 16:22:03 INFO - 'USER': 'cltbld', 16:22:03 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1506639858.562470-1115674979', 16:22:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:22:03 INFO - '_': '/tools/buildbot/bin/python'} 16:22:03 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/cmYlyjEDRk2IOAJ-a13Uqg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-018', '--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 16:22:04 INFO - mozversion application_buildid: 20170928215608 16:22:04 INFO - mozversion application_changeset: 307a7a34013060a6a1e87dfbb911f058d0781a2e 16:22:04 INFO - mozversion application_display_name: Nightly 16:22:04 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:22:04 INFO - mozversion application_name: Firefox 16:22:04 INFO - mozversion application_remotingname: firefox 16:22:04 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 16:22:04 INFO - mozversion application_vendor: Mozilla 16:22:04 INFO - mozversion application_version: 58.0a1 16:22:04 INFO - mozversion platform_buildid: 20170928215608 16:22:04 INFO - mozversion platform_changeset: 307a7a34013060a6a1e87dfbb911f058d0781a2e 16:22:04 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 16:22:04 INFO - mozversion platform_version: 58.0a1 16:22:04 INFO - using testdate: 1506640924 16:22:04 INFO - actual date: 1506640924 16:22:04 INFO - starting webserver on 'localhost:46612' 16:22:04 INFO - SUITE-START | Running 2 tests 16:22:04 INFO - TEST-START | dromaeo_css 16:22:04 INFO - Initialising browser for dromaeo_css test... 16:22:04 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:46612/getInfo.html -profile /tmp/tmpnHQI8l/profile 16:22:04 INFO - TEST-INFO | started process 30825 (/builds/slave/test/build/application/firefox/firefox http://localhost:46612/getInfo.html) 16:22:09 INFO - TEST-INFO | 30825: exit 0 16:22:09 INFO - Browser initialized. 16:22:09 INFO - Running cycle 1/1 for dromaeo_css test... 16:22:09 INFO - Using env: {'DISPLAY': ':0', 16:22:09 INFO - 'HOME': '/home/cltbld', 16:22:09 INFO - 'JSGC_DISABLE_POISONING': '1', 16:22:09 INFO - 'LANG': 'en_US.UTF-8', 16:22:09 INFO - 'LANGUAGE': 'en_US:en', 16:22:09 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 16:22:09 INFO - 'LOGNAME': 'cltbld', 16:22:09 INFO - 'MAIL': '/var/mail/cltbld', 16:22:09 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:22:09 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:22:09 INFO - 'MOZ_CRASHREPORTER': '1', 16:22:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:22:09 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 16:22:09 INFO - 'MOZ_NO_REMOTE': '1', 16:22:09 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:22:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:22:09 INFO - 'NO_EM_RESTART': '1', 16:22:09 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:22:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:22:09 INFO - 'PWD': '/builds/slave/test', 16:22:09 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:22:09 INFO - 'RUST_BACKTRACE': 'full', 16:22:09 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 16:22:09 INFO - 'SHELL': '/bin/bash', 16:22:09 INFO - 'SHLVL': '1', 16:22:09 INFO - 'TERM': 'linux', 16:22:09 INFO - 'TMOUT': '86400', 16:22:09 INFO - 'USER': 'cltbld', 16:22:09 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1506639858.562470-1115674979', 16:22:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:22:09 INFO - '_': '/tools/buildbot/bin/python'} 16:22:09 INFO - TEST-INFO | started process 31060 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpnHQI8l/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 16:22:10 INFO - PID 31060 | 16:22:10 INFO - PID 31060 | (/builds/slave/test/build/application/firefox/firefox:31111): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 16:22:10 INFO - PID 31060 | 16:22:20 INFO - PID 31060 | RSS: Main: 145137664 16:22:20 INFO - PID 31060 | 16:25:17 INFO - PID 31060 | Cycle 1(1): loaded http://localhost:46612/tests/dromaeo/cssquery-dojo.html (next: http://localhost:46612/tests/dromaeo/cssquery-ext.html) 16:25:18 INFO - PID 31060 | RSS: Main: 143114240 16:25:18 INFO - PID 31060 | 16:28:20 INFO - PID 31060 | Cycle 1(1): loaded http://localhost:46612/tests/dromaeo/cssquery-ext.html (next: http://localhost:46612/tests/dromaeo/cssquery-jquery.html) 16:28:21 INFO - PID 31060 | RSS: Main: 143630336 16:28:21 INFO - PID 31060 | 16:31:22 INFO - PID 31060 | Cycle 1(1): loaded http://localhost:46612/tests/dromaeo/cssquery-jquery.html (next: http://localhost:46612/tests/dromaeo/cssquery-mootools.html) 16:31:23 INFO - PID 31060 | RSS: Main: 143728640 16:31:23 INFO - PID 31060 | 16:34:24 INFO - PID 31060 | Cycle 1(1): loaded http://localhost:46612/tests/dromaeo/cssquery-mootools.html (next: http://localhost:46612/tests/dromaeo/cssquery-prototype.html) 16:34:25 INFO - PID 31060 | RSS: Main: 144777216 16:34:25 INFO - PID 31060 | 16:37:28 INFO - PID 31060 | Cycle 1(1): loaded http://localhost:46612/tests/dromaeo/cssquery-prototype.html (next: http://localhost:46612/tests/dromaeo/cssquery-yui.html) 16:37:28 INFO - PID 31060 | RSS: Main: 148176896 16:37:28 INFO - PID 31060 | 16:40:30 INFO - PID 31060 | Cycle 1(1): loaded http://localhost:46612/tests/dromaeo/cssquery-yui.html (next: http://localhost:46612/tests/dromaeo/cssquery-dojo.html) 16:40:30 INFO - PID 31060 | RSS: Main: 149323776 16:40:30 INFO - PID 31060 | 16:40:30 INFO - PID 31060 | __start_tp_report 16:40:30 INFO - PID 31060 | _x_x_mozilla_page_load 16:40:30 INFO - PID 31060 | _x_x_mozilla_page_load_details 16:40:30 INFO - PID 31060 | |i|pagename|runs| 16:40:30 INFO - PID 31060 | |0;dojo.html;3811.752236104655;3814.5041144651195;3815.503984482017;3832.8466861325555;3842.1739326044894;6055.606385584934;6194.070889366597;6217.533684973628;6226.5952713073675;6317.336679648634;6138.693065346726;6143.846403839906;6166.814995550129;6167.7841275555365;6176.814695559129;5501.614886957915;5509.063459211943;5513.09034009388;5517.503424691777;5584.6090773645865;5600.27196464459;5622.803201887935;5637.238972738669;5660.60375773696;5662.943370566303;5636.859078523029;5640.379558248589;5700.9714951425185;5718;5743.942560574404;5430.294061771984;5459.426760190163;5463.125899856004;5472.671639701631;5709.71451427429;5578.252280315895;5585.413531579207;5609.186667933127;5657.490825825655;5660.2075709400715;6131.87736245277;6174.567780255384;6217.036359364306;6254.312025677172;6265.216847894013;6729.427998620124;6780.72877084916;6816.591004539743;6826.488013399015;6840.521163518556;6860.245373008965;6889.965550172267;6897.55165914219;6898.1377327834025;6903.068085808452;6200.0079987202735;6294.968525157391;6297.748090076391;6387.2654644715985;6388.968055159695;6927.68825402848;6987.650617469106;7019.192792828788;7025.964870175617;7103.431725461951;6647.700853461606;6683.298253683391;6734.393904548614;6737.056812046318;6755.459563234929;6686.231083425469;6768.864622707617;6831.180258369028;6856;6873.037774711544;6655.43428808547;6692.063111164441;6776.356246156607;6789.388954993976;6800.897986530206;6729.461643068543;6794.3545363190415;6829.726810927507;6830.180378354629;6834.419074378634;4936.555709986119;4955.157623204064;5030.471800460972;5031.270465782514;5035.974820125876;4399.890002749893;4421.071574969293;4450.310201918708;4466.620337271368;4521.886952826206;6318.494520438354;6350.237971443456;6351.460125889259;6355.682215889187;6356.968215158895;6764.222114456802;6767.593944363354;6770.458363330922;6839.794806155824;6851.314868513109;6241.687915604201;6281.7173227204885;6319.96840015797;6333.904991425133;6358.809235722936;5317.468253174729;5382.569394448435;5383.757730902119;5386.434424385461;5386.461353864661;6819.011243369684;6827.112475378169;6835.829104272433;6836.418904393083;6884.173899132036;6691.397774200248;6713.12729345182;6791.219009813737;6795.558288711218;6804.11546498952;6947.930520694728;6968.442524598121;6986.441084713109;7071;7078.362947334765;6474.125992990886;6563.409293163638;6663.666816659244;6711.597304161766;6790.015447760146;6775.051492790914;6791.7283308669075;6837.555558888656;6844.555103918229;6862.142232220973;6540.869182616416;6551.050097735706;6640.667966601554;6642.800715978528;6646.83382915431;6498.935010649833;6505.7072431741635;6546.934530654823;6594.406503414716;6613.933860661332;6244.219472565929;6357.300696923242;6436.195475565554;6518.120053792867;6554.606723596408;6672.633005184567;6682.565633233824;6692.163479565054;6704.631245281361;6722.226943901514;6295.527835412454;6387.169667943138;6478.190226221723;6478.222613286436;6530.412262896362 16:40:30 INFO - PID 31060 | |1;ext.html;949.7055912667074;970.6263088710847;995.7759504111572;997.2520609542843;1001.2640709078826;43759.56240437599;44686.32970505445;44730.32904506435;44784.3282350765;44827.103457930905;41423.17153656925;41770.79114604423;44436.55563444365;45085.54914450854;45122.097558048816;8400.45397049193;8547.658093676246;8549.444286121414;8550.273226775731;8550.52972086535;8336.916630833704;8381.664733410656;8426.662933482654;8438.451500652469;8463;8461.746147615582;8478.915210847905;8486.0665326814;8488.915110848906;8504.064552899175;8408.663653453854;8425.957870210641;8431.578421078983;8437.367197460153;8460.49237045773;8243.546604936726;8290;8311.584420778936;8312.501249925024;8322.417430779848;41169.79415102935;41291.58708412937;41313.58686413128;41435;41699.7915010426;3621.0947263184203;3626.637336266352;3638.945415818738;3650.7261955353188;3655.817209139532;33906.152346191295;34002.48996265058;34104;34124.82937585321;34150.82924585361;5314.282571852789;5361.169018802092;5375.193720941851;5379.462053794589;5449.945500545023;218366;221635;223051;226218;236275.99999999828;55172.72413637906;55501.722491387285;55791.16313255223;55849.72075139598;56001.15998260029;57201.71399143061;57449.13826292609;57657;57702.7114864423;57714.71142644344;16419.6716065678;16645.83354166443;16650.83349166493;16653.666926661397;16896.239669214916;13646.795298070538;13675.931620341835;13677.521286754916;13681.794773078413;13691.452341906412;14515.274236288355;14607.269636518131;14697.55907322782;14706.852931470763;14876;5148.639595228298;5225.738713064407;5249.553787928069;5357.946420535744;5389.251431878464;1083.5990683447176;1104.0946423932298;1104.2987702808775;1110.8500352452477;1119.0935342372704;25760.613590796154;25931.870340648176;25941.351466213495;25992.22023339303;26059.478810424065;1197.1918954705538;1201.9158658893969;1213.9514419423124;1223.1926928227506;1225.737490384905;13666.248356340497;13668.111572747739;13719;13794.793078103836;13974.510892118727;295783;297486;298299;298329;298591;58502.70748646314;58643;58679.41320586825;58860.70569647125;58865.70567147137;1484.6659501612266;1513.182881244138;1521.246982743549;1532.923353832304;1543.236098131432;24909.626355605043;24943.126990555244;25076;25093.121740738985;25101;41326.38010429906;41772.373414399415;41792;41811.7909410451;41824.79087604542;237986;238059;238691;238702;239055;37364.813175933945;39536.60463395329;40310;40341.39487907625;40365.79817100896;48992.265116024;50174.74912625414;50525.74737126291;50578;50700.23949640832;37037;37393.25213495769;37421.81289093537;37479;38063.23873522569;36825;36939.26121477609;37022.259554809294;37103.44344834776;37237;42989;43172.352414714434;43694.78152609344;43697.78151109224;43757.78121109501;36073;36129.638703612625;36333;36354.23245821219;36668;26315.86842065854;26960.865195673898;27004;27151.59272610873;27274.318142046606 16:40:30 INFO - PID 31060 | |2;jquery.html;605.551891600216;607.7113371148702;620.4725982914522;625.5308518611042;646.6831252686184;23253;23506.529869402602;24069.27792166238;24074.75925240747;24079.15722949697;24575.385615359624;25711.485770284584;25740.356491087732;25741.35646608831;25792;6353.428191462779;6482.384173503533;6485.027245913127;6485.286618471988;6509.902351464708;6458.354164583528;6691.3308669133185;6725.932740672604;6728.6635668216395;6733.057371967904;6609.3390660934;6640.203175618933;6651.833704157387;6679.29867363927;6684.364985326386;6403.48772098231;6578.539502234846;6606.339366063403;6638.5684930479365;6675.66621668917;6220.315765265817;6224.470919971808;6239.469645080174;6239.719212635397;6244.281907580641;30022.249443763863;30485.542716859265;30562.847185764153;30621.38757224843;30647;2308.86146831189;2314.618088015475;2341.6487526870937;2342;2348.3542025942834;19181.712274315898;20312.898435507876;20352.79647203524;20441.38675839727;20514.179432822668;2550.3040478082053;2553.795696344288;2592.5592649249675;2605.387733882536;2615.5815069588966;137098;142565.28717356452;152338;158721;159414.99999999884;15077.396904123712;15300.770488442682;15579.298931548108;15871.52385428439;16225.2698628562;24079.51840963171;24126;24128.87935560346;24156.87921560381;24209;10343.482825858677;10631.362118272928;10639.680809575726;10646.361218326925;10698.946505267424;9706.951465242628;9715.805683886283;9767.853482197772;9769.755756106155;9946.104850563339;9133.406328588753;9147;9237.953810230905;9529.857052144223;9644.132028117503;2747.738964798341;2780.638516992778;2813.212300555848;2908.5927970084203;2970.8663110160096;1022.1669339488293;1022.7596514819012;1048.2295512798087;1057.4289883462843;1060.4485667452882;20829;20915.790842091385;21135.8943205283;21241.468963275733;21568.784312156677;1100.1308965916996;1103.2883789955595;1104.116706634705;1109.5506319940437;1120.56298043763;11419.257748246337;11459.713507162387;11564.190506664621;11665.941670291764;11675.241109327866;600478;610171;615539;615811;618785;131279.34360328136;135821;137237;138868;139126.30436847953;1198.9460474278678;1201.4095745999164;1233.8149277608431;1236.097648716442;1239.876012398753;18373.81626183721;18750.53123671919;18806.811931880507;18871.811281887556;18938.905305473385;64562.35437645563;64759.35240647533;65596.67201663961;65725.34274657193;67230;82106.58946705228;82558;82680.58659706663;83310.16689833024;83875.16124838918;15281;15335.38658453694;15368.462103826312;15545.145017024171;15569.844301557292;23237.76762232356;23377.88311058502;23388;23502.294931152093;23617.055317787097;16519.587010324838;16631.168441578116;16780;17677.38129165473;19101.808981910002;17174.141292935554;17583.384581539583;17606.29574816993;17737.113144342988;17842;19592.510187245432;19643.70534442013;19868.10593523266;19916.60166796685;20025.699614506084;15183.468578599695;15331;15340.539783806504;15340.846591533942;15348;18615.255389784255;18745.437636870916;18843.152058157142;18902.338418155297;18903.243870245034 16:40:30 INFO - PID 31060 | |3;mootools.html;1386.5147198480531;1455.5778824141;1459.6059064052713;1507.5175943698014;1508.1252873333467;16747.916260418697;17277.395291164812;17348.566285842844;17371.30514779407;17412.216450259737;16933.06868122253;17136.914315428436;17168.31326746932;17205.483835484923;17235;2787.28924124348;2797.9160625181225;2825.8021938464317;2829.1371131804895;2845.046909285392;2640.247529454106;2703.8918443262246;2716.9592456113173;2717.904873329434;2718.8504632245217;2646.219365287235;2702.527057764893;2706.6751989761156;2730.699623041464;2734.4394399148127;2711.200195942202;2721.4285000149994;2729.290384500034;2736.165469531812;2736.931576710578;2729.6997330293652;2769.62610047645;2770.0581802187153;2780.2910257884314;2782.9721702782917;16689.582760430963;16995;17069.231884565223;17074.48776536706;17091;7992.840143197103;8229.753107406787;8275.255227029536;8282.50304981703;8286.129956354556;5408.134698448269;5500.4224556421395;5585.273914391144;5654.575906806965;5765.250517432749;2143.882086485243;2147.452399638098;2174.7390313162523;2222.1777942161502;2230.743464501587;30532.847335763403;30633.387332253453;30748.846255768578;30785.38429231403;30813.537796933066;7080.504364694422;7177.641117944187;7180.3537681608905;7380.77857664271;7404.074490688664;20004.49988750293;20164.19343226284;20225.898870505553;20241.595168096555;20246.392608221777;8237.135100814448;8362.45644033136;8399.790005249795;8404.20160084791;8423.620937057847;7274.345308922222;7649.235076492418;7657.693692252247;7693.807654808675;7725.420593455513;5618.887622247532;5748.338941021752;5808.506276966421;5858.062709966385;5887.35239123696;1693.678201141779;1722.9138543073047;1726.7237242041213;1727.8358555937166;1738.0800255570762;885.1104639836923;899.9820003599891;917.6100157433065;919.2186641354929;919.8344298026286;8645.351598630128;8688.913110868936;8731.65073397057;8833.028366879638;8840.469571825712;784.5998540744262;786.7325109462696;826.3348004856028;835.4820011592851;844.7381311793364;5761.423857614205;6119.632822030692;6204.782832600928;6269.529785266123;6306.369363063657;24399.26802195937;24606.507869842502;24904;25115;25185.874070629896;23975.52048959046;24113.7588624115;24869.5026099477;25028.624570631455;25189.370265743502;11810.52757889692;12017.939910300393;12030.81953770694;12056.51773929033;12060.819087713691;9538.380005299634;9701.417914925127;9853.753656158367;9859.211263098792;9994.700158995242;20453;20464.488387790425;20745.688814668094;20842.68735968992;20858.37424877256;21718.348449545905;21729.78270217341;21796.89101554482;22110.447238818513;22308.77691223067;12832.615021549369;12954.67613309637;12989;13035.674108147372;13035.869641303465;15272;15439.768403473732;15532.689346212786;15630.218489075729;15654.843451565794;11775.35235562018;11866.110041747077;12116.757664846476;12204.572839950559;12341.197822141532;11918.642440726791;12046.156769026084;12404.131710780159;12526;12561.057920655805;11240.15698822608;11285.492152853305;11392.94303528477;11401.030912372209;11406.429678516208;8667.263282621048;8737.388382813142;8739.650413983369;8829.293656507336;8843.69047083349;8713.302935764998;8812.823743524965;8857;8865.822683546165;8874.955625221832 16:40:30 INFO - PID 31060 | |4;prototype.html;550.2928736573502;556.1824118545736;572.0589630058552;580.2659635561016;580.3934888041996;6139.8772024559485;6167.352427995058;6198.605313804392;6207.906881396777;6226;6135.539834512407;6167.414095660916;6197.380261973801;6199.194104766374;6201.937980620192;4885.780139893695;5136.666116702421;5142.07442660321;5145.691258524482;5148.536631703146;5011.84964451067;5039.118154322997;5041.218611115265;5060.0385926674;5077.898442031157;5006.699598024112;5024.196128619422;5030.2957585938;5055.873603159914;5063.088644044071;4967.155583550805;4985.202367621163;5021.774020169101;5022.949770502285;5036.899262014757;4568.291914753218;4593.379893714373;4610.147122782296;4638.397008388922;4646.4424269087585;6146.29317628474;6186.814395568095;6228.28374736902;6253.062040693932;6263.78076767311;5850.444207800252;5866.794662186803;5922.822315330547;5922.851928701773;5960.940390596125;6707.262201157869;6719.7312107515645;6720.966395167993;6722.327767223287;6738.966305168492;5143.588512918957;5157.994191132693;5161.3806343238675;5185.637005409623;5194.766235519411;6947.965260173667;7047.026832486912;7070.469714771361;7081.044059052014;7097.645117744144;5200.141976573898;5214.243934629458;5225.817096401684;5225.869353266123;5231.581473482112;5226.843194704165;5250.789968401221;5288.65623734456;5297.099493086184;5305.36335639718;5846.503047241032;5862;5869.8239052828485;5876.853078673067;5882.529397648178;6466.418022378009;6508.674566271668;6628.966855165693;6670.266270710218;6708.664566771642;1967.9606407871763;2000.9099590518463;2005.8295044921053;2006.2576846566865;2011.2658879508874;1240.3054289597853;1439.5465428390023;1442.170751817709;1458.4603696632314;1495.5588101510082;6092.786752463642;6145.8156255312415;6226.688665566794;6283.905741413882;6339.4611458025665;6650.434713049445;6661.333866613397;6679.599224046573;6746.494012949049;6816.863662726718;6183.536234782409;6207.5654704171175;6211.720472578745;6289.465395441439;6304.275008374004;3541.698955588804;3543.5216245806487;3563.6970857476886;3587.2601788132733;3612.4942508048894;6884.345987131214;6896.27589103147;6944.090375146875;7016;7074.327938845801;6832.180138383425;6862.828429289308;6870.484713646397;6977;7039.1553013638695;6952.270011648907;6985.306974815509;7014.438844892396;7073.92926070743;7080.575165490087;6633.507460821276;6698.933010669831;6751.527393082437;6808.795736127924;6821.693023813841;6704.932950670626;6732.427743641845;6756.966215168893;6805.4895882808;6836.726530938707;6960.756373526902;6988.40598549129;6995.6708225437005;7061.788146355618;7085.362317391459;5210;5265.236540701653;5293.523582877559;5334.226537152169;5344.144936810248;6721.966390168018;6759.256481787098;6828.1861287791;6864.553804002724;6867.866801977618;6519.282878883414;6519.6088234704175;6617.874961256694;6628.436582890508;6648.867022659617;6520.282768895323;6551.181102362205;6579.736810527525;6584.604923704593;6608.4052435281055;6384.585001974857;6403.583767055126;6444.677766111582;6458.289588145206;6504.991726282434;6106.6946652668075;6449.290578036506;6588.439982601532;6651.667416629052;6670.266270710121;6228.5951413158;6317.273513546001;6384.744610215539;6424.164858568543;6479.384458476437 16:40:30 INFO - PID 31060 | |5;yui.html;951.5004622573148;981.9067188617083;994.6767300627293;1000.1498726082831;1004.9447280399585;20593.176272949084;20813.895930520328;21268.46828829278;21356.679649805246;21385.786142138575;18651.347202847905;18969.52576185596;19073.237070517163;19156.712649310273;19168.42494725157;1038.3406536849118;1061.5647584490362;1068;1068.9305195162328;1069.1019543583388;1027.8149933012055;1064.5741703318695;1074.0226393981438;1074.8441475985978;1075.7310672331917;1106.9612563560277;1109.3177695717116;1110.4447776111945;1110.7334239782435;1111.4498323329963;1086.4947799273336;1098.2751384086544;1098.6703988803326;1103.7351035751362;1113.3208742666966;806.105223202245;806.5201205282847;806.9878951815728;807.0557447786105;807.1242701668697;20549.178032878666;21204.257850975297;21352;21551.245706400357;21558;23754.287371378887;23901.521969560687;24144.87927560351;24235.151769687975;24255.15106971265;18453.1696073677;18652.253909843726;18719.62560748791;18763.62472750551;18772;3828.942565861514;3836.405357169643;3882.864099756523;3908.2769687607597;3934.2328245992044;154612;157740;159483;161476.3852361473;163186;16787.66424671524;17101.315947361967;17165.055921924413;17240.827591724174;17278.22247998843;34138.82930585381;34433.311333773185;34725;34830.47754283688;34911.65088349135;9836.3606365586;9864.408135511892;9933.453660048623;9953.353032052893;9972.30193886421;4520.208963431386;4754.881127971828;4799.496052914464;4801.975990120027;4808.158572249842;6175.3515880832765;6247.219097612799;6270.372962703785;6283.340249273851;6290.24517057956;1136.6476392318407;1174.976500469986;1187.038498815962;1195.6891208285908;1198.532572296805;710.2045708806066;724.221461928429;727.3017902813252;731.7621772923821;750.3734381791156;13332.800007999887;13356.532521361705;13456.5290214842;14026.08830426019;14096.85903140976;748.2592233688717;750.6772088002211;754.1892465599502;755.5126943121655;773.9535627862347;6364.331745166691;6379.649119298483;6384.6169229846355;6421.325760795049;6552.868942621216;157090;157100;158135;161052;163368;141514;142266;143489;145622;150095;120872;121613;122405;123172.76827231792;123223;11768.411579421167;11920.523179073087;12080.879191208327;12296.631101066982;12411.62765117048;57099;58654.413455866605;58969.705151473965;58990;59846;75038.62480687561;76338;77368.61315693386;78020;78762.6061869687;16797.328106876077;17026.318947242467;17085.57286067858;17303.480895573153;17389.739153912447;18297.26810927548;18371.54071148232;18508.16713247934;18605.06974651289;18667.81332186715;18019;18112.45662630123;18140;18164;18254.634907301515;16543;16767.32930682808;16790.58023549421;16825.074620895968;16840.15799210059;17858.821411785717;17992.190351433954;17997.640047199246;18045;18228.81771182271;12200.572979946015;12225.69435764113;12243;12306.876931230574;12403.25580465175;11612.477438515118;11684.766304674029;11866.584669536525;11940.522379105087;11978.281303121841 16:40:30 INFO - PID 31060 | __end_tp_report 16:40:30 INFO - PID 31060 | __start_cc_report 16:40:30 INFO - PID 31060 | _x_x_mozilla_cycle_collect,245 16:40:30 INFO - PID 31060 | __end_cc_report 16:40:30 INFO - PID 31060 | __startTimestamp1506642030609__endTimestamp 16:40:30 INFO - PID 31060 | 16:40:30 INFO - PID 31060 | ------- Summary: start ------- 16:40:30 INFO - PID 31060 | Number of tests: 6 16:40:30 INFO - PID 31060 | 16:40:30 INFO - PID 31060 | [#0] dojo.html Cycles:165 Average:6227.21 Median:6478.22 stddev:751.92 (11.6%) stddev-sans-first:729.96 16:40:30 INFO - PID 31060 | Values: 3811.8 3814.5 3815.5 3832.8 3842.2 6055.6 6194.1 6217.5 6226.6 6317.3 6138.7 6143.8 6166.8 6167.8 6176.8 5501.6 5509.1 5513.1 5517.5 5584.6 5600.3 5622.8 5637.2 5660.6 5662.9 5636.9 5640.4 5701.0 5718.0 5743.9 5430.3 5459.4 5463.1 5472.7 5709.7 5578.3 5585.4 5609.2 5657.5 5660.2 6131.9 6174.6 6217.0 6254.3 6265.2 6729.4 6780.7 6816.6 6826.5 6840.5 6860.2 6890.0 6897.6 6898.1 6903.1 6200.0 6295.0 6297.7 6387.3 6389.0 6927.7 6987.7 7019.2 7026.0 7103.4 6647.7 6683.3 6734.4 6737.1 6755.5 6686.2 6768.9 6831.2 6856.0 6873.0 6655.4 6692.1 6776.4 6789.4 6800.9 6729.5 6794.4 6829.7 6830.2 6834.4 4936.6 4955.2 5030.5 5031.3 5036.0 4399.9 4421.1 4450.3 4466.6 4521.9 6318.5 6350.2 6351.5 6355.7 6357.0 6764.2 6767.6 6770.5 6839.8 6851.3 6241.7 6281.7 6320.0 6333.9 6358.8 5317.5 5382.6 5383.8 5386.4 5386.5 6819.0 6827.1 6835.8 6836.4 6884.2 6691.4 6713.1 6791.2 6795.6 6804.1 6947.9 6968.4 6986.4 7071.0 7078.4 6474.1 6563.4 6663.7 6711.6 6790.0 6775.1 6791.7 6837.6 6844.6 6862.1 6540.9 6551.1 6640.7 6642.8 6646.8 6498.9 6505.7 6546.9 6594.4 6613.9 6244.2 6357.3 6436.2 6518.1 6554.6 6672.6 6682.6 6692.2 6704.6 6722.2 6295.5 6387.2 6478.2 6478.2 6530.4 16:40:30 INFO - PID 31060 | 16:40:30 INFO - PID 31060 | [#1] ext.html Cycles:180 Average:43902.11 Median:37771.12 stddev:66556.14 (176.2%) stddev-sans-first:66664.71 16:40:30 INFO - PID 31060 | Values: 949.7 970.6 995.8 997.3 1001.3 43759.6 44686.3 44730.3 44784.3 44827.1 41423.2 41770.8 44436.6 45085.5 45122.1 8400.5 8547.7 8549.4 8550.3 8550.5 8336.9 8381.7 8426.7 8438.5 8463.0 8461.7 8478.9 8486.1 8488.9 8504.1 8408.7 8426.0 8431.6 8437.4 8460.5 8243.5 8290.0 8311.6 8312.5 8322.4 41169.8 41291.6 41313.6 41435.0 41699.8 3621.1 3626.6 3638.9 3650.7 3655.8 33906.2 34002.5 34104.0 34124.8 34150.8 5314.3 5361.2 5375.2 5379.5 5449.9 218366.0 221635.0 223051.0 226218.0 236276.0 55172.7 55501.7 55791.2 55849.7 56001.2 57201.7 57449.1 57657.0 57702.7 57714.7 16419.7 16645.8 16650.8 16653.7 16896.2 13646.8 13675.9 13677.5 13681.8 13691.5 14515.3 14607.3 14697.6 14706.9 14876.0 5148.6 5225.7 5249.6 5357.9 5389.3 1083.6 1104.1 1104.3 1110.9 1119.1 25760.6 25931.9 25941.4 25992.2 26059.5 1197.2 1201.9 1214.0 1223.2 1225.7 13666.2 13668.1 13719.0 13794.8 13974.5 295783.0 297486.0 298299.0 298329.0 298591.0 58502.7 58643.0 58679.4 58860.7 58865.7 1484.7 1513.2 1521.2 1532.9 1543.2 24909.6 24943.1 25076.0 25093.1 25101.0 41326.4 41772.4 41792.0 41811.8 41824.8 237986.0 238059.0 238691.0 238702.0 239055.0 37364.8 39536.6 40310.0 40341.4 40365.8 48992.3 50174.7 50525.7 50578.0 50700.2 37037.0 37393.3 37421.8 37479.0 38063.2 36825.0 36939.3 37022.3 37103.4 37237.0 42989.0 43172.4 43694.8 43697.8 43757.8 36073.0 36129.6 36333.0 36354.2 36668.0 26315.9 26960.9 27004.0 27151.6 27274.3 16:40:30 INFO - PID 31060 | 16:40:30 INFO - PID 31060 | [#2] jquery.html Cycles:180 Average:40000.54 Median:12864.82 stddev:102590.75 (797.5%) stddev-sans-first:102835.90 16:40:30 INFO - PID 31060 | Values: 605.6 607.7 620.5 625.5 646.7 23253.0 23506.5 24069.3 24074.8 24079.2 24575.4 25711.5 25740.4 25741.4 25792.0 6353.4 6482.4 6485.0 6485.3 6509.9 6458.4 6691.3 6725.9 6728.7 6733.1 6609.3 6640.2 6651.8 6679.3 6684.4 6403.5 6578.5 6606.3 6638.6 6675.7 6220.3 6224.5 6239.5 6239.7 6244.3 30022.2 30485.5 30562.8 30621.4 30647.0 2308.9 2314.6 2341.6 2342.0 2348.4 19181.7 20312.9 20352.8 20441.4 20514.2 2550.3 2553.8 2592.6 2605.4 2615.6 137098.0 142565.3 152338.0 158721.0 159415.0 15077.4 15300.8 15579.3 15871.5 16225.3 24079.5 24126.0 24128.9 24156.9 24209.0 10343.5 10631.4 10639.7 10646.4 10698.9 9707.0 9715.8 9767.9 9769.8 9946.1 9133.4 9147.0 9238.0 9529.9 9644.1 2747.7 2780.6 2813.2 2908.6 2970.9 1022.2 1022.8 1048.2 1057.4 1060.4 20829.0 20915.8 21135.9 21241.5 21568.8 1100.1 1103.3 1104.1 1109.6 1120.6 11419.3 11459.7 11564.2 11665.9 11675.2 600478.0 610171.0 615539.0 615811.0 618785.0 131279.3 135821.0 137237.0 138868.0 139126.3 1198.9 1201.4 1233.8 1236.1 1239.9 18373.8 18750.5 18806.8 18871.8 18938.9 64562.4 64759.4 65596.7 65725.3 67230.0 82106.6 82558.0 82680.6 83310.2 83875.2 15281.0 15335.4 15368.5 15545.1 15569.8 23237.8 23377.9 23388.0 23502.3 23617.1 16519.6 16631.2 16780.0 17677.4 19101.8 17174.1 17583.4 17606.3 17737.1 17842.0 19592.5 19643.7 19868.1 19916.6 20025.7 15183.5 15331.0 15340.5 15340.8 15348.0 18615.3 18745.4 18843.2 18902.3 18903.2 16:40:30 INFO - PID 31060 | 16:40:30 INFO - PID 31060 | [#3] mootools.html Cycles:180 Average:10453.81 Median:2714.08 stddev:7716.39 (284.3%) stddev-sans-first:7707.97 16:40:30 INFO - PID 31060 | Values: 1386.5 1455.6 1459.6 1507.5 1508.1 16747.9 17277.4 17348.6 17371.3 17412.2 16933.1 17136.9 17168.3 17205.5 17235.0 2787.3 2797.9 2825.8 2829.1 2845.0 2640.2 2703.9 2717.0 2717.9 2718.9 2646.2 2702.5 2706.7 2730.7 2734.4 2711.2 2721.4 2729.3 2736.2 2736.9 2729.7 2769.6 2770.1 2780.3 2783.0 16689.6 16995.0 17069.2 17074.5 17091.0 7992.8 8229.8 8275.3 8282.5 8286.1 5408.1 5500.4 5585.3 5654.6 5765.3 2143.9 2147.5 2174.7 2222.2 2230.7 30532.8 30633.4 30748.8 30785.4 30813.5 7080.5 7177.6 7180.4 7380.8 7404.1 20004.5 20164.2 20225.9 20241.6 20246.4 8237.1 8362.5 8399.8 8404.2 8423.6 7274.3 7649.2 7657.7 7693.8 7725.4 5618.9 5748.3 5808.5 5858.1 5887.4 1693.7 1722.9 1726.7 1727.8 1738.1 885.1 900.0 917.6 919.2 919.8 8645.4 8688.9 8731.7 8833.0 8840.5 784.6 786.7 826.3 835.5 844.7 5761.4 6119.6 6204.8 6269.5 6306.4 24399.3 24606.5 24904.0 25115.0 25185.9 23975.5 24113.8 24869.5 25028.6 25189.4 11810.5 12017.9 12030.8 12056.5 12060.8 9538.4 9701.4 9853.8 9859.2 9994.7 20453.0 20464.5 20745.7 20842.7 20858.4 21718.3 21729.8 21796.9 22110.4 22308.8 12832.6 12954.7 12989.0 13035.7 13035.9 15272.0 15439.8 15532.7 15630.2 15654.8 11775.4 11866.1 12116.8 12204.6 12341.2 11918.6 12046.2 12404.1 12526.0 12561.1 11240.2 11285.5 11392.9 11401.0 11406.4 8667.3 8737.4 8739.7 8829.3 8843.7 8713.3 8812.8 8857.0 8865.8 8875.0 16:40:30 INFO - PID 31060 | 16:40:30 INFO - PID 31060 | [#4] prototype.html Cycles:180 Average:5663.29 Median:6209.81 stddev:1550.15 (25.0%) stddev-sans-first:1506.25 16:40:30 INFO - PID 31060 | Values: 550.3 556.2 572.1 580.3 580.4 6139.9 6167.4 6198.6 6207.9 6226.0 6135.5 6167.4 6197.4 6199.2 6201.9 4885.8 5136.7 5142.1 5145.7 5148.5 5011.8 5039.1 5041.2 5060.0 5077.9 5006.7 5024.2 5030.3 5055.9 5063.1 4967.2 4985.2 5021.8 5022.9 5036.9 4568.3 4593.4 4610.1 4638.4 4646.4 6146.3 6186.8 6228.3 6253.1 6263.8 5850.4 5866.8 5922.8 5922.9 5960.9 6707.3 6719.7 6721.0 6722.3 6739.0 5143.6 5158.0 5161.4 5185.6 5194.8 6948.0 7047.0 7070.5 7081.0 7097.6 5200.1 5214.2 5225.8 5225.9 5231.6 5226.8 5250.8 5288.7 5297.1 5305.4 5846.5 5862.0 5869.8 5876.9 5882.5 6466.4 6508.7 6629.0 6670.3 6708.7 1968.0 2000.9 2005.8 2006.3 2011.3 1240.3 1439.5 1442.2 1458.5 1495.6 6092.8 6145.8 6226.7 6283.9 6339.5 6650.4 6661.3 6679.6 6746.5 6816.9 6183.5 6207.6 6211.7 6289.5 6304.3 3541.7 3543.5 3563.7 3587.3 3612.5 6884.3 6896.3 6944.1 7016.0 7074.3 6832.2 6862.8 6870.5 6977.0 7039.2 6952.3 6985.3 7014.4 7073.9 7080.6 6633.5 6698.9 6751.5 6808.8 6821.7 6704.9 6732.4 6757.0 6805.5 6836.7 6960.8 6988.4 6995.7 7061.8 7085.4 5210.0 5265.2 5293.5 5334.2 5344.1 6722.0 6759.3 6828.2 6864.6 6867.9 6519.3 6519.6 6617.9 6628.4 6648.9 6520.3 6551.2 6579.7 6584.6 6608.4 6384.6 6403.6 6444.7 6458.3 6505.0 6106.7 6449.3 6588.4 6651.7 6670.3 6228.6 6317.3 6384.7 6424.2 6479.4 16:40:30 INFO - PID 31060 | 16:40:30 INFO - PID 31060 | [#5] yui.html Cycles:180 Average:29318.45 Median:18241.73 stddev:44593.88 (244.5%) stddev-sans-first:44668.11 16:40:30 INFO - PID 31060 | Values: 951.5 981.9 994.7 1000.1 1004.9 20593.2 20813.9 21268.5 21356.7 21385.8 18651.3 18969.5 19073.2 19156.7 19168.4 1038.3 1061.6 1068.0 1068.9 1069.1 1027.8 1064.6 1074.0 1074.8 1075.7 1107.0 1109.3 1110.4 1110.7 1111.4 1086.5 1098.3 1098.7 1103.7 1113.3 806.1 806.5 807.0 807.1 807.1 20549.2 21204.3 21352.0 21551.2 21558.0 23754.3 23901.5 24144.9 24235.2 24255.2 18453.2 18652.3 18719.6 18763.6 18772.0 3828.9 3836.4 3882.9 3908.3 3934.2 154612.0 157740.0 159483.0 161476.4 163186.0 16787.7 17101.3 17165.1 17240.8 17278.2 34138.8 34433.3 34725.0 34830.5 34911.7 9836.4 9864.4 9933.5 9953.4 9972.3 4520.2 4754.9 4799.5 4802.0 4808.2 6175.4 6247.2 6270.4 6283.3 6290.2 1136.6 1175.0 1187.0 1195.7 1198.5 710.2 724.2 727.3 731.8 750.4 13332.8 13356.5 13456.5 14026.1 14096.9 748.3 750.7 754.2 755.5 774.0 6364.3 6379.6 6384.6 6421.3 6552.9 157090.0 157100.0 158135.0 161052.0 163368.0 141514.0 142266.0 143489.0 145622.0 150095.0 120872.0 121613.0 122405.0 123172.8 123223.0 11768.4 11920.5 12080.9 12296.6 12411.6 57099.0 58654.4 58969.7 58990.0 59846.0 75038.6 76338.0 77368.6 78020.0 78762.6 16797.3 17026.3 17085.6 17303.5 17389.7 18297.3 18371.5 18508.2 18605.1 18667.8 18019.0 18112.5 18140.0 18164.0 18254.6 16543.0 16767.3 16790.6 16825.1 16840.2 17858.8 17992.2 17997.6 18045.0 18228.8 12200.6 12225.7 12243.0 12306.9 12403.3 11612.5 11684.8 11866.6 11940.5 11978.3 16:40:30 INFO - PID 31060 | -------- Summary: end -------- 16:40:30 INFO - PID 31060 | 16:40:31 INFO - TEST-INFO | 31060: exit 0 16:40:31 INFO - TEST-OK | dromaeo_css | took 1106970ms 16:40:31 INFO - TEST-START | kraken 16:40:31 INFO - Initialising browser for kraken test... 16:40:31 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:46612/getInfo.html -profile /tmp/tmpdPAq0r/profile 16:40:31 INFO - TEST-INFO | started process 31424 (/builds/slave/test/build/application/firefox/firefox http://localhost:46612/getInfo.html) 16:40:36 INFO - TEST-INFO | 31424: exit 0 16:40:36 INFO - Browser initialized. 16:40:36 INFO - Running cycle 1/1 for kraken test... 16:40:36 INFO - Using env: {'DISPLAY': ':0', 16:40:36 INFO - 'HOME': '/home/cltbld', 16:40:36 INFO - 'JSGC_DISABLE_POISONING': '1', 16:40:36 INFO - 'LANG': 'en_US.UTF-8', 16:40:36 INFO - 'LANGUAGE': 'en_US:en', 16:40:36 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 16:40:36 INFO - 'LOGNAME': 'cltbld', 16:40:36 INFO - 'MAIL': '/var/mail/cltbld', 16:40:36 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:40:36 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:40:36 INFO - 'MOZ_CRASHREPORTER': '1', 16:40:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:40:36 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 16:40:36 INFO - 'MOZ_NO_REMOTE': '1', 16:40:36 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:40:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:40:36 INFO - 'NO_EM_RESTART': '1', 16:40:36 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:40:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:40:36 INFO - 'PWD': '/builds/slave/test', 16:40:36 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:40:36 INFO - 'RUST_BACKTRACE': 'full', 16:40:36 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 16:40:36 INFO - 'SHELL': '/bin/bash', 16:40:36 INFO - 'SHLVL': '1', 16:40:36 INFO - 'TERM': 'linux', 16:40:36 INFO - 'TMOUT': '86400', 16:40:36 INFO - 'USER': 'cltbld', 16:40:36 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1506639858.562470-1115674979', 16:40:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:40:36 INFO - '_': '/tools/buildbot/bin/python'} 16:40:36 INFO - TEST-INFO | started process 31662 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpdPAq0r/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 16:40:37 INFO - PID 31662 | 16:40:37 INFO - PID 31662 | (/builds/slave/test/build/application/firefox/firefox:31712): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 16:40:37 INFO - PID 31662 | 16:40:47 INFO - PID 31662 | RSS: Main: 144740352 16:40:47 INFO - PID 31662 | 16:40:50 INFO - PID 31662 | Cycle 1(1): loaded http://localhost:46612/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:46612/tests/kraken/driver.html?testName=audio-beat-detection) 16:40:50 INFO - PID 31662 | RSS: Main: 151703552 16:40:50 INFO - PID 31662 | 16:40:53 INFO - PID 31662 | Cycle 1(1): loaded http://localhost:46612/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:46612/tests/kraken/driver.html?testName=audio-dft) 16:40:54 INFO - PID 31662 | RSS: Main: 152293376 16:40:54 INFO - PID 31662 | 16:40:57 INFO - PID 31662 | Cycle 1(1): loaded http://localhost:46612/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:46612/tests/kraken/driver.html?testName=audio-fft) 16:40:57 INFO - PID 31662 | RSS: Main: 147668992 16:40:57 INFO - PID 31662 | 16:40:59 INFO - PID 31662 | Cycle 1(1): loaded http://localhost:46612/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:46612/tests/kraken/driver.html?testName=audio-oscillator) 16:41:00 INFO - PID 31662 | RSS: Main: 148303872 16:41:00 INFO - PID 31662 | 16:41:02 INFO - PID 31662 | Cycle 1(1): loaded http://localhost:46612/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:46612/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:41:02 INFO - PID 31662 | RSS: Main: 148475904 16:41:02 INFO - PID 31662 | 16:41:06 INFO - PID 31662 | Cycle 1(1): loaded http://localhost:46612/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:46612/tests/kraken/driver.html?testName=imaging-darkroom) 16:41:06 INFO - PID 31662 | RSS: Main: 148373504 16:41:06 INFO - PID 31662 | 16:41:10 INFO - PID 31662 | Cycle 1(1): loaded http://localhost:46612/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:46612/tests/kraken/driver.html?testName=imaging-desaturate) 16:41:10 INFO - PID 31662 | RSS: Main: 149909504 16:41:10 INFO - PID 31662 | 16:41:13 INFO - PID 31662 | Cycle 1(1): loaded http://localhost:46612/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:46612/tests/kraken/driver.html?testName=json-parse-financial) 16:41:14 INFO - PID 31662 | RSS: Main: 151384064 16:41:14 INFO - PID 31662 | 16:41:15 INFO - PID 31662 | Cycle 1(1): loaded http://localhost:46612/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:46612/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:41:16 INFO - PID 31662 | RSS: Main: 151289856 16:41:16 INFO - PID 31662 | 16:41:18 INFO - PID 31662 | Cycle 1(1): loaded http://localhost:46612/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:46612/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:41:18 INFO - PID 31662 | RSS: Main: 151179264 16:41:18 INFO - PID 31662 | 16:41:20 INFO - PID 31662 | Cycle 1(1): loaded http://localhost:46612/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:46612/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:41:21 INFO - PID 31662 | RSS: Main: 148353024 16:41:21 INFO - PID 31662 | 16:41:23 INFO - PID 31662 | Cycle 1(1): loaded http://localhost:46612/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:46612/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:41:24 INFO - PID 31662 | RSS: Main: 148127744 16:41:24 INFO - PID 31662 | 16:41:27 INFO - PID 31662 | Cycle 1(1): loaded http://localhost:46612/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:46612/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:41:27 INFO - PID 31662 | RSS: Main: 148815872 16:41:27 INFO - PID 31662 | 16:41:29 INFO - PID 31662 | Cycle 1(1): loaded http://localhost:46612/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:46612/tests/kraken/driver.html?testName=ai-astar) 16:41:29 INFO - PID 31662 | RSS: Main: 150409216 16:41:29 INFO - PID 31662 | 16:41:29 INFO - PID 31662 | __start_tp_report 16:41:29 INFO - PID 31662 | _x_x_mozilla_page_load 16:41:29 INFO - PID 31662 | _x_x_mozilla_page_load_details 16:41:29 INFO - PID 31662 | |i|pagename|runs| 16:41:29 INFO - PID 31662 | |0;ai-astar;108;108;107;105;105;108;107;107;106;106 16:41:29 INFO - PID 31662 | |1;audio-beat-detection;110;111;120;112;112;125;114;113;138;112 16:41:29 INFO - PID 31662 | |2;audio-dft;164;167;164;167;162;164;162;163;166;170 16:41:29 INFO - PID 31662 | |3;audio-fft;73;73;73;74;74;73;72;75;73;74 16:41:29 INFO - PID 31662 | |4;audio-oscillator;78;78;78;79;78;78;129;78;80;78 16:41:29 INFO - PID 31662 | |5;imaging-gaussian-blur;113;114;109;114;113;113;110;114;113;114 16:41:29 INFO - PID 31662 | |6;imaging-darkroom;150;150;150;149;150;150;149;150;149;150 16:41:29 INFO - PID 31662 | |7;imaging-desaturate;99;94;94;94;94;95;94;94;95;94 16:41:29 INFO - PID 31662 | |8;json-parse-financial;56;46;43;42;44;44;44;45;45;45 16:41:29 INFO - PID 31662 | |9;json-stringify-tinderbox;38;38;38;38;38;38;39;38;39;38 16:41:29 INFO - PID 31662 | |10;stanford-crypto-aes;69;68;70;69;69;68;68;70;73;70 16:41:29 INFO - PID 31662 | |11;stanford-crypto-ccm;109;109;105;104;106;111;103;108;104;103 16:41:29 INFO - PID 31662 | |12;stanford-crypto-pbkdf2;152;146;144;143;151;146;146;151;146;150 16:41:29 INFO - PID 31662 | |13;stanford-crypto-sha256-iterative;60;59;59;61;58;55;59;62;57;63 16:41:29 INFO - PID 31662 | __end_tp_report 16:41:29 INFO - PID 31662 | __start_cc_report 16:41:29 INFO - PID 31662 | _x_x_mozilla_cycle_collect,711 16:41:29 INFO - PID 31662 | __end_cc_report 16:41:29 INFO - PID 31662 | __startTimestamp1506642089498__endTimestamp 16:41:29 INFO - PID 31662 | 16:41:29 INFO - PID 31662 | ------- Summary: start ------- 16:41:29 INFO - PID 31662 | Number of tests: 14 16:41:29 INFO - PID 31662 | 16:41:29 INFO - PID 31662 | [#0] ai-astar Cycles:10 Average:106.70 Median:107.00 stddev:1.16 (1.1%) stddev-sans-first:1.13 16:41:29 INFO - PID 31662 | Values: 108.0 108.0 107.0 105.0 105.0 108.0 107.0 107.0 106.0 106.0 16:41:29 INFO - PID 31662 | 16:41:29 INFO - PID 31662 | [#1] audio-beat-detection Cycles:10 Average:116.70 Median:113.50 stddev:8.81 (7.8%) stddev-sans-first:9.00 16:41:29 INFO - PID 31662 | Values: 110.0 111.0 120.0 112.0 112.0 125.0 114.0 113.0 138.0 112.0 16:41:29 INFO - PID 31662 | 16:41:29 INFO - PID 31662 | [#2] audio-dft Cycles:10 Average:164.90 Median:165.00 stddev:2.56 (1.6%) stddev-sans-first:2.69 16:41:29 INFO - PID 31662 | Values: 164.0 167.0 164.0 167.0 162.0 164.0 162.0 163.0 166.0 170.0 16:41:29 INFO - PID 31662 | 16:41:29 INFO - PID 31662 | [#3] audio-fft Cycles:10 Average:73.40 Median:73.50 stddev:0.84 (1.1%) stddev-sans-first:0.88 16:41:29 INFO - PID 31662 | Values: 73.0 73.0 73.0 74.0 74.0 73.0 72.0 75.0 73.0 74.0 16:41:29 INFO - PID 31662 | 16:41:29 INFO - PID 31662 | [#4] audio-oscillator Cycles:10 Average:83.40 Median:78.00 stddev:16.04 (20.6%) stddev-sans-first:16.89 16:41:29 INFO - PID 31662 | Values: 78.0 78.0 78.0 79.0 78.0 78.0 129.0 78.0 80.0 78.0 16:41:29 INFO - PID 31662 | 16:41:29 INFO - PID 31662 | [#5] imaging-gaussian-blur Cycles:10 Average:112.70 Median:113.50 stddev:1.77 (1.6%) stddev-sans-first:1.87 16:41:29 INFO - PID 31662 | Values: 113.0 114.0 109.0 114.0 113.0 113.0 110.0 114.0 113.0 114.0 16:41:29 INFO - PID 31662 | 16:41:29 INFO - PID 31662 | [#6] imaging-darkroom Cycles:10 Average:149.70 Median:150.00 stddev:0.48 (0.3%) stddev-sans-first:0.50 16:41:29 INFO - PID 31662 | Values: 150.0 150.0 150.0 149.0 150.0 150.0 149.0 150.0 149.0 150.0 16:41:29 INFO - PID 31662 | 16:41:29 INFO - PID 31662 | [#7] imaging-desaturate Cycles:10 Average:94.70 Median:94.00 stddev:1.57 (1.7%) stddev-sans-first:0.44 16:41:29 INFO - PID 31662 | Values: 99.0 94.0 94.0 94.0 94.0 95.0 94.0 94.0 95.0 94.0 16:41:29 INFO - PID 31662 | 16:41:29 INFO - PID 31662 | [#8] json-parse-financial Cycles:10 Average:45.40 Median:45.00 stddev:3.89 (8.7%) stddev-sans-first:1.20 16:41:29 INFO - PID 31662 | Values: 56.0 46.0 43.0 42.0 44.0 44.0 44.0 45.0 45.0 45.0 16:41:29 INFO - PID 31662 | 16:41:29 INFO - PID 31662 | [#9] json-stringify-tinderbox Cycles:10 Average:38.20 Median:38.00 stddev:0.42 (1.1%) stddev-sans-first:0.44 16:41:29 INFO - PID 31662 | Values: 38.0 38.0 38.0 38.0 38.0 38.0 39.0 38.0 39.0 38.0 16:41:29 INFO - PID 31662 | 16:41:29 INFO - PID 31662 | [#10] stanford-crypto-aes Cycles:10 Average:69.40 Median:69.50 stddev:1.51 (2.2%) stddev-sans-first:1.59 16:41:29 INFO - PID 31662 | Values: 69.0 68.0 70.0 69.0 69.0 68.0 68.0 70.0 73.0 70.0 16:41:29 INFO - PID 31662 | 16:41:29 INFO - PID 31662 | [#11] stanford-crypto-ccm Cycles:10 Average:106.20 Median:107.00 stddev:2.86 (2.7%) stddev-sans-first:2.85 16:41:29 INFO - PID 31662 | Values: 109.0 109.0 105.0 104.0 106.0 111.0 103.0 108.0 104.0 103.0 16:41:29 INFO - PID 31662 | 16:41:29 INFO - PID 31662 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:147.50 Median:148.00 stddev:3.21 (2.2%) stddev-sans-first:2.96 16:41:29 INFO - PID 31662 | Values: 152.0 146.0 144.0 143.0 151.0 146.0 146.0 151.0 146.0 150.0 16:41:29 INFO - PID 31662 | 16:41:29 INFO - PID 31662 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:59.30 Median:59.50 stddev:2.36 (4.0%) stddev-sans-first:2.49 16:41:29 INFO - PID 31662 | Values: 60.0 59.0 59.0 61.0 58.0 55.0 59.0 62.0 57.0 63.0 16:41:29 INFO - PID 31662 | -------- Summary: end -------- 16:41:29 INFO - PID 31662 | 16:41:29 INFO - PID 31662 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 16:41:30 INFO - TEST-INFO | 31662: exit 0 16:41:30 INFO - TEST-OK | kraken | took 59039ms 16:41:30 INFO - SUITE-END | took 1166s 16:41:30 INFO - Completed test suite (00:19:26) 16:41:30 INFO - javascript benchmark 16:41:30 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 9092.181270777513, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3811.752236104655, 3814.5041144651195, 3815.503984482017, 3832.8466861325555, 3842.1739326044894, 6055.606385584934, 6194.070889366597, 6217.533684973628, 6226.5952713073675, 6317.336679648634, 6138.693065346726, 6143.846403839906, 6166.814995550129, 6167.7841275555365, 6176.814695559129, 5501.614886957915, 5509.063459211943, 5513.09034009388, 5517.503424691777, 5584.6090773645865, 5600.27196464459, 5622.803201887935, 5637.238972738669, 5660.60375773696, 5662.943370566303, 5636.859078523029, 5640.379558248589, 5700.9714951425185, 5718.0, 5743.942560574404, 5430.294061771984, 5459.426760190163, 5463.125899856004, 5472.671639701631, 5709.71451427429, 5578.252280315895, 5585.413531579207, 5609.186667933127, 5657.490825825655, 5660.2075709400715, 6131.87736245277, 6174.567780255384, 6217.036359364306, 6254.312025677172, 6265.216847894013, 6729.427998620124, 6780.72877084916, 6816.591004539743, 6826.488013399015, 6840.521163518556, 6860.245373008965, 6889.965550172267, 6897.55165914219, 6898.1377327834025, 6903.068085808452, 6200.0079987202735, 6294.968525157391, 6297.748090076391, 6387.2654644715985, 6388.968055159695, 6927.68825402848, 6987.650617469106, 7019.192792828788, 7025.964870175617, 7103.431725461951, 6647.700853461606, 6683.298253683391, 6734.393904548614, 6737.056812046318, 6755.459563234929, 6686.231083425469, 6768.864622707617, 6831.180258369028, 6856.0, 6873.037774711544, 6655.43428808547, 6692.063111164441, 6776.356246156607, 6789.388954993976, 6800.897986530206, 6729.461643068543, 6794.3545363190415, 6829.726810927507, 6830.180378354629, 6834.419074378634, 4936.555709986119, 4955.157623204064, 5030.471800460972, 5031.270465782514, 5035.974820125876, 4399.890002749893, 4421.071574969293, 4450.310201918708, 4466.620337271368, 4521.886952826206, 6318.494520438354, 6350.237971443456, 6351.460125889259, 6355.682215889187, 6356.968215158895, 6764.222114456802, 6767.593944363354, 6770.458363330922, 6839.794806155824, 6851.314868513109, 6241.687915604201, 6281.7173227204885, 6319.96840015797, 6333.904991425133, 6358.809235722936, 5317.468253174729, 5382.569394448435, 5383.757730902119, 5386.434424385461, 5386.461353864661, 6819.011243369684, 6827.112475378169, 6835.829104272433, 6836.418904393083, 6884.173899132036, 6691.397774200248, 6713.12729345182, 6791.219009813737, 6795.558288711218, 6804.11546498952, 6947.930520694728, 6968.442524598121, 6986.441084713109, 7071.0, 7078.362947334765, 6474.125992990886, 6563.409293163638, 6663.666816659244, 6711.597304161766, 6790.015447760146, 6775.051492790914, 6791.7283308669075, 6837.555558888656, 6844.555103918229, 6862.142232220973, 6540.869182616416, 6551.050097735706, 6640.667966601554, 6642.800715978528, 6646.83382915431, 6498.935010649833, 6505.7072431741635, 6546.934530654823, 6594.406503414716, 6613.933860661332, 6244.219472565929, 6357.300696923242, 6436.195475565554, 6518.120053792867, 6554.606723596408, 6672.633005184567, 6682.565633233824, 6692.163479565054, 6704.631245281361, 6722.226943901514, 6295.527835412454, 6387.169667943138, 6478.190226221723, 6478.222613286436, 6530.412262896362], "value": 6175.186656268559, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [949.7055912667074, 970.6263088710847, 995.7759504111572, 997.2520609542843, 1001.2640709078826, 43759.56240437599, 44686.32970505445, 44730.32904506435, 44784.3282350765, 44827.103457930905, 41423.17153656925, 41770.79114604423, 44436.55563444365, 45085.54914450854, 45122.097558048816, 8400.45397049193, 8547.658093676246, 8549.444286121414, 8550.273226775731, 8550.52972086535, 8336.916630833704, 8381.664733410656, 8426.662933482654, 8438.451500652469, 8463.0, 8461.746147615582, 8478.915210847905, 8486.0665326814, 8488.915110848906, 8504.064552899175, 8408.663653453854, 8425.957870210641, 8431.578421078983, 8437.367197460153, 8460.49237045773, 8243.546604936726, 8290.0, 8311.584420778936, 8312.501249925024, 8322.417430779848, 41169.79415102935, 41291.58708412937, 41313.58686413128, 41435.0, 41699.7915010426, 3621.0947263184203, 3626.637336266352, 3638.945415818738, 3650.7261955353188, 3655.817209139532, 33906.152346191295, 34002.48996265058, 34104.0, 34124.82937585321, 34150.82924585361, 5314.282571852789, 5361.169018802092, 5375.193720941851, 5379.462053794589, 5449.945500545023, 218366.0, 221635.0, 223051.0, 226218.0, 236275.99999999828, 55172.72413637906, 55501.722491387285, 55791.16313255223, 55849.72075139598, 56001.15998260029, 57201.71399143061, 57449.13826292609, 57657.0, 57702.7114864423, 57714.71142644344, 16419.6716065678, 16645.83354166443, 16650.83349166493, 16653.666926661397, 16896.239669214916, 13646.795298070538, 13675.931620341835, 13677.521286754916, 13681.794773078413, 13691.452341906412, 14515.274236288355, 14607.269636518131, 14697.55907322782, 14706.852931470763, 14876.0, 5148.639595228298, 5225.738713064407, 5249.553787928069, 5357.946420535744, 5389.251431878464, 1083.5990683447176, 1104.0946423932298, 1104.2987702808775, 1110.8500352452477, 1119.0935342372704, 25760.613590796154, 25931.870340648176, 25941.351466213495, 25992.22023339303, 26059.478810424065, 1197.1918954705538, 1201.9158658893969, 1213.9514419423124, 1223.1926928227506, 1225.737490384905, 13666.248356340497, 13668.111572747739, 13719.0, 13794.793078103836, 13974.510892118727, 295783.0, 297486.0, 298299.0, 298329.0, 298591.0, 58502.70748646314, 58643.0, 58679.41320586825, 58860.70569647125, 58865.70567147137, 1484.6659501612266, 1513.182881244138, 1521.246982743549, 1532.923353832304, 1543.236098131432, 24909.626355605043, 24943.126990555244, 25076.0, 25093.121740738985, 25101.0, 41326.38010429906, 41772.373414399415, 41792.0, 41811.7909410451, 41824.79087604542, 237986.0, 238059.0, 238691.0, 238702.0, 239055.0, 37364.813175933945, 39536.60463395329, 40310.0, 40341.39487907625, 40365.79817100896, 48992.265116024, 50174.74912625414, 50525.74737126291, 50578.0, 50700.23949640832, 37037.0, 37393.25213495769, 37421.81289093537, 37479.0, 38063.23873522569, 36825.0, 36939.26121477609, 37022.259554809294, 37103.44344834776, 37237.0, 42989.0, 43172.352414714434, 43694.78152609344, 43697.78151109224, 43757.78121109501, 36073.0, 36129.638703612625, 36333.0, 36354.23245821219, 36668.0, 26315.86842065854, 26960.865195673898, 27004.0, 27151.59272610873, 27274.318142046606], "value": 18986.53451780432, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [605.551891600216, 607.7113371148702, 620.4725982914522, 625.5308518611042, 646.6831252686184, 23253.0, 23506.529869402602, 24069.27792166238, 24074.75925240747, 24079.15722949697, 24575.385615359624, 25711.485770284584, 25740.356491087732, 25741.35646608831, 25792.0, 6353.428191462779, 6482.384173503533, 6485.027245913127, 6485.286618471988, 6509.902351464708, 6458.354164583528, 6691.3308669133185, 6725.932740672604, 6728.6635668216395, 6733.057371967904, 6609.3390660934, 6640.203175618933, 6651.833704157387, 6679.29867363927, 6684.364985326386, 6403.48772098231, 6578.539502234846, 6606.339366063403, 6638.5684930479365, 6675.66621668917, 6220.315765265817, 6224.470919971808, 6239.469645080174, 6239.719212635397, 6244.281907580641, 30022.249443763863, 30485.542716859265, 30562.847185764153, 30621.38757224843, 30647.0, 2308.86146831189, 2314.618088015475, 2341.6487526870937, 2342.0, 2348.3542025942834, 19181.712274315898, 20312.898435507876, 20352.79647203524, 20441.38675839727, 20514.179432822668, 2550.3040478082053, 2553.795696344288, 2592.5592649249675, 2605.387733882536, 2615.5815069588966, 137098.0, 142565.28717356452, 152338.0, 158721.0, 159414.99999999884, 15077.396904123712, 15300.770488442682, 15579.298931548108, 15871.52385428439, 16225.2698628562, 24079.51840963171, 24126.0, 24128.87935560346, 24156.87921560381, 24209.0, 10343.482825858677, 10631.362118272928, 10639.680809575726, 10646.361218326925, 10698.946505267424, 9706.951465242628, 9715.805683886283, 9767.853482197772, 9769.755756106155, 9946.104850563339, 9133.406328588753, 9147.0, 9237.953810230905, 9529.857052144223, 9644.132028117503, 2747.738964798341, 2780.638516992778, 2813.212300555848, 2908.5927970084203, 2970.8663110160096, 1022.1669339488293, 1022.7596514819012, 1048.2295512798087, 1057.4289883462843, 1060.4485667452882, 20829.0, 20915.790842091385, 21135.8943205283, 21241.468963275733, 21568.784312156677, 1100.1308965916996, 1103.2883789955595, 1104.116706634705, 1109.5506319940437, 1120.56298043763, 11419.257748246337, 11459.713507162387, 11564.190506664621, 11665.941670291764, 11675.241109327866, 600478.0, 610171.0, 615539.0, 615811.0, 618785.0, 131279.34360328136, 135821.0, 137237.0, 138868.0, 139126.30436847953, 1198.9460474278678, 1201.4095745999164, 1233.8149277608431, 1236.097648716442, 1239.876012398753, 18373.81626183721, 18750.53123671919, 18806.811931880507, 18871.811281887556, 18938.905305473385, 64562.35437645563, 64759.35240647533, 65596.67201663961, 65725.34274657193, 67230.0, 82106.58946705228, 82558.0, 82680.58659706663, 83310.16689833024, 83875.16124838918, 15281.0, 15335.38658453694, 15368.462103826312, 15545.145017024171, 15569.844301557292, 23237.76762232356, 23377.88311058502, 23388.0, 23502.294931152093, 23617.055317787097, 16519.587010324838, 16631.168441578116, 16780.0, 17677.38129165473, 19101.808981910002, 17174.141292935554, 17583.384581539583, 17606.29574816993, 17737.113144342988, 17842.0, 19592.510187245432, 19643.70534442013, 19868.10593523266, 19916.60166796685, 20025.699614506084, 15183.468578599695, 15331.0, 15340.539783806504, 15340.846591533942, 15348.0, 18615.255389784255, 18745.437636870916, 18843.152058157142, 18902.338418155297, 18903.243870245034], "value": 12649.41318612697, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1386.5147198480531, 1455.5778824141, 1459.6059064052713, 1507.5175943698014, 1508.1252873333467, 16747.916260418697, 17277.395291164812, 17348.566285842844, 17371.30514779407, 17412.216450259737, 16933.06868122253, 17136.914315428436, 17168.31326746932, 17205.483835484923, 17235.0, 2787.28924124348, 2797.9160625181225, 2825.8021938464317, 2829.1371131804895, 2845.046909285392, 2640.247529454106, 2703.8918443262246, 2716.9592456113173, 2717.904873329434, 2718.8504632245217, 2646.219365287235, 2702.527057764893, 2706.6751989761156, 2730.699623041464, 2734.4394399148127, 2711.200195942202, 2721.4285000149994, 2729.290384500034, 2736.165469531812, 2736.931576710578, 2729.6997330293652, 2769.62610047645, 2770.0581802187153, 2780.2910257884314, 2782.9721702782917, 16689.582760430963, 16995.0, 17069.231884565223, 17074.48776536706, 17091.0, 7992.840143197103, 8229.753107406787, 8275.255227029536, 8282.50304981703, 8286.129956354556, 5408.134698448269, 5500.4224556421395, 5585.273914391144, 5654.575906806965, 5765.250517432749, 2143.882086485243, 2147.452399638098, 2174.7390313162523, 2222.1777942161502, 2230.743464501587, 30532.847335763403, 30633.387332253453, 30748.846255768578, 30785.38429231403, 30813.537796933066, 7080.504364694422, 7177.641117944187, 7180.3537681608905, 7380.77857664271, 7404.074490688664, 20004.49988750293, 20164.19343226284, 20225.898870505553, 20241.595168096555, 20246.392608221777, 8237.135100814448, 8362.45644033136, 8399.790005249795, 8404.20160084791, 8423.620937057847, 7274.345308922222, 7649.235076492418, 7657.693692252247, 7693.807654808675, 7725.420593455513, 5618.887622247532, 5748.338941021752, 5808.506276966421, 5858.062709966385, 5887.35239123696, 1693.678201141779, 1722.9138543073047, 1726.7237242041213, 1727.8358555937166, 1738.0800255570762, 885.1104639836923, 899.9820003599891, 917.6100157433065, 919.2186641354929, 919.8344298026286, 8645.351598630128, 8688.913110868936, 8731.65073397057, 8833.028366879638, 8840.469571825712, 784.5998540744262, 786.7325109462696, 826.3348004856028, 835.4820011592851, 844.7381311793364, 5761.423857614205, 6119.632822030692, 6204.782832600928, 6269.529785266123, 6306.369363063657, 24399.26802195937, 24606.507869842502, 24904.0, 25115.0, 25185.874070629896, 23975.52048959046, 24113.7588624115, 24869.5026099477, 25028.624570631455, 25189.370265743502, 11810.52757889692, 12017.939910300393, 12030.81953770694, 12056.51773929033, 12060.819087713691, 9538.380005299634, 9701.417914925127, 9853.753656158367, 9859.211263098792, 9994.700158995242, 20453.0, 20464.488387790425, 20745.688814668094, 20842.68735968992, 20858.37424877256, 21718.348449545905, 21729.78270217341, 21796.89101554482, 22110.447238818513, 22308.77691223067, 12832.615021549369, 12954.67613309637, 12989.0, 13035.674108147372, 13035.869641303465, 15272.0, 15439.768403473732, 15532.689346212786, 15630.218489075729, 15654.843451565794, 11775.35235562018, 11866.110041747077, 12116.757664846476, 12204.572839950559, 12341.197822141532, 11918.642440726791, 12046.156769026084, 12404.131710780159, 12526.0, 12561.057920655805, 11240.15698822608, 11285.492152853305, 11392.94303528477, 11401.030912372209, 11406.429678516208, 8667.263282621048, 8737.388382813142, 8739.650413983369, 8829.293656507336, 8843.69047083349, 8713.302935764998, 8812.823743524965, 8857.0, 8865.822683546165, 8874.955625221832], "value": 7282.375803272141, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [550.2928736573502, 556.1824118545736, 572.0589630058552, 580.2659635561016, 580.3934888041996, 6139.8772024559485, 6167.352427995058, 6198.605313804392, 6207.906881396777, 6226.0, 6135.539834512407, 6167.414095660916, 6197.380261973801, 6199.194104766374, 6201.937980620192, 4885.780139893695, 5136.666116702421, 5142.07442660321, 5145.691258524482, 5148.536631703146, 5011.84964451067, 5039.118154322997, 5041.218611115265, 5060.0385926674, 5077.898442031157, 5006.699598024112, 5024.196128619422, 5030.2957585938, 5055.873603159914, 5063.088644044071, 4967.155583550805, 4985.202367621163, 5021.774020169101, 5022.949770502285, 5036.899262014757, 4568.291914753218, 4593.379893714373, 4610.147122782296, 4638.397008388922, 4646.4424269087585, 6146.29317628474, 6186.814395568095, 6228.28374736902, 6253.062040693932, 6263.78076767311, 5850.444207800252, 5866.794662186803, 5922.822315330547, 5922.851928701773, 5960.940390596125, 6707.262201157869, 6719.7312107515645, 6720.966395167993, 6722.327767223287, 6738.966305168492, 5143.588512918957, 5157.994191132693, 5161.3806343238675, 5185.637005409623, 5194.766235519411, 6947.965260173667, 7047.026832486912, 7070.469714771361, 7081.044059052014, 7097.645117744144, 5200.141976573898, 5214.243934629458, 5225.817096401684, 5225.869353266123, 5231.581473482112, 5226.843194704165, 5250.789968401221, 5288.65623734456, 5297.099493086184, 5305.36335639718, 5846.503047241032, 5862.0, 5869.8239052828485, 5876.853078673067, 5882.529397648178, 6466.418022378009, 6508.674566271668, 6628.966855165693, 6670.266270710218, 6708.664566771642, 1967.9606407871763, 2000.9099590518463, 2005.8295044921053, 2006.2576846566865, 2011.2658879508874, 1240.3054289597853, 1439.5465428390023, 1442.170751817709, 1458.4603696632314, 1495.5588101510082, 6092.786752463642, 6145.8156255312415, 6226.688665566794, 6283.905741413882, 6339.4611458025665, 6650.434713049445, 6661.333866613397, 6679.599224046573, 6746.494012949049, 6816.863662726718, 6183.536234782409, 6207.5654704171175, 6211.720472578745, 6289.465395441439, 6304.275008374004, 3541.698955588804, 3543.5216245806487, 3563.6970857476886, 3587.2601788132733, 3612.4942508048894, 6884.345987131214, 6896.27589103147, 6944.090375146875, 7016.0, 7074.327938845801, 6832.180138383425, 6862.828429289308, 6870.484713646397, 6977.0, 7039.1553013638695, 6952.270011648907, 6985.306974815509, 7014.438844892396, 7073.92926070743, 7080.575165490087, 6633.507460821276, 6698.933010669831, 6751.527393082437, 6808.795736127924, 6821.693023813841, 6704.932950670626, 6732.427743641845, 6756.966215168893, 6805.4895882808, 6836.726530938707, 6960.756373526902, 6988.40598549129, 6995.6708225437005, 7061.788146355618, 7085.362317391459, 5210.0, 5265.236540701653, 5293.523582877559, 5334.226537152169, 5344.144936810248, 6721.966390168018, 6759.256481787098, 6828.1861287791, 6864.553804002724, 6867.866801977618, 6519.282878883414, 6519.6088234704175, 6617.874961256694, 6628.436582890508, 6648.867022659617, 6520.282768895323, 6551.181102362205, 6579.736810527525, 6584.604923704593, 6608.4052435281055, 6384.585001974857, 6403.583767055126, 6444.677766111582, 6458.289588145206, 6504.991726282434, 6106.6946652668075, 6449.290578036506, 6588.439982601532, 6651.667416629052, 6670.266270710121, 6228.5951413158, 6317.273513546001, 6384.744610215539, 6424.164858568543, 6479.384458476437], "value": 5230.020394711675, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [951.5004622573148, 981.9067188617083, 994.6767300627293, 1000.1498726082831, 1004.9447280399585, 20593.176272949084, 20813.895930520328, 21268.46828829278, 21356.679649805246, 21385.786142138575, 18651.347202847905, 18969.52576185596, 19073.237070517163, 19156.712649310273, 19168.42494725157, 1038.3406536849118, 1061.5647584490362, 1068.0, 1068.9305195162328, 1069.1019543583388, 1027.8149933012055, 1064.5741703318695, 1074.0226393981438, 1074.8441475985978, 1075.7310672331917, 1106.9612563560277, 1109.3177695717116, 1110.4447776111945, 1110.7334239782435, 1111.4498323329963, 1086.4947799273336, 1098.2751384086544, 1098.6703988803326, 1103.7351035751362, 1113.3208742666966, 806.105223202245, 806.5201205282847, 806.9878951815728, 807.0557447786105, 807.1242701668697, 20549.178032878666, 21204.257850975297, 21352.0, 21551.245706400357, 21558.0, 23754.287371378887, 23901.521969560687, 24144.87927560351, 24235.151769687975, 24255.15106971265, 18453.1696073677, 18652.253909843726, 18719.62560748791, 18763.62472750551, 18772.0, 3828.942565861514, 3836.405357169643, 3882.864099756523, 3908.2769687607597, 3934.2328245992044, 154612.0, 157740.0, 159483.0, 161476.3852361473, 163186.0, 16787.66424671524, 17101.315947361967, 17165.055921924413, 17240.827591724174, 17278.22247998843, 34138.82930585381, 34433.311333773185, 34725.0, 34830.47754283688, 34911.65088349135, 9836.3606365586, 9864.408135511892, 9933.453660048623, 9953.353032052893, 9972.30193886421, 4520.208963431386, 4754.881127971828, 4799.496052914464, 4801.975990120027, 4808.158572249842, 6175.3515880832765, 6247.219097612799, 6270.372962703785, 6283.340249273851, 6290.24517057956, 1136.6476392318407, 1174.976500469986, 1187.038498815962, 1195.6891208285908, 1198.532572296805, 710.2045708806066, 724.221461928429, 727.3017902813252, 731.7621772923821, 750.3734381791156, 13332.800007999887, 13356.532521361705, 13456.5290214842, 14026.08830426019, 14096.85903140976, 748.2592233688717, 750.6772088002211, 754.1892465599502, 755.5126943121655, 773.9535627862347, 6364.331745166691, 6379.649119298483, 6384.6169229846355, 6421.325760795049, 6552.868942621216, 157090.0, 157100.0, 158135.0, 161052.0, 163368.0, 141514.0, 142266.0, 143489.0, 145622.0, 150095.0, 120872.0, 121613.0, 122405.0, 123172.76827231792, 123223.0, 11768.411579421167, 11920.523179073087, 12080.879191208327, 12296.631101066982, 12411.62765117048, 57099.0, 58654.413455866605, 58969.705151473965, 58990.0, 59846.0, 75038.62480687561, 76338.0, 77368.61315693386, 78020.0, 78762.6061869687, 16797.328106876077, 17026.318947242467, 17085.57286067858, 17303.480895573153, 17389.739153912447, 18297.26810927548, 18371.54071148232, 18508.16713247934, 18605.06974651289, 18667.81332186715, 18019.0, 18112.45662630123, 18140.0, 18164.0, 18254.634907301515, 16543.0, 16767.32930682808, 16790.58023549421, 16825.074620895968, 16840.15799210059, 17858.821411785717, 17992.190351433954, 17997.640047199246, 18045.0, 18228.81771182271, 12200.572979946015, 12225.69435764113, 12243.0, 12306.876931230574, 12403.25580465175, 11612.477438515118, 11684.766304674029, 11866.584669536525, 11940.522379105087, 11978.281303121841], "value": 10000.894297262634, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1368.2, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 108.0, 107.0, 105.0, 105.0, 108.0, 107.0, 107.0, 106.0, 106.0], "value": 106.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 111.0, 120.0, 112.0, 112.0, 125.0, 114.0, 113.0, 138.0, 112.0], "value": 116.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 167.0, 164.0, 167.0, 162.0, 164.0, 162.0, 163.0, 166.0, 170.0], "value": 164.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 73.0, 73.0, 74.0, 74.0, 73.0, 72.0, 75.0, 73.0, 74.0], "value": 73.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 78.0, 78.0, 79.0, 78.0, 78.0, 129.0, 78.0, 80.0, 78.0], "value": 83.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 114.0, 109.0, 114.0, 113.0, 113.0, 110.0, 114.0, 113.0, 114.0], "value": 112.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 150.0, 150.0, 149.0, 150.0, 150.0, 149.0, 150.0, 149.0, 150.0], "value": 149.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 94.0, 94.0, 94.0, 94.0, 95.0, 94.0, 94.0, 95.0, 94.0], "value": 94.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 46.0, 43.0, 42.0, 44.0, 44.0, 44.0, 45.0, 45.0, 45.0], "value": 45.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [38.0, 38.0, 38.0, 38.0, 38.0, 38.0, 39.0, 38.0, 39.0, 38.0], "value": 38.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 68.0, 70.0, 69.0, 69.0, 68.0, 68.0, 70.0, 73.0, 70.0], "value": 69.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 109.0, 105.0, 104.0, 106.0, 111.0, 103.0, 108.0, 104.0, 103.0], "value": 106.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 146.0, 144.0, 143.0, 151.0, 146.0, 146.0, 151.0, 146.0, 150.0], "value": 147.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 59.0, 59.0, 61.0, 58.0, 55.0, 59.0, 62.0, 57.0, 63.0], "value": 59.3, "unit": "score"}]}]} 16:41:30 INFO - Return code: 0 16:41:30 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:41:30 INFO - # TBPL SUCCESS # 16:41:30 INFO - Running post-action listener: _package_coverage_data 16:41:30 INFO - Running post-action listener: _resource_record_post_action 16:41:30 INFO - [mozharness: 2017-09-28 23:41:30.762643Z] Finished run-tests step (success) 16:41:30 INFO - Running post-run listener: _resource_record_post_run 16:41:30 INFO - Total resource usage - Wall time: 1184s; CPU: 13.0%; Read bytes: 8192; Write bytes: 329375744; Read time: 204; Write time: 262976 16:41:30 INFO - TinderboxPrint: CPU usage
13.1% 16:41:30 INFO - TinderboxPrint: I/O read bytes / time
8,192 / 204 16:41:30 INFO - TinderboxPrint: I/O write bytes / time
329,375,744 / 262,976 16:41:30 INFO - TinderboxPrint: CPU idle
8,206.5 (86.9%) 16:41:30 INFO - TinderboxPrint: CPU user
1,186.7 (12.6%) 16:41:30 INFO - TinderboxPrint: Swap in / out
0 / 0 16:41:30 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 12636160; Read time: 0; Write time: 2336 16:41:30 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 16:41:30 INFO - run-tests - Wall time: 1167s; CPU: 13.0%; Read bytes: 8192; Write bytes: 315604992; Read time: 204; Write time: 260516 16:41:31 INFO - Running post-run listener: _upload_blobber_files 16:41:31 INFO - Blob upload gear active. 16:41:31 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:41:31 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 16:41:31 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'] 16:41:31 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 16:41:31 INFO - (blobuploader) - INFO - Open directory for files ... 16:41:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 16:41:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:41:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:41:32 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 16:41:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:41:32 INFO - (blobuploader) - INFO - Done attempting. 16:41:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 16:41:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:41:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:41:33 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 16:41:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:41:33 INFO - (blobuploader) - INFO - Done attempting. 16:41:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:41:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:41:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:41:41 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:41:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:41:41 INFO - (blobuploader) - INFO - Done attempting. 16:41:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 16:41:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:41:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:41:42 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 16:41:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:41:42 INFO - (blobuploader) - INFO - Done attempting. 16:41:42 INFO - (blobuploader) - INFO - Iteration through files over. 16:41:42 INFO - Return code: 0 16:41:42 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:41:42 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:41:42 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1d5fb868f3fa650b7704513662a235fb4e84917eb5ff9e538e396459256f5bd4059156eba7a91edea260aed406814658b5b1bea0bbb240ab51554403847a17b4", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/339097296da91dbe2e945c45f6468c22923ecb9d9d2213fc05f04559852c61287821385054a362af4eb6e0ace4be150863756931e75adea688a87c28f72a9a39", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f6e8db7ab9d1bd4f79dc99ea43599d3f3fd2cdc7a93814d8f196b4c3d731affb1f800f3f9c9b3554f09c06eb5d3fec6d47232fc59bc425f430fba4ffc6bba1fb", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/590bad157a1c378b77a2824fd241a0739a4d3297e172e538b46cccb906093e7d7da7e4bb854ec91ae898e257894db1d5f72be16833dd9772798b9686e7306302"} 16:41:42 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:41:42 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:41:42 INFO - Contents: 16:41:42 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1d5fb868f3fa650b7704513662a235fb4e84917eb5ff9e538e396459256f5bd4059156eba7a91edea260aed406814658b5b1bea0bbb240ab51554403847a17b4", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/339097296da91dbe2e945c45f6468c22923ecb9d9d2213fc05f04559852c61287821385054a362af4eb6e0ace4be150863756931e75adea688a87c28f72a9a39", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f6e8db7ab9d1bd4f79dc99ea43599d3f3fd2cdc7a93814d8f196b4c3d731affb1f800f3f9c9b3554f09c06eb5d3fec6d47232fc59bc425f430fba4ffc6bba1fb", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/590bad157a1c378b77a2824fd241a0739a4d3297e172e538b46cccb906093e7d7da7e4bb854ec91ae898e257894db1d5f72be16833dd9772798b9686e7306302"} 16:41:42 INFO - Running post-run listener: copy_logs_to_upload_dir 16:41:42 INFO - Copying logs to upload dir... 16:41:42 INFO - mkdir: /builds/slave/test/build/upload/logs 16:41:42 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1238.033604 ========= master_lag: -0.30 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 37 secs) (at 2017-09-28 16:41:41.718086) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-28 16:41:41.723086) ========= 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=62c568580acbfdaac3eec7be000001dc-1506639858.562470-1115674979 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/1d5fb868f3fa650b7704513662a235fb4e84917eb5ff9e538e396459256f5bd4059156eba7a91edea260aed406814658b5b1bea0bbb240ab51554403847a17b4", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/339097296da91dbe2e945c45f6468c22923ecb9d9d2213fc05f04559852c61287821385054a362af4eb6e0ace4be150863756931e75adea688a87c28f72a9a39", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f6e8db7ab9d1bd4f79dc99ea43599d3f3fd2cdc7a93814d8f196b4c3d731affb1f800f3f9c9b3554f09c06eb5d3fec6d47232fc59bc425f430fba4ffc6bba1fb", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/590bad157a1c378b77a2824fd241a0739a4d3297e172e538b46cccb906093e7d7da7e4bb854ec91ae898e257894db1d5f72be16833dd9772798b9686e7306302"} build_url:https://queue.taskcluster.net/v1/task/cmYlyjEDRk2IOAJ-a13Uqg/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011373 build_url: 'https://queue.taskcluster.net/v1/task/cmYlyjEDRk2IOAJ-a13Uqg/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1d5fb868f3fa650b7704513662a235fb4e84917eb5ff9e538e396459256f5bd4059156eba7a91edea260aed406814658b5b1bea0bbb240ab51554403847a17b4", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/339097296da91dbe2e945c45f6468c22923ecb9d9d2213fc05f04559852c61287821385054a362af4eb6e0ace4be150863756931e75adea688a87c28f72a9a39", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f6e8db7ab9d1bd4f79dc99ea43599d3f3fd2cdc7a93814d8f196b4c3d731affb1f800f3f9c9b3554f09c06eb5d3fec6d47232fc59bc425f430fba4ffc6bba1fb", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/590bad157a1c378b77a2824fd241a0739a4d3297e172e538b46cccb906093e7d7da7e4bb854ec91ae898e257894db1d5f72be16833dd9772798b9686e7306302"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-28 16:41:41.754972) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-28 16:41:41.755402) ========= 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=62c568580acbfdaac3eec7be000001dc-1506639858.562470-1115674979 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004574 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-28 16:41:41.805229) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-28 16:41:41.808665) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-28 16:41:41.809141) ========= ========= Total master_lag: -0.16 =========