builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-023 starttime: 1506122845.56 results: success (0) revision: 3d72fdb0e561ea59d9e5850c3e71367dbb8a7148 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-22 16:27:25.557346) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-22 16:27:25.557741) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-22 16:27:25.558048) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-22 16:27:25.591222) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-22 16:27:25.591528) ========= 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=6d15e502d8e29d5d2676d664000001c3-1506122770.22441-1860920291 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-22 16:27:25-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 45.4M=0s 2017-09-22 16:27:25 (45.4 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.075445 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-22 16:27:25.690212) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-22 16:27:25.690492) ========= 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=6d15e502d8e29d5d2676d664000001c3-1506122770.22441-1860920291 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.021667 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-22 16:27:25.752158) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-22 16:27:25.752469) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 3d72fdb0e561ea59d9e5850c3e71367dbb8a7148 --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 3d72fdb0e561ea59d9e5850c3e71367dbb8a7148 --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=6d15e502d8e29d5d2676d664000001c3-1506122770.22441-1860920291 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-22 16:27:25,734 truncating revision to first 12 chars 2017-09-22 16:27:25,734 Setting DEBUG logging. 2017-09-22 16:27:25,734 attempt 1/10 2017-09-22 16:27:25,734 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/3d72fdb0e561?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-22 16:27:26,002 unpacking tar archive at: mozilla-central-3d72fdb0e561/testing/mozharness/ program finished with exit code 0 elapsedTime=0.558167 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-22 16:27:26.342385) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-22 16:27:26.342675) ========= script_repo_revision: 3d72fdb0e561ea59d9e5850c3e71367dbb8a7148 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-22 16:27:26.343027) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-22 16:27:26.345598) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-22 16:27:26.365233) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 35 secs) (at 2017-09-22 16:27:26.365484) ========= /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=6d15e502d8e29d5d2676d664000001c3-1506122770.22441-1860920291 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 16:27:26 INFO - MultiFileLogger online at 20170922 16:27:26 in /builds/slave/test 16:27:26 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:27:26 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:27:26 INFO - {'append_to_log': False, 16:27:26 INFO - 'base_work_dir': '/builds/slave/test', 16:27:26 INFO - 'blob_upload_branch': 'Firefox', 16:27:26 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:27:26 INFO - 'branch': 'Firefox', 16:27:26 INFO - 'buildbot_json_path': 'buildprops.json', 16:27:26 INFO - 'code_coverage': False, 16:27:26 INFO - 'config_files': ('talos/linux_config.py',), 16:27:26 INFO - 'default_actions': ('clobber', 16:27:26 INFO - 'read-buildbot-config', 16:27:26 INFO - 'download-and-extract', 16:27:26 INFO - 'populate-webroot', 16:27:26 INFO - 'create-virtualenv', 16:27:26 INFO - 'install', 16:27:26 INFO - 'setup-mitmproxy', 16:27:26 INFO - 'run-tests'), 16:27:26 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:27:26 INFO - 'disable_ccov_upload': False, 16:27:26 INFO - 'disable_stylo': False, 16:27:26 INFO - 'download_minidump_stackwalk': True, 16:27:26 INFO - 'download_symbols': 'ondemand', 16:27:26 INFO - 'enable_stylo': False, 16:27:26 INFO - 'enable_webrender': False, 16:27:26 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 16:27:26 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:27:26 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:27:26 INFO - 'gecko_profile': False, 16:27:26 INFO - 'gecko_profile_interval': 0, 16:27:26 INFO - 'installer_path': 'installer.exe', 16:27:26 INFO - 'log_level': 'info', 16:27:26 INFO - 'log_name': 'talos', 16:27:26 INFO - 'log_to_console': True, 16:27:26 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 16:27:26 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 16:27:26 INFO - 'opt_config_files': (), 16:27:26 INFO - 'pip_index': False, 16:27:26 INFO - 'suite': 'dromaeojs-e10s', 16:27:26 INFO - 'system_bits': '32', 16:27:26 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:27:26 INFO - 'title': 'talos-linux64-ix-023', 16:27:26 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:27:26 INFO - 'use_talos_json': True, 16:27:26 INFO - 'verify': 'False', 16:27:26 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:27:26 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:27:26 INFO - 'work_dir': 'build'} 16:27:26 INFO - [mozharness: 2017-09-22 23:27:26.483346Z] Running clobber step. 16:27:26 INFO - Running pre-action listener: _resource_record_pre_action 16:27:26 INFO - Running main action method: clobber 16:27:26 INFO - rmtree: /builds/slave/test/build 16:27:26 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:27:27 INFO - Running post-action listener: _resource_record_post_action 16:27:27 INFO - [mozharness: 2017-09-22 23:27:27.490568Z] Finished clobber step (success) 16:27:27 INFO - [mozharness: 2017-09-22 23:27:27.490666Z] Running read-buildbot-config step. 16:27:27 INFO - Running pre-action listener: _resource_record_pre_action 16:27:27 INFO - Running main action method: read_buildbot_config 16:27:27 INFO - Using buildbot properties: 16:27:27 INFO - { 16:27:27 INFO - "project": "", 16:27:27 INFO - "product": "firefox", 16:27:27 INFO - "who": "kwierso@gmail.com", 16:27:27 INFO - "installer_path": "public/build/target.tar.bz2", 16:27:27 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 16:27:27 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 16:27:27 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs-e10s", 16:27:27 INFO - "stage_platform": "linux64-pgo", 16:27:27 INFO - "basedir": "/builds/slave/test", 16:27:27 INFO - "buildnumber": 197, 16:27:27 INFO - "platform": "ubuntu64_hw", 16:27:27 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 16:27:27 INFO - "slavebuilddir": "test", 16:27:27 INFO - "taskId": "a8IjEP0JTZWXN3igzUvdLw", 16:27:27 INFO - "branch": "mozilla-central", 16:27:27 INFO - "script_repo_revision": "production", 16:27:27 INFO - "revision": "3d72fdb0e561ea59d9e5850c3e71367dbb8a7148", 16:27:27 INFO - "slavename": "talos-linux64-ix-023", 16:27:27 INFO - "repo_path": "mozilla-central" 16:27:27 INFO - } 16:27:27 INFO - Finding installer, test and symbols from parent task. 16:27:27 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/a8IjEP0JTZWXN3igzUvdLw'}, attempt #1 16:27:27 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/a8IjEP0JTZWXN3igzUvdLw'}, attempt #1 16:27:28 INFO - Task dependencies: cbFdpQ8wTE6HRI4Bano6mQ 16:27:28 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cbFdpQ8wTE6HRI4Bano6mQ'}, attempt #1 16:27:28 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/cbFdpQ8wTE6HRI4Bano6mQ/artifacts/public/build/target.tar.bz2 16:27:28 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/cbFdpQ8wTE6HRI4Bano6mQ/artifacts/public/build/target.test_packages.json 16:27:28 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/cbFdpQ8wTE6HRI4Bano6mQ/artifacts/public/build/target.crashreporter-symbols.zip 16:27:28 INFO - Running post-action listener: _resource_record_post_action 16:27:28 INFO - [mozharness: 2017-09-22 23:27:28.848694Z] Finished read-buildbot-config step (success) 16:27:28 INFO - [mozharness: 2017-09-22 23:27:28.848913Z] Running download-and-extract step. 16:27:28 INFO - Running pre-action listener: _resource_record_pre_action 16:27:28 INFO - Running main action method: download_and_extract 16:27:28 INFO - mkdir: /builds/slave/test/build/tests 16:27:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:27:28 INFO - trying https://queue.taskcluster.net/v1/task/cbFdpQ8wTE6HRI4Bano6mQ/artifacts/public/build/target.test_packages.json 16:27:28 INFO - Downloading https://queue.taskcluster.net/v1/task/cbFdpQ8wTE6HRI4Bano6mQ/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 16:27:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cbFdpQ8wTE6HRI4Bano6mQ/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 16:27:29 INFO - Downloaded 989 bytes. 16:27:29 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 16:27:29 INFO - Using the following test package requirements: 16:27:29 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 16:27:29 INFO - u'common': [u'target.common.tests.zip'], 16:27:29 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 16:27:29 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 16:27:29 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 16:27:29 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 16:27:29 INFO - u'mozbase': [u'target.common.tests.zip'], 16:27:29 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 16:27:29 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 16:27:29 INFO - u'web-platform': [u'target.common.tests.zip', 16:27:29 INFO - u'target.web-platform.tests.tar.gz'], 16:27:29 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 16:27:29 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 16:27:29 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/cbFdpQ8wTE6HRI4Bano6mQ/artifacts/public/build/target.common.tests.zip 16:27:29 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cbFdpQ8wTE6HRI4Bano6mQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 16:27:29 INFO - Fetch https://queue.taskcluster.net/v1/task/cbFdpQ8wTE6HRI4Bano6mQ/artifacts/public/build/target.common.tests.zip into memory 16:27:34 INFO - Content-Length response header: 55342278 16:27:34 INFO - Bytes received: 55342278 16:27:45 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/cbFdpQ8wTE6HRI4Bano6mQ/artifacts/public/build/target.talos.tests.zip 16:27:45 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cbFdpQ8wTE6HRI4Bano6mQ/artifacts/public/build/target.talos.tests.zip'}, attempt #1 16:27:45 INFO - Fetch https://queue.taskcluster.net/v1/task/cbFdpQ8wTE6HRI4Bano6mQ/artifacts/public/build/target.talos.tests.zip into memory 16:27:46 INFO - Content-Length response header: 14513823 16:27:46 INFO - Bytes received: 14513823 16:27:47 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:27:47 INFO - trying https://queue.taskcluster.net/v1/task/cbFdpQ8wTE6HRI4Bano6mQ/artifacts/public/build/target.tar.bz2 16:27:47 INFO - Downloading https://queue.taskcluster.net/v1/task/cbFdpQ8wTE6HRI4Bano6mQ/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 16:27:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cbFdpQ8wTE6HRI4Bano6mQ/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 16:27:49 INFO - Downloaded 62376189 bytes. 16:27:49 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/cbFdpQ8wTE6HRI4Bano6mQ/artifacts/public/build/target.tar.bz2 16:27:49 INFO - mkdir: /builds/slave/test/properties 16:27:49 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:27:49 INFO - Writing to file /builds/slave/test/properties/build_url 16:27:49 INFO - Contents: 16:27:49 INFO - build_url:https://queue.taskcluster.net/v1/task/cbFdpQ8wTE6HRI4Bano6mQ/artifacts/public/build/target.tar.bz2 16:27:49 INFO - Running post-action listener: _resource_record_post_action 16:27:49 INFO - Running post-action listener: find_tests_for_verification 16:27:49 INFO - Running post-action listener: set_extra_try_arguments 16:27:49 INFO - [mozharness: 2017-09-22 23:27:49.854006Z] Finished download-and-extract step (success) 16:27:49 INFO - [mozharness: 2017-09-22 23:27:49.854102Z] Running populate-webroot step. 16:27:49 INFO - Running pre-action listener: _resource_record_pre_action 16:27:49 INFO - Running main action method: populate_webroot 16:27:49 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:27:49 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 16:27:49 INFO - 'tests': ['tresize', 16:27:49 INFO - 'tcanvasmark']}, 16:27:49 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:27:49 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 16:27:49 INFO - 'tests': ['dromaeo_css', 16:27:49 INFO - 'kraken']}, 16:27:49 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 16:27:49 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:27:49 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 16:27:49 INFO - 'talos_options': ['--disable-stylo'], 16:27:49 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:27:49 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 16:27:49 INFO - 'tests': ['damp', 'tps']}, 16:27:49 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 16:27:49 INFO - 'talos_options': ['--disable-stylo'], 16:27:49 INFO - 'tests': ['damp', 'tps']}, 16:27:49 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:27:49 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 16:27:49 INFO - 'tests': ['dromaeo_dom']}, 16:27:49 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 16:27:49 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 16:27:49 INFO - 'tests': ['basic_compositor_video', 16:27:49 INFO - 'glvideo']}, 16:27:49 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 16:27:49 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 16:27:49 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 16:27:49 INFO - 'talos_options': ['--disable-stylo'], 16:27:49 INFO - 'tests': ['ts_paint_webext', 16:27:49 INFO - 'tp5o_webext']}, 16:27:49 INFO - 'other-e10s': {'tests': ['a11yr', 16:27:49 INFO - 'ts_paint', 16:27:49 INFO - 'tpaint', 16:27:49 INFO - 'sessionrestore', 16:27:49 INFO - 'sessionrestore_many_windows', 16:27:49 INFO - 'sessionrestore_no_auto_restore', 16:27:49 INFO - 'tabpaint']}, 16:27:49 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 16:27:49 INFO - 'tests': ['a11yr', 16:27:49 INFO - 'ts_paint', 16:27:49 INFO - 'tpaint', 16:27:49 INFO - 'sessionrestore', 16:27:49 INFO - 'sessionrestore_many_windows', 16:27:49 INFO - 'sessionrestore_no_auto_restore', 16:27:49 INFO - 'tabpaint']}, 16:27:49 INFO - 'perf-reftest-e10s': {'tests': ['perf_reftest']}, 16:27:49 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']}, 16:27:49 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 16:27:49 INFO - 'tests': ['perf_reftest_singletons']}, 16:27:49 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 16:27:49 INFO - 'tests': ['perf_reftest']}, 16:27:49 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:27:49 INFO - 'tsvgr_opacity', 16:27:49 INFO - 'tart', 16:27:49 INFO - 'tscrollx', 16:27:49 INFO - 'tsvg_static']}, 16:27:49 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 16:27:49 INFO - 'tests': ['tsvgx', 16:27:49 INFO - 'tsvgr_opacity', 16:27:49 INFO - 'tart', 16:27:49 INFO - 'tscrollx', 16:27:49 INFO - 'tsvg_static']}, 16:27:49 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 16:27:49 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 16:27:49 INFO - 'talos_options': ['--disable-stylo'], 16:27:49 INFO - 'tests': ['tp5o']}, 16:27:49 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 16:27:49 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 16:27:49 INFO - 'talos_options': ['--mitmproxy', 16:27:49 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 16:27:49 INFO - '--firstNonBlankPaint'], 16:27:49 INFO - 'tests': ['tp6_google', 16:27:49 INFO - 'tp6_youtube', 16:27:49 INFO - 'tp6_amazon', 16:27:49 INFO - 'tp6_facebook']}, 16:27:49 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 16:27:49 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 16:27:49 INFO - 'talos_options': ['--disable-stylo', 16:27:49 INFO - '--mitmproxy', 16:27:49 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 16:27:49 INFO - '--firstNonBlankPaint'], 16:27:49 INFO - 'tests': ['tp6_google', 16:27:49 INFO - 'tp6_youtube', 16:27:49 INFO - 'tp6_amazon', 16:27:49 INFO - 'tp6_facebook']}, 16:27:49 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 16:27:49 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 16:27:49 INFO - 'talos_options': ['--stylo-threads=1', 16:27:49 INFO - '--mitmproxy', 16:27:49 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 16:27:49 INFO - '--firstNonBlankPaint'], 16:27:49 INFO - 'tests': ['tp6_google', 16:27:49 INFO - 'tp6_youtube', 16:27:49 INFO - 'tp6_amazon', 16:27:49 INFO - 'tp6_facebook']}, 16:27:49 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 16:27:49 INFO - 'talos_options': ['--xperf_path', 16:27:49 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:27:49 INFO - 'tests': ['tp5n']}, 16:27:49 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 16:27:49 INFO - 'talos_options': ['--disable-stylo', 16:27:49 INFO - '--xperf_path', 16:27:49 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:27:49 INFO - 'tests': ['tp5n']}}} 16:27:49 INFO - Running post-action listener: _resource_record_post_action 16:27:49 INFO - [mozharness: 2017-09-22 23:27:49.870319Z] Finished populate-webroot step (success) 16:27:49 INFO - [mozharness: 2017-09-22 23:27:49.870426Z] Running create-virtualenv step. 16:27:49 INFO - Running pre-action listener: _resource_record_pre_action 16:27:49 INFO - Running main action method: create_virtualenv 16:27:49 INFO - Creating virtualenv /builds/slave/test/build/venv 16:27:49 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 16:27:49 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 16:27:49 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 16:27:49 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:27:50 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:27:50 INFO - Installing distribute.............................................................................................................................................................................................done. 16:27:50 INFO - Installing pip...............done. 16:27:50 INFO - Return code: 0 16:27:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 16:27:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 16:27:50 INFO - Reading from file tmpfile_stdout 16:27:50 INFO - Output received: 16:27:50 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:27:50 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:27:50 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:27:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:27:50 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:27:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:27:50 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 0xe1c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfe4b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11899c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1186d60>, '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 0x1187a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1187ef0>, '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': '6d15e502d8e29d5d2676d664000001c3-1506122770.22441-1860920291', '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:27:50 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:27:50 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:27:50 INFO - Using env: {'DISPLAY': ':0', 16:27:50 INFO - 'HOME': '/home/cltbld', 16:27:50 INFO - 'LANG': 'en_US.UTF-8', 16:27:50 INFO - 'LANGUAGE': 'en_US:en', 16:27:50 INFO - 'LOGNAME': 'cltbld', 16:27:50 INFO - 'MAIL': '/var/mail/cltbld', 16:27:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:27:50 INFO - 'MOZ_NO_REMOTE': '1', 16:27:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:27:50 INFO - 'NO_EM_RESTART': '1', 16:27:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:27:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:27:50 INFO - 'PWD': '/builds/slave/test', 16:27:50 INFO - 'SHELL': '/bin/bash', 16:27:50 INFO - 'SHLVL': '1', 16:27:50 INFO - 'TERM': 'linux', 16:27:50 INFO - 'TMOUT': '86400', 16:27:50 INFO - 'USER': 'cltbld', 16:27:50 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1506122770.22441-1860920291', 16:27:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:27:50 INFO - '_': '/tools/buildbot/bin/python'} 16:27:52 INFO - Ignoring indexes: http://pypi.python.org/simple/ 16:27:52 INFO - Downloading/unpacking pip>=1.5 16:27:52 INFO - Running setup.py egg_info for package pip 16:27:52 INFO - warning: no files found matching 'pip/cacert.pem' 16:27:52 INFO - warning: no files found matching '*.html' under directory 'docs' 16:27:52 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 16:27:52 INFO - no previously-included directories found matching 'docs/_build/_sources' 16:27:52 INFO - Installing collected packages: pip 16:27:52 INFO - Found existing installation: pip 1.1 16:27:52 INFO - Uninstalling pip: 16:27:52 INFO - Successfully uninstalled pip 16:27:52 INFO - Running setup.py install for pip 16:27:52 INFO - warning: no files found matching 'pip/cacert.pem' 16:27:52 INFO - warning: no files found matching '*.html' under directory 'docs' 16:27:52 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 16:27:52 INFO - no previously-included directories found matching 'docs/_build/_sources' 16:27:52 INFO - Installing pip script to /builds/slave/test/build/venv/bin 16:27:52 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 16:27:52 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 16:27:52 INFO - Successfully installed pip 16:27:52 INFO - Cleaning up... 16:27:52 INFO - Return code: 0 16:27:52 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:27:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:27:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:27:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:27:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:27:52 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 0xe1c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfe4b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11899c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1186d60>, '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 0x1187a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1187ef0>, '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': '6d15e502d8e29d5d2676d664000001c3-1506122770.22441-1860920291', '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:27:52 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:27:52 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:27:52 INFO - Using env: {'DISPLAY': ':0', 16:27:52 INFO - 'HOME': '/home/cltbld', 16:27:52 INFO - 'LANG': 'en_US.UTF-8', 16:27:52 INFO - 'LANGUAGE': 'en_US:en', 16:27:52 INFO - 'LOGNAME': 'cltbld', 16:27:52 INFO - 'MAIL': '/var/mail/cltbld', 16:27:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:27:52 INFO - 'MOZ_NO_REMOTE': '1', 16:27:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:27:52 INFO - 'NO_EM_RESTART': '1', 16:27:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:27:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:27:52 INFO - 'PWD': '/builds/slave/test', 16:27:52 INFO - 'SHELL': '/bin/bash', 16:27:52 INFO - 'SHLVL': '1', 16:27:52 INFO - 'TERM': 'linux', 16:27:52 INFO - 'TMOUT': '86400', 16:27:52 INFO - 'USER': 'cltbld', 16:27:52 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1506122770.22441-1860920291', 16:27:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:27:52 INFO - '_': '/tools/buildbot/bin/python'} 16:27:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:27:52 INFO - Downloading/unpacking psutil>=3.1.1 16:27:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:27:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:27:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:27:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:27:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:27:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:27:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:27:53 INFO - Installing collected packages: psutil 16:27:53 INFO - Running setup.py install for psutil 16:27:53 INFO - building 'psutil._psutil_linux' extension 16:27:53 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:27:53 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:27:53 INFO - building 'psutil._psutil_posix' extension 16:27:53 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:27:53 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:27:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:27:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:27:53 INFO - Successfully installed psutil 16:27:53 INFO - Cleaning up... 16:27:54 INFO - Return code: 0 16:27:54 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:27:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:27:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:27:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:27:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:27:54 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 0xe1c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfe4b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11899c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1186d60>, '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 0x1187a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1187ef0>, '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': '6d15e502d8e29d5d2676d664000001c3-1506122770.22441-1860920291', '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:27:54 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:27:54 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:27:54 INFO - Using env: {'DISPLAY': ':0', 16:27:54 INFO - 'HOME': '/home/cltbld', 16:27:54 INFO - 'LANG': 'en_US.UTF-8', 16:27:54 INFO - 'LANGUAGE': 'en_US:en', 16:27:54 INFO - 'LOGNAME': 'cltbld', 16:27:54 INFO - 'MAIL': '/var/mail/cltbld', 16:27:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:27:54 INFO - 'MOZ_NO_REMOTE': '1', 16:27:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:27:54 INFO - 'NO_EM_RESTART': '1', 16:27:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:27:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:27:54 INFO - 'PWD': '/builds/slave/test', 16:27:54 INFO - 'SHELL': '/bin/bash', 16:27:54 INFO - 'SHLVL': '1', 16:27:54 INFO - 'TERM': 'linux', 16:27:54 INFO - 'TMOUT': '86400', 16:27:54 INFO - 'USER': 'cltbld', 16:27:54 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1506122770.22441-1860920291', 16:27:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:27:54 INFO - '_': '/tools/buildbot/bin/python'} 16:27:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:27:54 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:27:54 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:27:54 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:27:54 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:27:54 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:27:55 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:27:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:27:55 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:27:55 INFO - Installing collected packages: mozsystemmonitor 16:27:55 INFO - Running setup.py install for mozsystemmonitor 16:27:55 INFO - Successfully installed mozsystemmonitor 16:27:55 INFO - Cleaning up... 16:27:56 INFO - Return code: 0 16:27:56 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:27:56 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:27:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:27:56 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:27:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:27:56 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 0xe1c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfe4b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11899c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1186d60>, '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 0x1187a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1187ef0>, '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': '6d15e502d8e29d5d2676d664000001c3-1506122770.22441-1860920291', '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:27:56 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:27:56 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:27:56 INFO - Using env: {'DISPLAY': ':0', 16:27:56 INFO - 'HOME': '/home/cltbld', 16:27:56 INFO - 'LANG': 'en_US.UTF-8', 16:27:56 INFO - 'LANGUAGE': 'en_US:en', 16:27:56 INFO - 'LOGNAME': 'cltbld', 16:27:56 INFO - 'MAIL': '/var/mail/cltbld', 16:27:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:27:56 INFO - 'MOZ_NO_REMOTE': '1', 16:27:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:27:56 INFO - 'NO_EM_RESTART': '1', 16:27:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:27:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:27:56 INFO - 'PWD': '/builds/slave/test', 16:27:56 INFO - 'SHELL': '/bin/bash', 16:27:56 INFO - 'SHLVL': '1', 16:27:56 INFO - 'TERM': 'linux', 16:27:56 INFO - 'TMOUT': '86400', 16:27:56 INFO - 'USER': 'cltbld', 16:27:56 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1506122770.22441-1860920291', 16:27:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:27:56 INFO - '_': '/tools/buildbot/bin/python'} 16:27:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:27:56 INFO - Downloading/unpacking jsonschema==2.5.1 16:27:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:27:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:27:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:27:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:27:57 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:27:57 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:27:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:27:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:27:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:27:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:27:58 INFO - Downloading functools32-3.2.3-2.tar.gz 16:27:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:27:58 INFO - warning: no files found matching '*.txt' 16:27:58 INFO - no previously-included directories found matching 'build' 16:27:58 INFO - no previously-included directories found matching 'dist' 16:27:58 INFO - no previously-included directories found matching '.git*' 16:27:58 INFO - Installing collected packages: jsonschema, functools32 16:27:58 INFO - Running setup.py install for functools32 16:27:58 INFO - warning: no files found matching '*.txt' 16:27:58 INFO - no previously-included directories found matching 'build' 16:27:58 INFO - no previously-included directories found matching 'dist' 16:27:58 INFO - no previously-included directories found matching '.git*' 16:27:58 INFO - Successfully installed jsonschema functools32 16:27:58 INFO - Cleaning up... 16:27:58 INFO - Return code: 0 16:27:58 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:27:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:27:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:27:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:27:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:27:58 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 0xe1c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfe4b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11899c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1186d60>, '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 0x1187a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1187ef0>, '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': '6d15e502d8e29d5d2676d664000001c3-1506122770.22441-1860920291', '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:27:58 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:27:58 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:27:58 INFO - Using env: {'DISPLAY': ':0', 16:27:58 INFO - 'HOME': '/home/cltbld', 16:27:58 INFO - 'LANG': 'en_US.UTF-8', 16:27:58 INFO - 'LANGUAGE': 'en_US:en', 16:27:58 INFO - 'LOGNAME': 'cltbld', 16:27:58 INFO - 'MAIL': '/var/mail/cltbld', 16:27:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:27:58 INFO - 'MOZ_NO_REMOTE': '1', 16:27:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:27:58 INFO - 'NO_EM_RESTART': '1', 16:27:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:27:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:27:58 INFO - 'PWD': '/builds/slave/test', 16:27:58 INFO - 'SHELL': '/bin/bash', 16:27:58 INFO - 'SHLVL': '1', 16:27:58 INFO - 'TERM': 'linux', 16:27:58 INFO - 'TMOUT': '86400', 16:27:58 INFO - 'USER': 'cltbld', 16:27:58 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1506122770.22441-1860920291', 16:27:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:27:58 INFO - '_': '/tools/buildbot/bin/python'} 16:27:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:27:58 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:27:58 INFO - Cleaning up... 16:27:58 INFO - Return code: 0 16:27:58 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:27:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:27:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:27:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:27:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:27:58 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 0xe1c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfe4b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11899c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1186d60>, '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 0x1187a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1187ef0>, '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': '6d15e502d8e29d5d2676d664000001c3-1506122770.22441-1860920291', '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:27:58 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:27:58 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:27:58 INFO - Using env: {'DISPLAY': ':0', 16:27:58 INFO - 'HOME': '/home/cltbld', 16:27:58 INFO - 'LANG': 'en_US.UTF-8', 16:27:58 INFO - 'LANGUAGE': 'en_US:en', 16:27:58 INFO - 'LOGNAME': 'cltbld', 16:27:58 INFO - 'MAIL': '/var/mail/cltbld', 16:27:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:27:58 INFO - 'MOZ_NO_REMOTE': '1', 16:27:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:27:58 INFO - 'NO_EM_RESTART': '1', 16:27:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:27:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:27:58 INFO - 'PWD': '/builds/slave/test', 16:27:58 INFO - 'SHELL': '/bin/bash', 16:27:58 INFO - 'SHLVL': '1', 16:27:58 INFO - 'TERM': 'linux', 16:27:58 INFO - 'TMOUT': '86400', 16:27:58 INFO - 'USER': 'cltbld', 16:27:58 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1506122770.22441-1860920291', 16:27:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:27:58 INFO - '_': '/tools/buildbot/bin/python'} 16:27:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:27:58 INFO - Downloading/unpacking blobuploader==1.2.4 16:27:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:27:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:27:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:27:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:27:59 INFO - Downloading blobuploader-1.2.4.tar.gz 16:27:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:27:59 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:27:59 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:27:59 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:27:59 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:27:59 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:28:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:28:00 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:28:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:28:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:28:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:28:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:28:00 INFO - Downloading docopt-0.6.1.tar.gz 16:28:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:28:00 INFO - Installing collected packages: blobuploader, requests, docopt 16:28:00 INFO - Running setup.py install for blobuploader 16:28:00 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:28:00 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:28:00 INFO - Running setup.py install for requests 16:28:00 INFO - Running setup.py install for docopt 16:28:01 INFO - Successfully installed blobuploader requests docopt 16:28:01 INFO - Cleaning up... 16:28:01 INFO - Return code: 0 16:28:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:28:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:28:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:28:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:28:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:28:01 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 0xe1c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfe4b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11899c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1186d60>, '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 0x1187a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1187ef0>, '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': '6d15e502d8e29d5d2676d664000001c3-1506122770.22441-1860920291', '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:28:01 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:28:01 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:28:01 INFO - Using env: {'DISPLAY': ':0', 16:28:01 INFO - 'HOME': '/home/cltbld', 16:28:01 INFO - 'LANG': 'en_US.UTF-8', 16:28:01 INFO - 'LANGUAGE': 'en_US:en', 16:28:01 INFO - 'LOGNAME': 'cltbld', 16:28:01 INFO - 'MAIL': '/var/mail/cltbld', 16:28:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:28:01 INFO - 'MOZ_NO_REMOTE': '1', 16:28:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:28:01 INFO - 'NO_EM_RESTART': '1', 16:28:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:28:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:28:01 INFO - 'PWD': '/builds/slave/test', 16:28:01 INFO - 'SHELL': '/bin/bash', 16:28:01 INFO - 'SHLVL': '1', 16:28:01 INFO - 'TERM': 'linux', 16:28:01 INFO - 'TMOUT': '86400', 16:28:01 INFO - 'USER': 'cltbld', 16:28:01 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1506122770.22441-1860920291', 16:28:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:28:01 INFO - '_': '/tools/buildbot/bin/python'} 16:28:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:28:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:28:01 INFO - Running setup.py (path:/tmp/pip-q70Imc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:28:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:28:01 INFO - Running setup.py (path:/tmp/pip-i1pBjL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:28:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:28:01 INFO - Running setup.py (path:/tmp/pip-qUTeMX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:28:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:28:01 INFO - Running setup.py (path:/tmp/pip-Jx97Iz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:28:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:28:01 INFO - Running setup.py (path:/tmp/pip-ffjv1y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:28:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:28:01 INFO - Running setup.py (path:/tmp/pip-SXolF7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:28:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:28:01 INFO - Running setup.py (path:/tmp/pip-G3PiVE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:28:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:28:01 INFO - Running setup.py (path:/tmp/pip-_M1QUP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:28:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:28:01 INFO - Running setup.py (path:/tmp/pip-PuXeIy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:28:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:28:01 INFO - Running setup.py (path:/tmp/pip-mNOUkZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:28:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:28:02 INFO - Running setup.py (path:/tmp/pip-tg84Te-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:28:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:28:02 INFO - Running setup.py (path:/tmp/pip-4NHltX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:28:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:28:02 INFO - Running setup.py (path:/tmp/pip-pugebV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:28:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:28:02 INFO - Running setup.py (path:/tmp/pip-Rj5lqK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:28:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:28:02 INFO - Running setup.py (path:/tmp/pip-krK6z6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:28:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:28:02 INFO - Running setup.py (path:/tmp/pip-BCmn5r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:28:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:28:02 INFO - Running setup.py (path:/tmp/pip-0FmXMe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:28:02 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:28:02 INFO - Running setup.py install for manifestparser 16:28:02 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:28:02 INFO - Running setup.py install for mozcrash 16:28:02 INFO - Running setup.py install for mozdebug 16:28:02 INFO - Running setup.py install for mozdevice 16:28:02 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:28:02 INFO - Running setup.py install for mozfile 16:28:03 INFO - Running setup.py install for mozhttpd 16:28:03 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:28:03 INFO - Running setup.py install for mozinfo 16:28:03 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:28:03 INFO - Running setup.py install for mozInstall 16:28:03 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:28:03 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:28:03 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:28:03 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:28:03 INFO - Running setup.py install for mozleak 16:28:03 INFO - Running setup.py install for mozlog 16:28:03 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:28:03 INFO - Running setup.py install for moznetwork 16:28:03 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:28:03 INFO - Running setup.py install for mozprocess 16:28:03 INFO - Running setup.py install for mozprofile 16:28:03 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:28:03 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:28:03 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:28:03 INFO - Running setup.py install for mozrunner 16:28:03 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:28:03 INFO - Running setup.py install for mozscreenshot 16:28:04 INFO - Running setup.py install for moztest 16:28:04 INFO - Running setup.py install for mozversion 16:28:04 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:28:04 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:28:04 INFO - Cleaning up... 16:28:04 INFO - Return code: 0 16:28:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:28:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:28:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:28:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:28:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:28:04 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 0xe1c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfe4b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11899c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1186d60>, '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 0x1187a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1187ef0>, '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': '6d15e502d8e29d5d2676d664000001c3-1506122770.22441-1860920291', '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:28:04 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:28:04 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:28:04 INFO - Using env: {'DISPLAY': ':0', 16:28:04 INFO - 'HOME': '/home/cltbld', 16:28:04 INFO - 'LANG': 'en_US.UTF-8', 16:28:04 INFO - 'LANGUAGE': 'en_US:en', 16:28:04 INFO - 'LOGNAME': 'cltbld', 16:28:04 INFO - 'MAIL': '/var/mail/cltbld', 16:28:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:28:04 INFO - 'MOZ_NO_REMOTE': '1', 16:28:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:28:04 INFO - 'NO_EM_RESTART': '1', 16:28:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:28:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:28:04 INFO - 'PWD': '/builds/slave/test', 16:28:04 INFO - 'SHELL': '/bin/bash', 16:28:04 INFO - 'SHLVL': '1', 16:28:04 INFO - 'TERM': 'linux', 16:28:04 INFO - 'TMOUT': '86400', 16:28:04 INFO - 'USER': 'cltbld', 16:28:04 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1506122770.22441-1860920291', 16:28:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:28:04 INFO - '_': '/tools/buildbot/bin/python'} 16:28:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:28:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:28:04 INFO - Running setup.py (path:/tmp/pip-pJ_C8X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:28:04 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:28:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:28:04 INFO - Running setup.py (path:/tmp/pip-NamL_9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:28:04 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:28:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:28:04 INFO - Running setup.py (path:/tmp/pip-VM7JWO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:28:04 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:28:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:28:04 INFO - Running setup.py (path:/tmp/pip-7NIHnL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:28:04 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:28:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:28:04 INFO - Running setup.py (path:/tmp/pip-l6eX_v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:28:04 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:28:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:28:04 INFO - Running setup.py (path:/tmp/pip-SYxGie-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:28:04 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:28:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:28:04 INFO - Running setup.py (path:/tmp/pip-rRrHzx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:28:04 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:28:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:28:04 INFO - Running setup.py (path:/tmp/pip-bPnCcn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:28:05 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:28:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:28:05 INFO - Running setup.py (path:/tmp/pip-AbJphF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:28:05 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:28:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:28:05 INFO - Running setup.py (path:/tmp/pip-6b9wki-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:28:05 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:28:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:28:05 INFO - Running setup.py (path:/tmp/pip-eNmoJE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:28:05 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:28:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:28:05 INFO - Running setup.py (path:/tmp/pip-E0tGR0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:28:05 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:28:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:28:05 INFO - Running setup.py (path:/tmp/pip-cPfxb5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:28:05 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:28:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:28:05 INFO - Running setup.py (path:/tmp/pip-VXDG4G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:28:05 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:28:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:28:05 INFO - Running setup.py (path:/tmp/pip-97E0YJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:28:05 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:28:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:28:05 INFO - Running setup.py (path:/tmp/pip-UJgc7o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:28:05 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:28:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:28:05 INFO - Running setup.py (path:/tmp/pip-wbA26T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:28:05 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:28:05 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:28:05 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:28:05 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:28:05 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:28:05 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:28:05 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:28:05 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:28:05 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:28:05 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:28:05 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:28:05 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:28:06 INFO - Downloading blessings-1.6.tar.gz 16:28:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:28:06 INFO - Installing collected packages: blessings 16:28:06 INFO - Running setup.py install for blessings 16:28:06 INFO - Successfully installed blessings 16:28:06 INFO - Cleaning up... 16:28:07 INFO - Return code: 0 16:28:07 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:28:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:28:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:28:07 INFO - Reading from file tmpfile_stdout 16:28:07 INFO - Current package versions: 16:28:07 INFO - argparse == 1.2.1 16:28:07 INFO - blessings == 1.6 16:28:07 INFO - blobuploader == 1.2.4 16:28:07 INFO - docopt == 0.6.1 16:28:07 INFO - functools32 == 3.2.3-2 16:28:07 INFO - jsonschema == 2.5.1 16:28:07 INFO - manifestparser == 1.1 16:28:07 INFO - mozInstall == 1.13 16:28:07 INFO - mozcrash == 1.0 16:28:07 INFO - mozdebug == 0.1 16:28:07 INFO - mozdevice == 0.51 16:28:07 INFO - mozfile == 1.2 16:28:07 INFO - mozhttpd == 0.7 16:28:07 INFO - mozinfo == 0.10 16:28:07 INFO - mozleak == 0.1 16:28:07 INFO - mozlog == 3.5 16:28:07 INFO - moznetwork == 0.27 16:28:07 INFO - mozprocess == 0.25 16:28:07 INFO - mozprofile == 0.28 16:28:07 INFO - mozrunner == 6.13 16:28:07 INFO - mozscreenshot == 0.1 16:28:07 INFO - mozsystemmonitor == 0.3 16:28:07 INFO - moztest == 0.8 16:28:07 INFO - mozversion == 1.4 16:28:07 INFO - psutil == 3.1.1 16:28:07 INFO - requests == 1.2.3 16:28:07 INFO - wsgiref == 0.1.2 16:28:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:28: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:28:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:28: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:28:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:28:07 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 0xe1c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfe4b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11899c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1186d60>, '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 0x1187a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1187ef0>, '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': '6d15e502d8e29d5d2676d664000001c3-1506122770.22441-1860920291', '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:28:07 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:28:07 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:28:07 INFO - Using env: {'DISPLAY': ':0', 16:28:07 INFO - 'HOME': '/home/cltbld', 16:28:07 INFO - 'LANG': 'en_US.UTF-8', 16:28:07 INFO - 'LANGUAGE': 'en_US:en', 16:28:07 INFO - 'LOGNAME': 'cltbld', 16:28:07 INFO - 'MAIL': '/var/mail/cltbld', 16:28:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:28:07 INFO - 'MOZ_NO_REMOTE': '1', 16:28:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:28:07 INFO - 'NO_EM_RESTART': '1', 16:28:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:28:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:28:07 INFO - 'PWD': '/builds/slave/test', 16:28:07 INFO - 'SHELL': '/bin/bash', 16:28:07 INFO - 'SHLVL': '1', 16:28:07 INFO - 'TERM': 'linux', 16:28:07 INFO - 'TMOUT': '86400', 16:28:07 INFO - 'USER': 'cltbld', 16:28:07 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1506122770.22441-1860920291', 16:28:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:28:07 INFO - '_': '/tools/buildbot/bin/python'} 16:28:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:28:07 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:28:07 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:28:07 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:28:07 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:28:07 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:28:07 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:28:07 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:28:07 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:28:07 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:28:07 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 16:28:07 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:28:07 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:28:07 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:28:07 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:28:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 16:28:08 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:28:08 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:28:08 INFO - Installing collected packages: simplejson 16:28:08 INFO - Running setup.py install for simplejson 16:28:08 INFO - building 'simplejson._speedups' extension 16:28:08 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:28:09 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:28:09 INFO - Successfully installed simplejson 16:28:09 INFO - Cleaning up... 16:28:09 INFO - Return code: 0 16:28:09 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:28:09 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:28:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:28:09 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:28:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:28:09 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 0xe1c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfe4b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11899c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1186d60>, '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 0x1187a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1187ef0>, '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': '6d15e502d8e29d5d2676d664000001c3-1506122770.22441-1860920291', '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:28:09 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:28:09 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:28:09 INFO - Using env: {'DISPLAY': ':0', 16:28:09 INFO - 'HOME': '/home/cltbld', 16:28:09 INFO - 'LANG': 'en_US.UTF-8', 16:28:09 INFO - 'LANGUAGE': 'en_US:en', 16:28:09 INFO - 'LOGNAME': 'cltbld', 16:28:09 INFO - 'MAIL': '/var/mail/cltbld', 16:28:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:28:09 INFO - 'MOZ_NO_REMOTE': '1', 16:28:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:28:09 INFO - 'NO_EM_RESTART': '1', 16:28:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:28:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:28:09 INFO - 'PWD': '/builds/slave/test', 16:28:09 INFO - 'SHELL': '/bin/bash', 16:28:09 INFO - 'SHLVL': '1', 16:28:09 INFO - 'TERM': 'linux', 16:28:09 INFO - 'TMOUT': '86400', 16:28:09 INFO - 'USER': 'cltbld', 16:28:09 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1506122770.22441-1860920291', 16:28:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:28:09 INFO - '_': '/tools/buildbot/bin/python'} 16:28:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:28:09 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:28:09 INFO - Cleaning up... 16:28:09 INFO - Return code: 0 16:28:09 INFO - Running post-action listener: _resource_record_post_action 16:28:09 INFO - Running post-action listener: _start_resource_monitoring 16:28:09 INFO - Starting resource monitoring. 16:28:09 INFO - [mozharness: 2017-09-22 23:28:09.983428Z] Finished create-virtualenv step (success) 16:28:09 INFO - [mozharness: 2017-09-22 23:28:09.983943Z] Running install step. 16:28:09 INFO - Running pre-action listener: _resource_record_pre_action 16:28:09 INFO - Running main action method: install 16:28:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:28:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:28:10 INFO - Reading from file tmpfile_stdout 16:28:10 INFO - Detecting whether we're running mozinstall >=1.0... 16:28:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:28:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:28:10 INFO - Reading from file tmpfile_stdout 16:28:10 INFO - Output received: 16:28:10 INFO - Usage: mozinstall [options] installer 16:28:10 INFO - Options: 16:28:10 INFO - -h, --help show this help message and exit 16:28:10 INFO - -d DEST, --destination=DEST 16:28:10 INFO - Directory to install application into. [default: 16:28:10 INFO - "/builds/slave/test"] 16:28:10 INFO - --app=APP Application being installed. [default: firefox] 16:28:10 INFO - mkdir: /builds/slave/test/build/application 16:28:10 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:28:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 16:28:27 INFO - Reading from file tmpfile_stdout 16:28:27 INFO - Output received: 16:28:27 INFO - /builds/slave/test/build/application/firefox/firefox 16:28:27 INFO - Running post-action listener: _resource_record_post_action 16:28:27 INFO - [mozharness: 2017-09-22 23:28:27.992452Z] Finished install step (success) 16:28:27 INFO - [mozharness: 2017-09-22 23:28:27.992706Z] Running setup-mitmproxy step. 16:28:27 INFO - Running pre-action listener: _resource_record_pre_action 16:28:27 INFO - Running main action method: setup_mitmproxy 16:28:27 INFO - Skipping: mitmproxy is not required 16:28:27 INFO - Running post-action listener: _resource_record_post_action 16:28:27 INFO - [mozharness: 2017-09-22 23:28:27.993931Z] Finished setup-mitmproxy step (success) 16:28:27 INFO - [mozharness: 2017-09-22 23:28:27.994155Z] Running run-tests step. 16:28:27 INFO - Running pre-action listener: _resource_record_pre_action 16:28:27 INFO - Running pre-action listener: _set_gcov_prefix 16:28:27 INFO - Running main action method: run_tests 16:28:27 WARNING - Try message not found. 16:28:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:28:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:28:28 INFO - Python 2.7.3 16:28:28 INFO - Return code: 0 16:28:28 INFO - grabbing minidump binary from tooltool 16:28:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:28:28 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 0x1186d60>, '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 0x1187a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1187ef0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:28:28 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:28:28 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:28:28 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:28:28 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:28:28 INFO - Return code: 0 16:28:28 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 16:28:28 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:28:28 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:28:28 INFO - ENV: RUST_BACKTRACE is now full 16:28:28 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 16:28:28 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:28:28 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/cbFdpQ8wTE6HRI4Bano6mQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-023', '--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:28:28 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/cbFdpQ8wTE6HRI4Bano6mQ/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-023 --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:28:28 INFO - Using env: {'DISPLAY': ':0', 16:28:28 INFO - 'HOME': '/home/cltbld', 16:28:28 INFO - 'LANG': 'en_US.UTF-8', 16:28:28 INFO - 'LANGUAGE': 'en_US:en', 16:28:28 INFO - 'LOGNAME': 'cltbld', 16:28:28 INFO - 'MAIL': '/var/mail/cltbld', 16:28:28 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:28:28 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:28:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:28:28 INFO - 'MOZ_NO_REMOTE': '1', 16:28:28 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:28:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:28:28 INFO - 'NO_EM_RESTART': '1', 16:28:28 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:28:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:28:28 INFO - 'PWD': '/builds/slave/test', 16:28:28 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:28:28 INFO - 'RUST_BACKTRACE': 'full', 16:28:28 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 16:28:28 INFO - 'SHELL': '/bin/bash', 16:28:28 INFO - 'SHLVL': '1', 16:28:28 INFO - 'TERM': 'linux', 16:28:28 INFO - 'TMOUT': '86400', 16:28:28 INFO - 'USER': 'cltbld', 16:28:28 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1506122770.22441-1860920291', 16:28:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:28:28 INFO - '_': '/tools/buildbot/bin/python'} 16:28:28 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/cbFdpQ8wTE6HRI4Bano6mQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-023', '--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:28:28 INFO - mozversion application_buildid: 20170922215617 16:28:28 INFO - mozversion application_changeset: 3d72fdb0e561ea59d9e5850c3e71367dbb8a7148 16:28:28 INFO - mozversion application_display_name: Nightly 16:28:28 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:28:28 INFO - mozversion application_name: Firefox 16:28:28 INFO - mozversion application_remotingname: firefox 16:28:28 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 16:28:28 INFO - mozversion application_vendor: Mozilla 16:28:28 INFO - mozversion application_version: 58.0a1 16:28:28 INFO - mozversion platform_buildid: 20170922215617 16:28:28 INFO - mozversion platform_changeset: 3d72fdb0e561ea59d9e5850c3e71367dbb8a7148 16:28:28 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 16:28:28 INFO - mozversion platform_version: 58.0a1 16:28:28 INFO - using testdate: 1506122908 16:28:28 INFO - actual date: 1506122908 16:28:28 INFO - starting webserver on 'localhost:42834' 16:28:28 INFO - SUITE-START | Running 2 tests 16:28:28 INFO - TEST-START | dromaeo_css 16:28:28 INFO - Initialising browser for dromaeo_css test... 16:28:28 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:42834/getInfo.html -profile /tmp/tmpCX9jSh/profile 16:28:28 INFO - TEST-INFO | started process 28756 (/builds/slave/test/build/application/firefox/firefox http://localhost:42834/getInfo.html) 16:28:33 INFO - TEST-INFO | 28756: exit 0 16:28:33 INFO - Browser initialized. 16:28:33 INFO - Running cycle 1/1 for dromaeo_css test... 16:28:33 INFO - Using env: {'DISPLAY': ':0', 16:28:33 INFO - 'HOME': '/home/cltbld', 16:28:33 INFO - 'JSGC_DISABLE_POISONING': '1', 16:28:33 INFO - 'LANG': 'en_US.UTF-8', 16:28:33 INFO - 'LANGUAGE': 'en_US:en', 16:28:33 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 16:28:33 INFO - 'LOGNAME': 'cltbld', 16:28:33 INFO - 'MAIL': '/var/mail/cltbld', 16:28:33 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:28:33 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:28:33 INFO - 'MOZ_CRASHREPORTER': '1', 16:28:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:28:33 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 16:28:33 INFO - 'MOZ_NO_REMOTE': '1', 16:28:33 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:28:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:28:33 INFO - 'NO_EM_RESTART': '1', 16:28:33 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:28:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:28:33 INFO - 'PWD': '/builds/slave/test', 16:28:33 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:28:33 INFO - 'RUST_BACKTRACE': 'full', 16:28:33 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 16:28:33 INFO - 'SHELL': '/bin/bash', 16:28:33 INFO - 'SHLVL': '1', 16:28:33 INFO - 'TERM': 'linux', 16:28:33 INFO - 'TMOUT': '86400', 16:28:33 INFO - 'USER': 'cltbld', 16:28:33 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1506122770.22441-1860920291', 16:28:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:28:33 INFO - '_': '/tools/buildbot/bin/python'} 16:28:33 INFO - TEST-INFO | started process 28993 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpCX9jSh/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 16:28:34 INFO - PID 28993 | 16:28:34 INFO - PID 28993 | (/builds/slave/test/build/application/firefox/firefox:29043): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 16:28:34 INFO - PID 28993 | 16:28:44 INFO - PID 28993 | RSS: Main: 141078528 16:28:44 INFO - PID 28993 | 16:31:42 INFO - PID 28993 | Cycle 1(1): loaded http://localhost:42834/tests/dromaeo/cssquery-dojo.html (next: http://localhost:42834/tests/dromaeo/cssquery-ext.html) 16:31:43 INFO - PID 28993 | RSS: Main: 145932288 16:31:43 INFO - PID 28993 | 16:34:45 INFO - PID 28993 | Cycle 1(1): loaded http://localhost:42834/tests/dromaeo/cssquery-ext.html (next: http://localhost:42834/tests/dromaeo/cssquery-jquery.html) 16:34:45 INFO - PID 28993 | RSS: Main: 146128896 16:34:45 INFO - PID 28993 | 16:37:47 INFO - PID 28993 | Cycle 1(1): loaded http://localhost:42834/tests/dromaeo/cssquery-jquery.html (next: http://localhost:42834/tests/dromaeo/cssquery-mootools.html) 16:37:48 INFO - PID 28993 | RSS: Main: 148328448 16:37:48 INFO - PID 28993 | 16:40:49 INFO - PID 28993 | Cycle 1(1): loaded http://localhost:42834/tests/dromaeo/cssquery-mootools.html (next: http://localhost:42834/tests/dromaeo/cssquery-prototype.html) 16:40:50 INFO - PID 28993 | RSS: Main: 146784256 16:40:50 INFO - PID 28993 | 16:43:53 INFO - PID 28993 | Cycle 1(1): loaded http://localhost:42834/tests/dromaeo/cssquery-prototype.html (next: http://localhost:42834/tests/dromaeo/cssquery-yui.html) 16:43:54 INFO - PID 28993 | RSS: Main: 148439040 16:43:54 INFO - PID 28993 | 16:46:55 INFO - PID 28993 | Cycle 1(1): loaded http://localhost:42834/tests/dromaeo/cssquery-yui.html (next: http://localhost:42834/tests/dromaeo/cssquery-dojo.html) 16:46:55 INFO - PID 28993 | RSS: Main: 148488192 16:46:55 INFO - PID 28993 | 16:46:55 INFO - PID 28993 | __start_tp_report 16:46:55 INFO - PID 28993 | _x_x_mozilla_page_load 16:46:55 INFO - PID 28993 | _x_x_mozilla_page_load_details 16:46:55 INFO - PID 28993 | |i|pagename|runs| 16:46:55 INFO - PID 28993 | |0;dojo.html;3862.3434016217243;3876.592957739437;3906.1797022625246;3919.9804000980007;3920.960790392097;6216.316205217422;6350.682465876711;6372.681365931702;6388.329225431333;6446.903296450557;6337.271213810413;6371.553991220617;6380.4257616814475;6387.169667943161;6399.648019358933;5662.716864156786;5663.688497132656;5700.629459085167;5711.8000869969555;5712.600117991742;5771.942280577183;5775.509081728037;5780.2196703444915;5789.073748200289;5810.970945145268;5698.230738850244;5700.600957932947;5762.769489220426;5769.7115144242825;5797.971010144965;5283.128283833202;5512.5589952803675;5625.156226566007;5636.323641163087;5788.189653448541;5606.719664016783;5616.831495055155;5672.829815105512;5689.459501347365;5705.1727499512335;6191.752329906798;6194.349593292684;6271.592346497463;6313.779017734403;6362.0456931460185;6829.146356705412;6882.483813713991;6895.138107736533;6944.930550694479;6951;7014.403775679073;7057.929420705778;7075.964620176866;7082.185548661918;7084.92915070853;6401.871962560769;6424.93575064248;6441.291457939623;6503.894337962558;6509.5768775029;7117.857642847114;7119;7195.13658361;7196.136463624399;7217.7473788417155;6789.422899053636;6844.965775171192;6863.416609588241;6874.931250687429;6876.484263680244;6903.378695917391;6911.7926462206215;6953.130858642669;7000.299970002959;7008.719651213996;6717.630530320879;6807.2512023677355;6853.691583878737;6967.65161741921;6976.7558135465015;6875.48433867462;6956.860862782716;6963.303669632996;6993.405560527311;7013.4038606719005;4980.053789780002;5050.823221187241;5063.038022775734;5067.797288108508;5077.365329333833;4468.151051300242;4484.618807401343;4493.056458143761;4518;4558.544145585415;6222.502199824004;6267.811965641038;6276.46650034743;6290.308066112724;6295.559310848196;6799.898001529981;6819.215790184192;6862.965685171542;6910.412614927787;6965.233824279325;6255.499560035186;6335.68321583919;6382.712777925005;6387.361263873669;6406.551541392151;5384.111621582395;5546.27898373217;5606.663600183921;5616.0171969905105;5617.691026993472;7123.109611298588;7198.5320954137815;7284.745033923788;7304.817379565553;7307.232740562271;6845.965770171118;6858.3827455529245;6923.411510021705;6928.203256625351;6934.4452443805385;7152.85694286101;7160.176579693402;7250.673719682522;7255.854882902207;7282.380997615051;6704.7988560343265;6770.424513916175;6786.253512113565;6801.829954251183;6907.378335949788;6962.756303529352;6969.54697944632;6977.965110174417;6978.860422791414;7000.124984376953;6673.899891501728;6681.465482761465;6693.1298931138645;6772.119624448987;6795.864082718417;6666.799996000128;6667.066610674412;6750.088738020305;6752.93247067523;6765.9661701691175;6433.388828061326;6561.475081993333;6607;6677;6690.866182676222;6772.288909664611;6774.254831968381;6790.830229244309;6818.522703410912;6832.282610325844;6406.167198264196;6472.385123413268;6504.60972341661;6571.408573228432;6586.242582102927 16:46:55 INFO - PID 28993 | |1;ext.html;897.4480694372962;930.1256818590525;958.2477754962351;964.0407794244726;976.7558110472382;43949;44905;45001.87495312615;45009.54990450094;45055;42111.36832947501;42390.7880460598;42429.787851060704;42888.356674649905;43586.56413435865;8311.83376332473;8458.154184581523;8463.4075614707;8468.44955077921;8474.23731864132;8239.83520329593;8438.198371154731;8439.535825529594;8442.07137214906;8445.535495547743;8418.284445822112;8423.073461919215;8423.326133909302;8460.746177614681;8476.745697629081;8390;8392.286655634249;8406.78983025423;8430.32557395407;8433.578321083922;8284.08875023747;8333.083360830304;8338.791530211733;8350.749477515685;8376.664933402657;41145.177096457905;41305.586944130475;41330;41483.1703365934;41552.37671434931;3759.7744135351695;3770.1517158639085;3794.7723136611617;3796.924061518782;3808.067023579213;32916;33262.50106248408;33295.50056749151;33379.16552086193;33442.1639459013;5394.379646340682;5430.266913966643;5436.320459942507;5544.750486228089;5553.722313884289;195916;212806;214988;215229;215516;47132;52529;53361.1995820063;53573;53628;56412.717936410874;56477;56713;56789.43210567924;56901.71549142311;16856.74714879278;17086.231119599648;17122;17231.22459489326;17244.741328880078;14198.929005354907;14220.360083796253;14233;14235.64410889736;14253.857461425254;14784.186869722053;14950.626234344229;14962.625934351729;14971.70056598862;15097.54707358781;5691.231683722644;5701.315842098975;5737.3688894221605;5760.481556659837;5903.232579764689;909.1999040844147;928.4058202750246;942.7266092833001;945.4280160502934;954.2747511891013;23245.186418475274;23256;23591;23841;23895.880520597635;1036.8133735927454;1038.8857225705167;1039.1375158618328;1042.744527590745;1043.0716662170673;12177.69555761113;12301.630951071482;12370.44333005017;12377.133600647869;12529.31088790107;266458;267539;268402;268571;269004;57480.42519574834;57682.42317576854;57741.4225857736;57928;58553.70723146357;1507.3895072495875;1532.6474910770362;1556.7587024011295;1570.3797000184863;1573.732465480871;22682.659760103943;22844.31467055991;22887.88556057209;23339.883300583388;23402.18092366759;41128.38307425451;41255.381169281885;41358;41376.37935430911;41389.37915931203;229003;229219;229674;229679;230400;36555.81722091373;39787.80106099451;40061.599384005785;40686;40694;49587.50412495829;50363;50662;50997.49002510076;51187.488125118274;36669.63330366662;36824.26351472902;36871.815640921624;36900.81549592235;37054.888353349445;36943.891683249545;37091.814540927124;37092.443613345284;37167.62832371642;37219;43555;43580.692579222676;44010.77994610006;44120.55879441293;44169.77915110404;35555.288894221456;35566;35683;36047.197640118415;36216;26292;26844.463110738066;27042.32394190161;27189.86405067962;27256.863715681295 16:46:55 INFO - PID 28993 | |2;jquery.html;615.2033117113339;616.3682225718641;626.1734510446213;626.2922897126247;633.5596760251624;22274;22449.32652020438;23329;23429.999999999978;23450.296491105295;24811.62782558263;25630.231093067192;25756.871215643943;25768.74231257687;25811;6459.967700161493;6679.198496180441;6682.465402767767;6683.13119294491;6683.933160668404;6461.289258181596;6480.805575832709;6677.799666010027;6690.464762818963;6691.498137639672;6612.966935165341;6630.933690663104;6644;6644.60132392058;6646.302138275484;6497.48020158391;6625.0393692914495;6629.2376376716575;6631.038499417606;6671;6267.05994100884;6267.373262673696;6271.122042913996;6275.8744825103695;6284.99440089588;29680.25799355012;30177.09468715942;30272.394552109054;30308.54537181944;30314.090577282717;2332.07882886259;2344.038944032938;2349.447879748258;2353.446939969106;2360.268316821791;19679.508012299662;20008;20339.694904576445;20518.897405513027;20525.4868628284;2491.825572209946;2550.5664037113734;2575.639410482534;2597.9090731824294;2606.4787042591556;136757.31621341928;137753;143654;144229;146064.26967865092;15071.246437678072;15433.922830385776;16143.547080762788;16467.58831029234;16833.074180920168;25765.8711706444;25965.480690386463;25971.350716231867;25986.74013259881;26006.479870402487;10709.518071686636;10757.193210509087;10862.619808306672;10898.073663738513;10902.672919812418;9820.165285950632;9859.211263098934;9861.309708320492;9946.955569665226;9963.202943764481;9375.671851485165;9530.237580993504;9545.61817527305;9687.51562421876;9974.601015959426;2678.5312570300507;2697.7976651751196;2795.692473827877;2851.9144425667264;2857.4999375109273;1045.7385653586603;1048.4548035021899;1053.1785207538132;1053.4926874039736;1063.6702622187147;20675.689864652042;21072.683909741365;21159;21348.78651213499;21444.57110857774;1084.983725244122;1100.5102729285552;1105.0772604874858;1117.1565468071553;1127.1321082766224;11412.48643811014;11460.369679667532;11481.770364592661;11500.309981401142;11876.465559049864;556017;587940;597707;602053;611806;122068;123908;125769.37115314364;126508;129760;1242.142921384242;1246.0965799795258;1268.765278423476;1272.3002348708358;1274.4010315151863;18489.05469907306;18600.06999649985;18745.062746862328;19011;19022.23911043543;58813.70593147007;58895.11657325229;60039;60129.398706012376;60758;76096;76812.61593691996;76893;77148.61425692835;77576.22423775916;15044.323005464561;15187.924060379628;15188.088714677155;15338.693226135638;15371.231438428258;22544.661830072233;23183.420414489774;23342.183023594094;23436.414089647897;23452;18883.150258238136;18902.243910243436;19221.807781922562;19372.61254774925;19384.8061519383;16624.251908663897;16791.496255112364;16895;17375.47873563795;17485.737713934046;19289;19370.8062919369;19382.321618743277;19505.8049419504;19511.414657560297;14208.087342095052;15003.77494337564;15018.24908754536;15030.248487575796;15070;19209.423717288504;19311.517212069248;19431.514212144808;19433.708494372313;19447.416577502696 16:46:55 INFO - PID 28993 | |3;mootools.html;1456.8980171388;1511.6069821846318;1511.6976604679066;1515.7953676253703;1534.5319677498362;16444.34222631095;17149.485515434524;17158.056306903116;17171.141442927867;17175.742363864552;16538.50384488467;16749.66500669986;16825.074620895844;16834;16870.746938795928;2756.462489814492;2771.5815580539997;2801.9159425217276;2821.210061182862;2827.533456979596;2736.315921019745;2769.556870900656;2772.0436449425015;2772.819766715167;2775.5004099262123;2636.4595257972164;2706.742859428361;2731.112388473744;2733.986330068357;2737.9452410951767;2658;2663.42736311692;2692.67687877454;2693.205504376204;2694.663167104112;2533.4933013397394;2580.44520428107;2580.651612032389;2642.6036094585775;2727.4136060747032;16396.67206655872;16620.168991550494;16628.33486660532;16635.08507032113;16648.417305394374;8346.123657015987;8357.247847693738;8581.184787445183;8645.351598630128;8667.609957551926;5666.405027472098;5746.885062298772;5828.883422331572;5829.912551311733;5832.766689332421;2038.9184432622678;2060.7733149353558;2071.6271071207175;2101.4746313421647;2134.6157691615504;29779.25551861199;30078.54882176769;30139.19164850116;30190.396192076034;30300.090997269897;6954.478414118961;7036.9296307036275;7076.575405475688;7203.207647158808;7224;19404.320848770225;19495;19499.805001950084;19509.317173898846;19527.121279542454;8302.460340077876;8961.507117108491;9163.58763855628;9173.58718857653;9184.90815091854;7975.2423519765525;8027.678892844221;8043.316318112909;8103.4732742372735;8116.35069194463;5650.124230744241;5777.797777077866;5886.852828679317;5903.0555111182;5907.350191478934;1744.2151032035636;1744.5725797179518;1751.991240043817;1761.9868575569099;1768.3015208992376;968.4092703450889;974.2060220919927;985.6008316631776;1008.9848652270222;1011.5195282240877;8523.764054212104;8573.356998225157;8585;8618.39671223021;8637.265832404317;772.6986475274647;809.5911564659809;809.8501777171124;819.5287709566903;828.7016673997356;6239.625622462667;6409.647469389229;6627.900581491282;6656.134702488645;6662.6335551545135;25287.74712252854;25508.23475295707;25544.616830747553;25813.096541620955;26188.34529136749;24930.50138997246;25374.619380709304;25452.236432907044;25489.36276593063;25746.871265643553;11105.83341249882;12136.33250171249;12369.134160608852;12485.500579976699;12506.062045346636;9365.57854896545;9914.206863450763;10022.799544008933;10066.748331291776;10250.487475626338;21434.464138396666;21476.570468590853;21614.891925540272;21710.45723856853;21834.454138646026;22416.439589010406;22479.550408992054;22747.317580472612;22793.202237921592;23143.884280578488;13351.198928064347;13463.192208467523;13691.657708557366;13695.109817861807;13696;15378;15671.451499197474;15696.29366678525;15702.607434814221;15733.842661573695;11943.16397852142;12492.875071249171;12536.937315313366;12895.548655797002;12931.5473958411;12452.501899923902;12518.499260029497;12565.246085234916;12791.42438590247;12854.935725321313;10559.419231942316;10809.13526917829;10864.456777160953;10866.18503612248;10908.836367454653;8304.916950830415;8340.833183336177;8534.530600817016;8543.231109200202;8561.400701950804;8067.475614085066;8154.633041513028;8156.184381561797;8170.754877353689;8175.550344730859 16:46:55 INFO - PID 28993 | |4;prototype.html;557.3673880146034;564.2889958652097;572.6306532286675;578.8036733680814;580.7339998802611;5849.678267695281;6119.173911521943;6370.203724534434;6416.454601358879;6435.935640643592;6182.412670796279;6235.879800867704;6284.585968157162;6288.811335659926;6322.715477803498;4944.357233559632;5030.170021946375;5058.038972595195;5076.695398276097;5082.567981721558;4969.0807200668;5042.831582419558;5042.848714538552;5094.898102037975;5098.337216161894;4937.580305674022;5001.899962000758;5010.473900240477;5023.572996295319;5029.245613158019;4936.432310284327;4976.726280054596;4992.775325110342;5003.699778013331;5014.448410674841;4562.680612357103;4576.519465456146;4577.588017078446;4614.723116613014;4615.584597386218;6047.334793172748;6126.326104128542;6169.907451388232;6187.102870083858;6237.906431403532;5816.621919575214;5842.883142337172;5862.472377486047;5889.411058894119;5917;6621.668916554201;6663.133792606978;6680.298568650271;6689.799306020778;6734.3602357775935;4838.443578988426;5065.860181459179;5095.617828662828;5097.43928167905;5139.614528910347;6775.3902148806355;6796.25241223465;6858.005589189589;6960.234374218832;6967.039008421451;5170.431252562215;5179.974100129475;5239.266502689627;5240.580753539708;5252.9737351313;5223.52988231061;5230.895382092337;5264.6051546134195;5284.656497327662;5335.906139241446;5848.941510584925;5857.736401861926;5872.765089396376;5877.294724632986;5911.733971971272;6650.736360091567;6651.933480665131;6673.4661227101715;6698.7320507179165;6715.93284067153;1752.3516298969207;1786.1069465267367;1814.4284550366592;1816.273490603769;1838.1452624529659;1230.809224570193;1285.6014635463036;1285.884270415667;1300.4524615707278;1320.3992183556459;6155.322914479404;6170.444659980623;6218.284897236876;6225.128482012522;6246.15676883624;6674.933250667431;6682.966585167043;6719.227288861844;6831.726730930806;6858.622775747382;6144.310314663414;6145.324014358417;6151.938480615226;6154.107654390088;6166.907496387557;3432.3993301172195;3518.5249991251367;3538.6992105670793;3557.719221080409;3573.10672331917;6890.965545172242;6912.55068420551;6990.650467476606;7002.474814388839;7041.647917604099;6802.3537763912345;6853.862922741617;6887.724491020404;6890.31096890307;6898.379145876896;6902.377096703221;7000.789976300617;7044.647767611599;7073.5048546602275;7122.679479423542;6746.359095885883;6748.527603067738;6779;6789.864202715819;6796.320367963164;6731.865362692621;6740.73037078531;6753.716793809161;6823.624700641512;6827.2490026098085;7026.191987921455;7072.71709131629;7089.503734738519;7094.4679149062995;7168.319009694071;5209.8277887475015;5215.400228973716;5216.869578260574;5248.89502209946;5256.78972841082;6305.274893387135;6567.784959782455;6685.598864068172;6777.390034896883;6810.901040026875;6459.61242325462;6474.320196379313;6493.577917435352;6525;6614.603123812394;6440.871182576416;6496;6516.413522782972;6536.901946470712;6566.507511936528;6201.658908760061;6333.556651034383;6451.6774161290805;6452.774152904626;6513.5114866384265;6504.593300478664;6634.270230274762;6658.1344425224415;6697.321071571216;6713.429358504582;6347.68261586928;6414.903776443264;6517.641529715911;6549.410553050248;6559.245686746085 16:46:55 INFO - PID 28993 | |5;yui.html;967.4340510801181;970.6457143142756;974.8976357482464;999.3654029691144;1007.2395341517154;20376.286829960954;20581.176752929903;20591.485212869688;20644.277450289243;23659.76340236597;23337.066517339328;23356.182533611325;23375.1818686346;23385.415364615892;23409.882950585223;1074.785042991401;1077.2943721862155;1079.692287698006;1079.7786453776957;1086.4839201379361;1019.8368261078228;1020.0411613083678;1051.437480947693;1088.29260980363;1095.3427943234035;1143.6283207957406;1151.0158814213853;1155.5608868629952;1155.647527504111;1155.6706338693475;1149.620625193684;1152.4353066997112;1154.1343992005995;1154.1978325064083;1154.8267759836049;845.6659619450282;848.3891598049395;848.749618862437;848.9066202717696;850.1668365002271;21437.785622143892;21500.247491337726;21849.67225491603;22065.00707468168;22283.331500055025;23224;23246.76753232463;23248.07007719689;23618.881905590533;23674.881625591934;18420.078996050277;18603;18653.720194197096;18736.531586710305;18736.90631546847;3898.3567711327596;3938.6455219030427;3960.425738267964;3968.5436174839974;3972.126132250929;153978;155706.99999999886;158805;160627;161080;17542.824571754118;17751.644967100845;17758.822411775716;17792.3772667956;17866.642667146585;34868;35012.124696882274;35095.4735678965;35295.82352088223;35330.64669353273;9056.728298150934;9185.035571265054;9202.953985230164;9247.352685312098;9299.581518831668;4004.039030632627;4205.936910946339;4421.093675796518;4523.660725445605;4531.003179300548;6871.072405225331;6895.55178913379;6906.861862762717;6910.550814197061;6963.234044255128;1315.5724389573427;1325.701717113661;1347.8786909178025;1348.5684580934007;1351.7228968061524;746.4700062955254;757.7575175943755;761.4517547365888;774.7327172125607;778.9844203115906;13765.449382024806;13831;14203.644908877153;14591.343389547495;14668.753988513014;723.667113127967;735.8822588385833;745.3329270303049;747.2975403121048;748.356413484403;6354.459870910932;6378.649174295366;6390.201224846895;6440.194975628046;6562.540622156499;142905;143966;146382;147141.26429367784;147516.26241868723;130048.3497582525;131036;132991.33504332416;133209;137380;109112.45443772888;109742.45128774305;110150;111051.44474277577;114438;12539.435725392197;12553.435095420546;12564;12732.299723514925;12829;56366.71816640891;56718.43281567297;57404.42595574158;57511.71244143753;58922.82154356975;67064.6646766763;67299;68377;68530.3146968524;70731.64634176796;17893.821061789735;17898.194581243613;18049.278028879224;18137;18150.092495375444;20334;20342.389728308175;20452.590948181252;20509;20622.793772062687;18050.819491804912;18320.45038648843;18433.262669493608;18473.261069557066;18486.81513184905;17349.653006940043;17644.1177941105;17674.204660790554;17680.2043908027;17880.28478860883;18360.99014554159;18468.907655462175;18549.35077272289;18552.350667726565;18664.906675466536;13896.72206555843;14035.157890526601;14089.506867259597;14185.290735463392;14224.71550568962;14603.269836508345;14632;14694;14746.188959607325;14799.704005920037 16:46:55 INFO - PID 28993 | __end_tp_report 16:46:55 INFO - PID 28993 | __start_cc_report 16:46:55 INFO - PID 28993 | _x_x_mozilla_cycle_collect,262 16:46:55 INFO - PID 28993 | __end_cc_report 16:46:55 INFO - PID 28993 | __startTimestamp1506124015836__endTimestamp 16:46:55 INFO - PID 28993 | 16:46:55 INFO - PID 28993 | ------- Summary: start ------- 16:46:55 INFO - PID 28993 | Number of tests: 6 16:46:55 INFO - PID 28993 | 16:46:55 INFO - PID 28993 | [#0] dojo.html Cycles:165 Average:6352.80 Median:6607.00 stddev:783.12 (11.9%) stddev-sans-first:760.76 16:46:55 INFO - PID 28993 | Values: 3862.3 3876.6 3906.2 3920.0 3921.0 6216.3 6350.7 6372.7 6388.3 6446.9 6337.3 6371.6 6380.4 6387.2 6399.6 5662.7 5663.7 5700.6 5711.8 5712.6 5771.9 5775.5 5780.2 5789.1 5811.0 5698.2 5700.6 5762.8 5769.7 5798.0 5283.1 5512.6 5625.2 5636.3 5788.2 5606.7 5616.8 5672.8 5689.5 5705.2 6191.8 6194.3 6271.6 6313.8 6362.0 6829.1 6882.5 6895.1 6944.9 6951.0 7014.4 7057.9 7076.0 7082.2 7084.9 6401.9 6424.9 6441.3 6503.9 6509.6 7117.9 7119.0 7195.1 7196.1 7217.7 6789.4 6845.0 6863.4 6874.9 6876.5 6903.4 6911.8 6953.1 7000.3 7008.7 6717.6 6807.3 6853.7 6967.7 6976.8 6875.5 6956.9 6963.3 6993.4 7013.4 4980.1 5050.8 5063.0 5067.8 5077.4 4468.2 4484.6 4493.1 4518.0 4558.5 6222.5 6267.8 6276.5 6290.3 6295.6 6799.9 6819.2 6863.0 6910.4 6965.2 6255.5 6335.7 6382.7 6387.4 6406.6 5384.1 5546.3 5606.7 5616.0 5617.7 7123.1 7198.5 7284.7 7304.8 7307.2 6846.0 6858.4 6923.4 6928.2 6934.4 7152.9 7160.2 7250.7 7255.9 7282.4 6704.8 6770.4 6786.3 6801.8 6907.4 6962.8 6969.5 6978.0 6978.9 7000.1 6673.9 6681.5 6693.1 6772.1 6795.9 6666.8 6667.1 6750.1 6752.9 6766.0 6433.4 6561.5 6607.0 6677.0 6690.9 6772.3 6774.3 6790.8 6818.5 6832.3 6406.2 6472.4 6504.6 6571.4 6586.2 16:46:55 INFO - PID 28993 | 16:46:55 INFO - PID 28993 | [#1] ext.html Cycles:180 Average:42108.58 Median:41136.78 stddev:61645.85 (149.9%) stddev-sans-first:61741.12 16:46:55 INFO - PID 28993 | Values: 897.4 930.1 958.2 964.0 976.8 43949.0 44905.0 45001.9 45009.5 45055.0 42111.4 42390.8 42429.8 42888.4 43586.6 8311.8 8458.2 8463.4 8468.4 8474.2 8239.8 8438.2 8439.5 8442.1 8445.5 8418.3 8423.1 8423.3 8460.7 8476.7 8390.0 8392.3 8406.8 8430.3 8433.6 8284.1 8333.1 8338.8 8350.7 8376.7 41145.2 41305.6 41330.0 41483.2 41552.4 3759.8 3770.2 3794.8 3796.9 3808.1 32916.0 33262.5 33295.5 33379.2 33442.2 5394.4 5430.3 5436.3 5544.8 5553.7 195916.0 212806.0 214988.0 215229.0 215516.0 47132.0 52529.0 53361.2 53573.0 53628.0 56412.7 56477.0 56713.0 56789.4 56901.7 16856.7 17086.2 17122.0 17231.2 17244.7 14198.9 14220.4 14233.0 14235.6 14253.9 14784.2 14950.6 14962.6 14971.7 15097.5 5691.2 5701.3 5737.4 5760.5 5903.2 909.2 928.4 942.7 945.4 954.3 23245.2 23256.0 23591.0 23841.0 23895.9 1036.8 1038.9 1039.1 1042.7 1043.1 12177.7 12301.6 12370.4 12377.1 12529.3 266458.0 267539.0 268402.0 268571.0 269004.0 57480.4 57682.4 57741.4 57928.0 58553.7 1507.4 1532.6 1556.8 1570.4 1573.7 22682.7 22844.3 22887.9 23339.9 23402.2 41128.4 41255.4 41358.0 41376.4 41389.4 229003.0 229219.0 229674.0 229679.0 230400.0 36555.8 39787.8 40061.6 40686.0 40694.0 49587.5 50363.0 50662.0 50997.5 51187.5 36669.6 36824.3 36871.8 36900.8 37054.9 36943.9 37091.8 37092.4 37167.6 37219.0 43555.0 43580.7 44010.8 44120.6 44169.8 35555.3 35566.0 35683.0 36047.2 36216.0 26292.0 26844.5 27042.3 27189.9 27256.9 16:46:55 INFO - PID 28993 | 16:46:55 INFO - PID 28993 | [#2] jquery.html Cycles:180 Average:38622.40 Median:23335.59 stddev:98697.39 (422.9%) stddev-sans-first:98933.01 16:46:55 INFO - PID 28993 | Values: 615.2 616.4 626.2 626.3 633.6 22274.0 22449.3 23329.0 23430.0 23450.3 24811.6 25630.2 25756.9 25768.7 25811.0 6460.0 6679.2 6682.5 6683.1 6683.9 6461.3 6480.8 6677.8 6690.5 6691.5 6613.0 6630.9 6644.0 6644.6 6646.3 6497.5 6625.0 6629.2 6631.0 6671.0 6267.1 6267.4 6271.1 6275.9 6285.0 29680.3 30177.1 30272.4 30308.5 30314.1 2332.1 2344.0 2349.4 2353.4 2360.3 19679.5 20008.0 20339.7 20518.9 20525.5 2491.8 2550.6 2575.6 2597.9 2606.5 136757.3 137753.0 143654.0 144229.0 146064.3 15071.2 15433.9 16143.5 16467.6 16833.1 25765.9 25965.5 25971.4 25986.7 26006.5 10709.5 10757.2 10862.6 10898.1 10902.7 9820.2 9859.2 9861.3 9947.0 9963.2 9375.7 9530.2 9545.6 9687.5 9974.6 2678.5 2697.8 2795.7 2851.9 2857.5 1045.7 1048.5 1053.2 1053.5 1063.7 20675.7 21072.7 21159.0 21348.8 21444.6 1085.0 1100.5 1105.1 1117.2 1127.1 11412.5 11460.4 11481.8 11500.3 11876.5 556017.0 587940.0 597707.0 602053.0 611806.0 122068.0 123908.0 125769.4 126508.0 129760.0 1242.1 1246.1 1268.8 1272.3 1274.4 18489.1 18600.1 18745.1 19011.0 19022.2 58813.7 58895.1 60039.0 60129.4 60758.0 76096.0 76812.6 76893.0 77148.6 77576.2 15044.3 15187.9 15188.1 15338.7 15371.2 22544.7 23183.4 23342.2 23436.4 23452.0 18883.2 18902.2 19221.8 19372.6 19384.8 16624.3 16791.5 16895.0 17375.5 17485.7 19289.0 19370.8 19382.3 19505.8 19511.4 14208.1 15003.8 15018.2 15030.2 15070.0 19209.4 19311.5 19431.5 19433.7 19447.4 16:46:55 INFO - PID 28993 | 16:46:55 INFO - PID 28993 | [#3] mootools.html Cycles:180 Average:10534.90 Median:14196.87 stddev:7786.14 (54.8%) stddev-sans-first:7778.11 16:46:55 INFO - PID 28993 | Values: 1456.9 1511.6 1511.7 1515.8 1534.5 16444.3 17149.5 17158.1 17171.1 17175.7 16538.5 16749.7 16825.1 16834.0 16870.7 2756.5 2771.6 2801.9 2821.2 2827.5 2736.3 2769.6 2772.0 2772.8 2775.5 2636.5 2706.7 2731.1 2734.0 2737.9 2658.0 2663.4 2692.7 2693.2 2694.7 2533.5 2580.4 2580.7 2642.6 2727.4 16396.7 16620.2 16628.3 16635.1 16648.4 8346.1 8357.2 8581.2 8645.4 8667.6 5666.4 5746.9 5828.9 5829.9 5832.8 2038.9 2060.8 2071.6 2101.5 2134.6 29779.3 30078.5 30139.2 30190.4 30300.1 6954.5 7036.9 7076.6 7203.2 7224.0 19404.3 19495.0 19499.8 19509.3 19527.1 8302.5 8961.5 9163.6 9173.6 9184.9 7975.2 8027.7 8043.3 8103.5 8116.4 5650.1 5777.8 5886.9 5903.1 5907.4 1744.2 1744.6 1752.0 1762.0 1768.3 968.4 974.2 985.6 1009.0 1011.5 8523.8 8573.4 8585.0 8618.4 8637.3 772.7 809.6 809.9 819.5 828.7 6239.6 6409.6 6627.9 6656.1 6662.6 25287.7 25508.2 25544.6 25813.1 26188.3 24930.5 25374.6 25452.2 25489.4 25746.9 11105.8 12136.3 12369.1 12485.5 12506.1 9365.6 9914.2 10022.8 10066.7 10250.5 21434.5 21476.6 21614.9 21710.5 21834.5 22416.4 22479.6 22747.3 22793.2 23143.9 13351.2 13463.2 13691.7 13695.1 13696.0 15378.0 15671.5 15696.3 15702.6 15733.8 11943.2 12492.9 12536.9 12895.5 12931.5 12452.5 12518.5 12565.2 12791.4 12854.9 10559.4 10809.1 10864.5 10866.2 10908.8 8304.9 8340.8 8534.5 8543.2 8561.4 8067.5 8154.6 8156.2 8170.8 8175.6 16:46:55 INFO - PID 28993 | 16:46:55 INFO - PID 28993 | [#4] prototype.html Cycles:180 Average:5638.91 Median:6221.71 stddev:1574.86 (25.3%) stddev-sans-first:1532.39 16:46:55 INFO - PID 28993 | Values: 557.4 564.3 572.6 578.8 580.7 5849.7 6119.2 6370.2 6416.5 6435.9 6182.4 6235.9 6284.6 6288.8 6322.7 4944.4 5030.2 5058.0 5076.7 5082.6 4969.1 5042.8 5042.8 5094.9 5098.3 4937.6 5001.9 5010.5 5023.6 5029.2 4936.4 4976.7 4992.8 5003.7 5014.4 4562.7 4576.5 4577.6 4614.7 4615.6 6047.3 6126.3 6169.9 6187.1 6237.9 5816.6 5842.9 5862.5 5889.4 5917.0 6621.7 6663.1 6680.3 6689.8 6734.4 4838.4 5065.9 5095.6 5097.4 5139.6 6775.4 6796.3 6858.0 6960.2 6967.0 5170.4 5180.0 5239.3 5240.6 5253.0 5223.5 5230.9 5264.6 5284.7 5335.9 5848.9 5857.7 5872.8 5877.3 5911.7 6650.7 6651.9 6673.5 6698.7 6715.9 1752.4 1786.1 1814.4 1816.3 1838.1 1230.8 1285.6 1285.9 1300.5 1320.4 6155.3 6170.4 6218.3 6225.1 6246.2 6674.9 6683.0 6719.2 6831.7 6858.6 6144.3 6145.3 6151.9 6154.1 6166.9 3432.4 3518.5 3538.7 3557.7 3573.1 6891.0 6912.6 6990.7 7002.5 7041.6 6802.4 6853.9 6887.7 6890.3 6898.4 6902.4 7000.8 7044.6 7073.5 7122.7 6746.4 6748.5 6779.0 6789.9 6796.3 6731.9 6740.7 6753.7 6823.6 6827.2 7026.2 7072.7 7089.5 7094.5 7168.3 5209.8 5215.4 5216.9 5248.9 5256.8 6305.3 6567.8 6685.6 6777.4 6810.9 6459.6 6474.3 6493.6 6525.0 6614.6 6440.9 6496.0 6516.4 6536.9 6566.5 6201.7 6333.6 6451.7 6452.8 6513.5 6504.6 6634.3 6658.1 6697.3 6713.4 6347.7 6414.9 6517.6 6549.4 6559.2 16:46:55 INFO - PID 28993 | 16:46:55 INFO - PID 28993 | [#5] yui.html Cycles:180 Average:28418.59 Median:18577.68 stddev:41516.40 (223.5%) stddev-sans-first:41581.70 16:46:55 INFO - PID 28993 | Values: 967.4 970.6 974.9 999.4 1007.2 20376.3 20581.2 20591.5 20644.3 23659.8 23337.1 23356.2 23375.2 23385.4 23409.9 1074.8 1077.3 1079.7 1079.8 1086.5 1019.8 1020.0 1051.4 1088.3 1095.3 1143.6 1151.0 1155.6 1155.6 1155.7 1149.6 1152.4 1154.1 1154.2 1154.8 845.7 848.4 848.7 848.9 850.2 21437.8 21500.2 21849.7 22065.0 22283.3 23224.0 23246.8 23248.1 23618.9 23674.9 18420.1 18603.0 18653.7 18736.5 18736.9 3898.4 3938.6 3960.4 3968.5 3972.1 153978.0 155707.0 158805.0 160627.0 161080.0 17542.8 17751.6 17758.8 17792.4 17866.6 34868.0 35012.1 35095.5 35295.8 35330.6 9056.7 9185.0 9203.0 9247.4 9299.6 4004.0 4205.9 4421.1 4523.7 4531.0 6871.1 6895.6 6906.9 6910.6 6963.2 1315.6 1325.7 1347.9 1348.6 1351.7 746.5 757.8 761.5 774.7 779.0 13765.4 13831.0 14203.6 14591.3 14668.8 723.7 735.9 745.3 747.3 748.4 6354.5 6378.6 6390.2 6440.2 6562.5 142905.0 143966.0 146382.0 147141.3 147516.3 130048.3 131036.0 132991.3 133209.0 137380.0 109112.5 109742.5 110150.0 111051.4 114438.0 12539.4 12553.4 12564.0 12732.3 12829.0 56366.7 56718.4 57404.4 57511.7 58922.8 67064.7 67299.0 68377.0 68530.3 70731.6 17893.8 17898.2 18049.3 18137.0 18150.1 20334.0 20342.4 20452.6 20509.0 20622.8 18050.8 18320.5 18433.3 18473.3 18486.8 17349.7 17644.1 17674.2 17680.2 17880.3 18361.0 18468.9 18549.4 18552.4 18664.9 13896.7 14035.2 14089.5 14185.3 14224.7 14603.3 14632.0 14694.0 14746.2 14799.7 16:46:55 INFO - PID 28993 | -------- Summary: end -------- 16:46:55 INFO - PID 28993 | 16:46:56 INFO - TEST-INFO | 28993: exit 0 16:46:56 INFO - TEST-OK | dromaeo_css | took 1108053ms 16:46:56 INFO - TEST-START | kraken 16:46:56 INFO - Initialising browser for kraken test... 16:46:56 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:42834/getInfo.html -profile /tmp/tmpRdU0kW/profile 16:46:56 INFO - TEST-INFO | started process 29359 (/builds/slave/test/build/application/firefox/firefox http://localhost:42834/getInfo.html) 16:47:01 INFO - TEST-INFO | 29359: exit 0 16:47:01 INFO - Browser initialized. 16:47:01 INFO - Running cycle 1/1 for kraken test... 16:47:01 INFO - Using env: {'DISPLAY': ':0', 16:47:01 INFO - 'HOME': '/home/cltbld', 16:47:01 INFO - 'JSGC_DISABLE_POISONING': '1', 16:47:01 INFO - 'LANG': 'en_US.UTF-8', 16:47:01 INFO - 'LANGUAGE': 'en_US:en', 16:47:01 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 16:47:01 INFO - 'LOGNAME': 'cltbld', 16:47:01 INFO - 'MAIL': '/var/mail/cltbld', 16:47:01 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:47:01 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:47:01 INFO - 'MOZ_CRASHREPORTER': '1', 16:47:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:47:01 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 16:47:01 INFO - 'MOZ_NO_REMOTE': '1', 16:47:01 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:47:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:47:01 INFO - 'NO_EM_RESTART': '1', 16:47:01 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:47:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:47:01 INFO - 'PWD': '/builds/slave/test', 16:47:01 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:47:01 INFO - 'RUST_BACKTRACE': 'full', 16:47:01 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 16:47:01 INFO - 'SHELL': '/bin/bash', 16:47:01 INFO - 'SHLVL': '1', 16:47:01 INFO - 'TERM': 'linux', 16:47:01 INFO - 'TMOUT': '86400', 16:47:01 INFO - 'USER': 'cltbld', 16:47:01 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1506122770.22441-1860920291', 16:47:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:47:01 INFO - '_': '/tools/buildbot/bin/python'} 16:47:01 INFO - TEST-INFO | started process 29596 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpRdU0kW/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 16:47:02 INFO - PID 29596 | 16:47:02 INFO - PID 29596 | (/builds/slave/test/build/application/firefox/firefox:29646): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 16:47:02 INFO - PID 29596 | 16:47:12 INFO - PID 29596 | RSS: Main: 144277504 16:47:12 INFO - PID 29596 | 16:47:15 INFO - PID 29596 | Cycle 1(1): loaded http://localhost:42834/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:42834/tests/kraken/driver.html?testName=audio-beat-detection) 16:47:16 INFO - PID 29596 | RSS: Main: 151777280 16:47:16 INFO - PID 29596 | 16:47:18 INFO - PID 29596 | Cycle 1(1): loaded http://localhost:42834/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:42834/tests/kraken/driver.html?testName=audio-dft) 16:47:19 INFO - PID 29596 | RSS: Main: 151822336 16:47:19 INFO - PID 29596 | 16:47:22 INFO - PID 29596 | Cycle 1(1): loaded http://localhost:42834/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:42834/tests/kraken/driver.html?testName=audio-fft) 16:47:22 INFO - PID 29596 | RSS: Main: 146911232 16:47:22 INFO - PID 29596 | 16:47:24 INFO - PID 29596 | Cycle 1(1): loaded http://localhost:42834/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:42834/tests/kraken/driver.html?testName=audio-oscillator) 16:47:25 INFO - PID 29596 | RSS: Main: 148176896 16:47:25 INFO - PID 29596 | 16:47:27 INFO - PID 29596 | Cycle 1(1): loaded http://localhost:42834/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:42834/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:47:28 INFO - PID 29596 | RSS: Main: 147984384 16:47:28 INFO - PID 29596 | 16:47:31 INFO - PID 29596 | Cycle 1(1): loaded http://localhost:42834/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:42834/tests/kraken/driver.html?testName=imaging-darkroom) 16:47:31 INFO - PID 29596 | RSS: Main: 148217856 16:47:31 INFO - PID 29596 | 16:47:35 INFO - PID 29596 | Cycle 1(1): loaded http://localhost:42834/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:42834/tests/kraken/driver.html?testName=imaging-desaturate) 16:47:35 INFO - PID 29596 | RSS: Main: 149540864 16:47:35 INFO - PID 29596 | 16:47:38 INFO - PID 29596 | Cycle 1(1): loaded http://localhost:42834/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:42834/tests/kraken/driver.html?testName=json-parse-financial) 16:47:39 INFO - PID 29596 | RSS: Main: 151687168 16:47:39 INFO - PID 29596 | 16:47:40 INFO - PID 29596 | Cycle 1(1): loaded http://localhost:42834/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:42834/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:47:41 INFO - PID 29596 | RSS: Main: 151310336 16:47:41 INFO - PID 29596 | 16:47:43 INFO - PID 29596 | Cycle 1(1): loaded http://localhost:42834/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:42834/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:47:43 INFO - PID 29596 | RSS: Main: 151707648 16:47:43 INFO - PID 29596 | 16:47:45 INFO - PID 29596 | Cycle 1(1): loaded http://localhost:42834/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:42834/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:47:46 INFO - PID 29596 | RSS: Main: 148570112 16:47:46 INFO - PID 29596 | 16:47:48 INFO - PID 29596 | Cycle 1(1): loaded http://localhost:42834/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:42834/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:47:49 INFO - PID 29596 | RSS: Main: 148373504 16:47:49 INFO - PID 29596 | 16:47:52 INFO - PID 29596 | Cycle 1(1): loaded http://localhost:42834/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:42834/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:47:52 INFO - PID 29596 | RSS: Main: 148643840 16:47:52 INFO - PID 29596 | 16:47:54 INFO - PID 29596 | Cycle 1(1): loaded http://localhost:42834/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:42834/tests/kraken/driver.html?testName=ai-astar) 16:47:54 INFO - PID 29596 | RSS: Main: 149757952 16:47:54 INFO - PID 29596 | 16:47:54 INFO - PID 29596 | __start_tp_report 16:47:54 INFO - PID 29596 | _x_x_mozilla_page_load 16:47:54 INFO - PID 29596 | _x_x_mozilla_page_load_details 16:47:54 INFO - PID 29596 | |i|pagename|runs| 16:47:54 INFO - PID 29596 | |0;ai-astar;106;105;105;104;104;107;107;107;104;105 16:47:54 INFO - PID 29596 | |1;audio-beat-detection;114;116;116;114;114;112;124;141;111;118 16:47:54 INFO - PID 29596 | |2;audio-dft;165;164;165;164;163;164;164;164;165;171 16:47:54 INFO - PID 29596 | |3;audio-fft;73;74;76;75;73;74;73;76;73;73 16:47:54 INFO - PID 29596 | |4;audio-oscillator;79;77;78;82;78;78;79;78;82;77 16:47:54 INFO - PID 29596 | |5;imaging-gaussian-blur;114;114;114;118;116;114;110;114;113;115 16:47:54 INFO - PID 29596 | |6;imaging-darkroom;149;149;149;151;150;150;151;150;150;150 16:47:54 INFO - PID 29596 | |7;imaging-desaturate;94;94;99;95;94;94;94;95;96;94 16:47:54 INFO - PID 29596 | |8;json-parse-financial;46;43;45;43;45;44;44;44;44;44 16:47:54 INFO - PID 29596 | |9;json-stringify-tinderbox;35;35;35;35;35;34;34;35;35;35 16:47:54 INFO - PID 29596 | |10;stanford-crypto-aes;70;71;70;72;70;72;71;72;71;78 16:47:54 INFO - PID 29596 | |11;stanford-crypto-ccm;110;103;104;105;107;106;104;109;112;109 16:47:54 INFO - PID 29596 | |12;stanford-crypto-pbkdf2;153;149;156;146;151;150;149;152;153;153 16:47:54 INFO - PID 29596 | |13;stanford-crypto-sha256-iterative;63;61;67;63;60;61;60;60;60;61 16:47:54 INFO - PID 29596 | __end_tp_report 16:47:54 INFO - PID 29596 | __start_cc_report 16:47:54 INFO - PID 29596 | _x_x_mozilla_cycle_collect,696 16:47:54 INFO - PID 29596 | __end_cc_report 16:47:54 INFO - PID 29596 | __startTimestamp1506124074617__endTimestamp 16:47:54 INFO - PID 29596 | 16:47:54 INFO - PID 29596 | ------- Summary: start ------- 16:47:54 INFO - PID 29596 | Number of tests: 14 16:47:54 INFO - PID 29596 | 16:47:54 INFO - PID 29596 | [#0] ai-astar Cycles:10 Average:105.40 Median:105.50 stddev:1.26 (1.2%) stddev-sans-first:1.32 16:47:54 INFO - PID 29596 | Values: 106.0 105.0 105.0 104.0 104.0 107.0 107.0 107.0 104.0 105.0 16:47:54 INFO - PID 29596 | 16:47:54 INFO - PID 29596 | [#1] audio-beat-detection Cycles:10 Average:118.00 Median:116.00 stddev:8.86 (7.6%) stddev-sans-first:9.28 16:47:54 INFO - PID 29596 | Values: 114.0 116.0 116.0 114.0 114.0 112.0 124.0 141.0 111.0 118.0 16:47:54 INFO - PID 29596 | 16:47:54 INFO - PID 29596 | [#2] audio-dft Cycles:10 Average:164.90 Median:164.50 stddev:2.23 (1.4%) stddev-sans-first:2.37 16:47:54 INFO - PID 29596 | Values: 165.0 164.0 165.0 164.0 163.0 164.0 164.0 164.0 165.0 171.0 16:47:54 INFO - PID 29596 | 16:47:54 INFO - PID 29596 | [#3] audio-fft Cycles:10 Average:74.00 Median:74.00 stddev:1.25 (1.7%) stddev-sans-first:1.27 16:47:54 INFO - PID 29596 | Values: 73.0 74.0 76.0 75.0 73.0 74.0 73.0 76.0 73.0 73.0 16:47:54 INFO - PID 29596 | 16:47:54 INFO - PID 29596 | [#4] audio-oscillator Cycles:10 Average:78.80 Median:78.50 stddev:1.81 (2.3%) stddev-sans-first:1.92 16:47:54 INFO - PID 29596 | Values: 79.0 77.0 78.0 82.0 78.0 78.0 79.0 78.0 82.0 77.0 16:47:54 INFO - PID 29596 | 16:47:54 INFO - PID 29596 | [#5] imaging-gaussian-blur Cycles:10 Average:114.20 Median:114.00 stddev:2.04 (1.8%) stddev-sans-first:2.17 16:47:54 INFO - PID 29596 | Values: 114.0 114.0 114.0 118.0 116.0 114.0 110.0 114.0 113.0 115.0 16:47:54 INFO - PID 29596 | 16:47:54 INFO - PID 29596 | [#6] imaging-darkroom Cycles:10 Average:149.90 Median:150.00 stddev:0.74 (0.5%) stddev-sans-first:0.71 16:47:54 INFO - PID 29596 | Values: 149.0 149.0 149.0 151.0 150.0 150.0 151.0 150.0 150.0 150.0 16:47:54 INFO - PID 29596 | 16:47:54 INFO - PID 29596 | [#7] imaging-desaturate Cycles:10 Average:94.90 Median:94.50 stddev:1.60 (1.7%) stddev-sans-first:1.66 16:47:54 INFO - PID 29596 | Values: 94.0 94.0 99.0 95.0 94.0 94.0 94.0 95.0 96.0 94.0 16:47:54 INFO - PID 29596 | 16:47:54 INFO - PID 29596 | [#8] json-parse-financial Cycles:10 Average:44.20 Median:44.00 stddev:0.92 (2.1%) stddev-sans-first:0.71 16:47:54 INFO - PID 29596 | Values: 46.0 43.0 45.0 43.0 45.0 44.0 44.0 44.0 44.0 44.0 16:47:54 INFO - PID 29596 | 16:47:54 INFO - PID 29596 | [#9] json-stringify-tinderbox Cycles:10 Average:34.80 Median:35.00 stddev:0.42 (1.2%) stddev-sans-first:0.44 16:47:54 INFO - PID 29596 | Values: 35.0 35.0 35.0 35.0 35.0 34.0 34.0 35.0 35.0 35.0 16:47:54 INFO - PID 29596 | 16:47:54 INFO - PID 29596 | [#10] stanford-crypto-aes Cycles:10 Average:71.70 Median:71.50 stddev:2.36 (3.3%) stddev-sans-first:2.42 16:47:54 INFO - PID 29596 | Values: 70.0 71.0 70.0 72.0 70.0 72.0 71.0 72.0 71.0 78.0 16:47:54 INFO - PID 29596 | 16:47:54 INFO - PID 29596 | [#11] stanford-crypto-ccm Cycles:10 Average:106.90 Median:108.00 stddev:3.00 (2.8%) stddev-sans-first:2.96 16:47:54 INFO - PID 29596 | Values: 110.0 103.0 104.0 105.0 107.0 106.0 104.0 109.0 112.0 109.0 16:47:54 INFO - PID 29596 | 16:47:54 INFO - PID 29596 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:151.20 Median:152.50 stddev:2.82 (1.8%) stddev-sans-first:2.92 16:47:54 INFO - PID 29596 | Values: 153.0 149.0 156.0 146.0 151.0 150.0 149.0 152.0 153.0 153.0 16:47:54 INFO - PID 29596 | 16:47:54 INFO - PID 29596 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.60 Median:61.00 stddev:2.22 (3.6%) stddev-sans-first:2.30 16:47:54 INFO - PID 29596 | Values: 63.0 61.0 67.0 63.0 60.0 61.0 60.0 60.0 60.0 61.0 16:47:54 INFO - PID 29596 | -------- Summary: end -------- 16:47:54 INFO - PID 29596 | 16:47:54 INFO - PID 29596 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 16:47:55 INFO - TEST-INFO | 29596: exit 0 16:47:55 INFO - TEST-OK | kraken | took 58807ms 16:47:55 INFO - SUITE-END | took 1166s 16:47:55 INFO - Completed test suite (00:19:26) 16:47:55 INFO - javascript benchmark 16:47:55 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 9113.3824714949, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3862.3434016217243, 3876.592957739437, 3906.1797022625246, 3919.9804000980007, 3920.960790392097, 6216.316205217422, 6350.682465876711, 6372.681365931702, 6388.329225431333, 6446.903296450557, 6337.271213810413, 6371.553991220617, 6380.4257616814475, 6387.169667943161, 6399.648019358933, 5662.716864156786, 5663.688497132656, 5700.629459085167, 5711.8000869969555, 5712.600117991742, 5771.942280577183, 5775.509081728037, 5780.2196703444915, 5789.073748200289, 5810.970945145268, 5698.230738850244, 5700.600957932947, 5762.769489220426, 5769.7115144242825, 5797.971010144965, 5283.128283833202, 5512.5589952803675, 5625.156226566007, 5636.323641163087, 5788.189653448541, 5606.719664016783, 5616.831495055155, 5672.829815105512, 5689.459501347365, 5705.1727499512335, 6191.752329906798, 6194.349593292684, 6271.592346497463, 6313.779017734403, 6362.0456931460185, 6829.146356705412, 6882.483813713991, 6895.138107736533, 6944.930550694479, 6951.0, 7014.403775679073, 7057.929420705778, 7075.964620176866, 7082.185548661918, 7084.92915070853, 6401.871962560769, 6424.93575064248, 6441.291457939623, 6503.894337962558, 6509.5768775029, 7117.857642847114, 7119.0, 7195.13658361, 7196.136463624399, 7217.7473788417155, 6789.422899053636, 6844.965775171192, 6863.416609588241, 6874.931250687429, 6876.484263680244, 6903.378695917391, 6911.7926462206215, 6953.130858642669, 7000.299970002959, 7008.719651213996, 6717.630530320879, 6807.2512023677355, 6853.691583878737, 6967.65161741921, 6976.7558135465015, 6875.48433867462, 6956.860862782716, 6963.303669632996, 6993.405560527311, 7013.4038606719005, 4980.053789780002, 5050.823221187241, 5063.038022775734, 5067.797288108508, 5077.365329333833, 4468.151051300242, 4484.618807401343, 4493.056458143761, 4518.0, 4558.544145585415, 6222.502199824004, 6267.811965641038, 6276.46650034743, 6290.308066112724, 6295.559310848196, 6799.898001529981, 6819.215790184192, 6862.965685171542, 6910.412614927787, 6965.233824279325, 6255.499560035186, 6335.68321583919, 6382.712777925005, 6387.361263873669, 6406.551541392151, 5384.111621582395, 5546.27898373217, 5606.663600183921, 5616.0171969905105, 5617.691026993472, 7123.109611298588, 7198.5320954137815, 7284.745033923788, 7304.817379565553, 7307.232740562271, 6845.965770171118, 6858.3827455529245, 6923.411510021705, 6928.203256625351, 6934.4452443805385, 7152.85694286101, 7160.176579693402, 7250.673719682522, 7255.854882902207, 7282.380997615051, 6704.7988560343265, 6770.424513916175, 6786.253512113565, 6801.829954251183, 6907.378335949788, 6962.756303529352, 6969.54697944632, 6977.965110174417, 6978.860422791414, 7000.124984376953, 6673.899891501728, 6681.465482761465, 6693.1298931138645, 6772.119624448987, 6795.864082718417, 6666.799996000128, 6667.066610674412, 6750.088738020305, 6752.93247067523, 6765.9661701691175, 6433.388828061326, 6561.475081993333, 6607.0, 6677.0, 6690.866182676222, 6772.288909664611, 6774.254831968381, 6790.830229244309, 6818.522703410912, 6832.282610325844, 6406.167198264196, 6472.385123413268, 6504.60972341661, 6571.408573228432, 6586.242582102927], "value": 6297.745640106966, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [897.4480694372962, 930.1256818590525, 958.2477754962351, 964.0407794244726, 976.7558110472382, 43949.0, 44905.0, 45001.87495312615, 45009.54990450094, 45055.0, 42111.36832947501, 42390.7880460598, 42429.787851060704, 42888.356674649905, 43586.56413435865, 8311.83376332473, 8458.154184581523, 8463.4075614707, 8468.44955077921, 8474.23731864132, 8239.83520329593, 8438.198371154731, 8439.535825529594, 8442.07137214906, 8445.535495547743, 8418.284445822112, 8423.073461919215, 8423.326133909302, 8460.746177614681, 8476.745697629081, 8390.0, 8392.286655634249, 8406.78983025423, 8430.32557395407, 8433.578321083922, 8284.08875023747, 8333.083360830304, 8338.791530211733, 8350.749477515685, 8376.664933402657, 41145.177096457905, 41305.586944130475, 41330.0, 41483.1703365934, 41552.37671434931, 3759.7744135351695, 3770.1517158639085, 3794.7723136611617, 3796.924061518782, 3808.067023579213, 32916.0, 33262.50106248408, 33295.50056749151, 33379.16552086193, 33442.1639459013, 5394.379646340682, 5430.266913966643, 5436.320459942507, 5544.750486228089, 5553.722313884289, 195916.0, 212806.0, 214988.0, 215229.0, 215516.0, 47132.0, 52529.0, 53361.1995820063, 53573.0, 53628.0, 56412.717936410874, 56477.0, 56713.0, 56789.43210567924, 56901.71549142311, 16856.74714879278, 17086.231119599648, 17122.0, 17231.22459489326, 17244.741328880078, 14198.929005354907, 14220.360083796253, 14233.0, 14235.64410889736, 14253.857461425254, 14784.186869722053, 14950.626234344229, 14962.625934351729, 14971.70056598862, 15097.54707358781, 5691.231683722644, 5701.315842098975, 5737.3688894221605, 5760.481556659837, 5903.232579764689, 909.1999040844147, 928.4058202750246, 942.7266092833001, 945.4280160502934, 954.2747511891013, 23245.186418475274, 23256.0, 23591.0, 23841.0, 23895.880520597635, 1036.8133735927454, 1038.8857225705167, 1039.1375158618328, 1042.744527590745, 1043.0716662170673, 12177.69555761113, 12301.630951071482, 12370.44333005017, 12377.133600647869, 12529.31088790107, 266458.0, 267539.0, 268402.0, 268571.0, 269004.0, 57480.42519574834, 57682.42317576854, 57741.4225857736, 57928.0, 58553.70723146357, 1507.3895072495875, 1532.6474910770362, 1556.7587024011295, 1570.3797000184863, 1573.732465480871, 22682.659760103943, 22844.31467055991, 22887.88556057209, 23339.883300583388, 23402.18092366759, 41128.38307425451, 41255.381169281885, 41358.0, 41376.37935430911, 41389.37915931203, 229003.0, 229219.0, 229674.0, 229679.0, 230400.0, 36555.81722091373, 39787.80106099451, 40061.599384005785, 40686.0, 40694.0, 49587.50412495829, 50363.0, 50662.0, 50997.49002510076, 51187.488125118274, 36669.63330366662, 36824.26351472902, 36871.815640921624, 36900.81549592235, 37054.888353349445, 36943.891683249545, 37091.814540927124, 37092.443613345284, 37167.62832371642, 37219.0, 43555.0, 43580.692579222676, 44010.77994610006, 44120.55879441293, 44169.77915110404, 35555.288894221456, 35566.0, 35683.0, 36047.197640118415, 36216.0, 26292.0, 26844.463110738066, 27042.32394190161, 27189.86405067962, 27256.863715681295], "value": 18581.364391086863, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [615.2033117113339, 616.3682225718641, 626.1734510446213, 626.2922897126247, 633.5596760251624, 22274.0, 22449.32652020438, 23329.0, 23429.999999999978, 23450.296491105295, 24811.62782558263, 25630.231093067192, 25756.871215643943, 25768.74231257687, 25811.0, 6459.967700161493, 6679.198496180441, 6682.465402767767, 6683.13119294491, 6683.933160668404, 6461.289258181596, 6480.805575832709, 6677.799666010027, 6690.464762818963, 6691.498137639672, 6612.966935165341, 6630.933690663104, 6644.0, 6644.60132392058, 6646.302138275484, 6497.48020158391, 6625.0393692914495, 6629.2376376716575, 6631.038499417606, 6671.0, 6267.05994100884, 6267.373262673696, 6271.122042913996, 6275.8744825103695, 6284.99440089588, 29680.25799355012, 30177.09468715942, 30272.394552109054, 30308.54537181944, 30314.090577282717, 2332.07882886259, 2344.038944032938, 2349.447879748258, 2353.446939969106, 2360.268316821791, 19679.508012299662, 20008.0, 20339.694904576445, 20518.897405513027, 20525.4868628284, 2491.825572209946, 2550.5664037113734, 2575.639410482534, 2597.9090731824294, 2606.4787042591556, 136757.31621341928, 137753.0, 143654.0, 144229.0, 146064.26967865092, 15071.246437678072, 15433.922830385776, 16143.547080762788, 16467.58831029234, 16833.074180920168, 25765.8711706444, 25965.480690386463, 25971.350716231867, 25986.74013259881, 26006.479870402487, 10709.518071686636, 10757.193210509087, 10862.619808306672, 10898.073663738513, 10902.672919812418, 9820.165285950632, 9859.211263098934, 9861.309708320492, 9946.955569665226, 9963.202943764481, 9375.671851485165, 9530.237580993504, 9545.61817527305, 9687.51562421876, 9974.601015959426, 2678.5312570300507, 2697.7976651751196, 2795.692473827877, 2851.9144425667264, 2857.4999375109273, 1045.7385653586603, 1048.4548035021899, 1053.1785207538132, 1053.4926874039736, 1063.6702622187147, 20675.689864652042, 21072.683909741365, 21159.0, 21348.78651213499, 21444.57110857774, 1084.983725244122, 1100.5102729285552, 1105.0772604874858, 1117.1565468071553, 1127.1321082766224, 11412.48643811014, 11460.369679667532, 11481.770364592661, 11500.309981401142, 11876.465559049864, 556017.0, 587940.0, 597707.0, 602053.0, 611806.0, 122068.0, 123908.0, 125769.37115314364, 126508.0, 129760.0, 1242.142921384242, 1246.0965799795258, 1268.765278423476, 1272.3002348708358, 1274.4010315151863, 18489.05469907306, 18600.06999649985, 18745.062746862328, 19011.0, 19022.23911043543, 58813.70593147007, 58895.11657325229, 60039.0, 60129.398706012376, 60758.0, 76096.0, 76812.61593691996, 76893.0, 77148.61425692835, 77576.22423775916, 15044.323005464561, 15187.924060379628, 15188.088714677155, 15338.693226135638, 15371.231438428258, 22544.661830072233, 23183.420414489774, 23342.183023594094, 23436.414089647897, 23452.0, 18883.150258238136, 18902.243910243436, 19221.807781922562, 19372.61254774925, 19384.8061519383, 16624.251908663897, 16791.496255112364, 16895.0, 17375.47873563795, 17485.737713934046, 19289.0, 19370.8062919369, 19382.321618743277, 19505.8049419504, 19511.414657560297, 14208.087342095052, 15003.77494337564, 15018.24908754536, 15030.248487575796, 15070.0, 19209.423717288504, 19311.517212069248, 19431.514212144808, 19433.708494372313, 19447.416577502696], "value": 12594.926444065493, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1456.8980171388, 1511.6069821846318, 1511.6976604679066, 1515.7953676253703, 1534.5319677498362, 16444.34222631095, 17149.485515434524, 17158.056306903116, 17171.141442927867, 17175.742363864552, 16538.50384488467, 16749.66500669986, 16825.074620895844, 16834.0, 16870.746938795928, 2756.462489814492, 2771.5815580539997, 2801.9159425217276, 2821.210061182862, 2827.533456979596, 2736.315921019745, 2769.556870900656, 2772.0436449425015, 2772.819766715167, 2775.5004099262123, 2636.4595257972164, 2706.742859428361, 2731.112388473744, 2733.986330068357, 2737.9452410951767, 2658.0, 2663.42736311692, 2692.67687877454, 2693.205504376204, 2694.663167104112, 2533.4933013397394, 2580.44520428107, 2580.651612032389, 2642.6036094585775, 2727.4136060747032, 16396.67206655872, 16620.168991550494, 16628.33486660532, 16635.08507032113, 16648.417305394374, 8346.123657015987, 8357.247847693738, 8581.184787445183, 8645.351598630128, 8667.609957551926, 5666.405027472098, 5746.885062298772, 5828.883422331572, 5829.912551311733, 5832.766689332421, 2038.9184432622678, 2060.7733149353558, 2071.6271071207175, 2101.4746313421647, 2134.6157691615504, 29779.25551861199, 30078.54882176769, 30139.19164850116, 30190.396192076034, 30300.090997269897, 6954.478414118961, 7036.9296307036275, 7076.575405475688, 7203.207647158808, 7224.0, 19404.320848770225, 19495.0, 19499.805001950084, 19509.317173898846, 19527.121279542454, 8302.460340077876, 8961.507117108491, 9163.58763855628, 9173.58718857653, 9184.90815091854, 7975.2423519765525, 8027.678892844221, 8043.316318112909, 8103.4732742372735, 8116.35069194463, 5650.124230744241, 5777.797777077866, 5886.852828679317, 5903.0555111182, 5907.350191478934, 1744.2151032035636, 1744.5725797179518, 1751.991240043817, 1761.9868575569099, 1768.3015208992376, 968.4092703450889, 974.2060220919927, 985.6008316631776, 1008.9848652270222, 1011.5195282240877, 8523.764054212104, 8573.356998225157, 8585.0, 8618.39671223021, 8637.265832404317, 772.6986475274647, 809.5911564659809, 809.8501777171124, 819.5287709566903, 828.7016673997356, 6239.625622462667, 6409.647469389229, 6627.900581491282, 6656.134702488645, 6662.6335551545135, 25287.74712252854, 25508.23475295707, 25544.616830747553, 25813.096541620955, 26188.34529136749, 24930.50138997246, 25374.619380709304, 25452.236432907044, 25489.36276593063, 25746.871265643553, 11105.83341249882, 12136.33250171249, 12369.134160608852, 12485.500579976699, 12506.062045346636, 9365.57854896545, 9914.206863450763, 10022.799544008933, 10066.748331291776, 10250.487475626338, 21434.464138396666, 21476.570468590853, 21614.891925540272, 21710.45723856853, 21834.454138646026, 22416.439589010406, 22479.550408992054, 22747.317580472612, 22793.202237921592, 23143.884280578488, 13351.198928064347, 13463.192208467523, 13691.657708557366, 13695.109817861807, 13696.0, 15378.0, 15671.451499197474, 15696.29366678525, 15702.607434814221, 15733.842661573695, 11943.16397852142, 12492.875071249171, 12536.937315313366, 12895.548655797002, 12931.5473958411, 12452.501899923902, 12518.499260029497, 12565.246085234916, 12791.42438590247, 12854.935725321313, 10559.419231942316, 10809.13526917829, 10864.456777160953, 10866.18503612248, 10908.836367454653, 8304.916950830415, 8340.833183336177, 8534.530600817016, 8543.231109200202, 8561.400701950804, 8067.475614085066, 8154.633041513028, 8156.184381561797, 8170.754877353689, 8175.550344730859], "value": 7334.937586226242, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [557.3673880146034, 564.2889958652097, 572.6306532286675, 578.8036733680814, 580.7339998802611, 5849.678267695281, 6119.173911521943, 6370.203724534434, 6416.454601358879, 6435.935640643592, 6182.412670796279, 6235.879800867704, 6284.585968157162, 6288.811335659926, 6322.715477803498, 4944.357233559632, 5030.170021946375, 5058.038972595195, 5076.695398276097, 5082.567981721558, 4969.0807200668, 5042.831582419558, 5042.848714538552, 5094.898102037975, 5098.337216161894, 4937.580305674022, 5001.899962000758, 5010.473900240477, 5023.572996295319, 5029.245613158019, 4936.432310284327, 4976.726280054596, 4992.775325110342, 5003.699778013331, 5014.448410674841, 4562.680612357103, 4576.519465456146, 4577.588017078446, 4614.723116613014, 4615.584597386218, 6047.334793172748, 6126.326104128542, 6169.907451388232, 6187.102870083858, 6237.906431403532, 5816.621919575214, 5842.883142337172, 5862.472377486047, 5889.411058894119, 5917.0, 6621.668916554201, 6663.133792606978, 6680.298568650271, 6689.799306020778, 6734.3602357775935, 4838.443578988426, 5065.860181459179, 5095.617828662828, 5097.43928167905, 5139.614528910347, 6775.3902148806355, 6796.25241223465, 6858.005589189589, 6960.234374218832, 6967.039008421451, 5170.431252562215, 5179.974100129475, 5239.266502689627, 5240.580753539708, 5252.9737351313, 5223.52988231061, 5230.895382092337, 5264.6051546134195, 5284.656497327662, 5335.906139241446, 5848.941510584925, 5857.736401861926, 5872.765089396376, 5877.294724632986, 5911.733971971272, 6650.736360091567, 6651.933480665131, 6673.4661227101715, 6698.7320507179165, 6715.93284067153, 1752.3516298969207, 1786.1069465267367, 1814.4284550366592, 1816.273490603769, 1838.1452624529659, 1230.809224570193, 1285.6014635463036, 1285.884270415667, 1300.4524615707278, 1320.3992183556459, 6155.322914479404, 6170.444659980623, 6218.284897236876, 6225.128482012522, 6246.15676883624, 6674.933250667431, 6682.966585167043, 6719.227288861844, 6831.726730930806, 6858.622775747382, 6144.310314663414, 6145.324014358417, 6151.938480615226, 6154.107654390088, 6166.907496387557, 3432.3993301172195, 3518.5249991251367, 3538.6992105670793, 3557.719221080409, 3573.10672331917, 6890.965545172242, 6912.55068420551, 6990.650467476606, 7002.474814388839, 7041.647917604099, 6802.3537763912345, 6853.862922741617, 6887.724491020404, 6890.31096890307, 6898.379145876896, 6902.377096703221, 7000.789976300617, 7044.647767611599, 7073.5048546602275, 7122.679479423542, 6746.359095885883, 6748.527603067738, 6779.0, 6789.864202715819, 6796.320367963164, 6731.865362692621, 6740.73037078531, 6753.716793809161, 6823.624700641512, 6827.2490026098085, 7026.191987921455, 7072.71709131629, 7089.503734738519, 7094.4679149062995, 7168.319009694071, 5209.8277887475015, 5215.400228973716, 5216.869578260574, 5248.89502209946, 5256.78972841082, 6305.274893387135, 6567.784959782455, 6685.598864068172, 6777.390034896883, 6810.901040026875, 6459.61242325462, 6474.320196379313, 6493.577917435352, 6525.0, 6614.603123812394, 6440.871182576416, 6496.0, 6516.413522782972, 6536.901946470712, 6566.507511936528, 6201.658908760061, 6333.556651034383, 6451.6774161290805, 6452.774152904626, 6513.5114866384265, 6504.593300478664, 6634.270230274762, 6658.1344425224415, 6697.321071571216, 6713.429358504582, 6347.68261586928, 6414.903776443264, 6517.641529715911, 6549.410553050248, 6559.245686746085], "value": 5186.720696611951, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [967.4340510801181, 970.6457143142756, 974.8976357482464, 999.3654029691144, 1007.2395341517154, 20376.286829960954, 20581.176752929903, 20591.485212869688, 20644.277450289243, 23659.76340236597, 23337.066517339328, 23356.182533611325, 23375.1818686346, 23385.415364615892, 23409.882950585223, 1074.785042991401, 1077.2943721862155, 1079.692287698006, 1079.7786453776957, 1086.4839201379361, 1019.8368261078228, 1020.0411613083678, 1051.437480947693, 1088.29260980363, 1095.3427943234035, 1143.6283207957406, 1151.0158814213853, 1155.5608868629952, 1155.647527504111, 1155.6706338693475, 1149.620625193684, 1152.4353066997112, 1154.1343992005995, 1154.1978325064083, 1154.8267759836049, 845.6659619450282, 848.3891598049395, 848.749618862437, 848.9066202717696, 850.1668365002271, 21437.785622143892, 21500.247491337726, 21849.67225491603, 22065.00707468168, 22283.331500055025, 23224.0, 23246.76753232463, 23248.07007719689, 23618.881905590533, 23674.881625591934, 18420.078996050277, 18603.0, 18653.720194197096, 18736.531586710305, 18736.90631546847, 3898.3567711327596, 3938.6455219030427, 3960.425738267964, 3968.5436174839974, 3972.126132250929, 153978.0, 155706.99999999886, 158805.0, 160627.0, 161080.0, 17542.824571754118, 17751.644967100845, 17758.822411775716, 17792.3772667956, 17866.642667146585, 34868.0, 35012.124696882274, 35095.4735678965, 35295.82352088223, 35330.64669353273, 9056.728298150934, 9185.035571265054, 9202.953985230164, 9247.352685312098, 9299.581518831668, 4004.039030632627, 4205.936910946339, 4421.093675796518, 4523.660725445605, 4531.003179300548, 6871.072405225331, 6895.55178913379, 6906.861862762717, 6910.550814197061, 6963.234044255128, 1315.5724389573427, 1325.701717113661, 1347.8786909178025, 1348.5684580934007, 1351.7228968061524, 746.4700062955254, 757.7575175943755, 761.4517547365888, 774.7327172125607, 778.9844203115906, 13765.449382024806, 13831.0, 14203.644908877153, 14591.343389547495, 14668.753988513014, 723.667113127967, 735.8822588385833, 745.3329270303049, 747.2975403121048, 748.356413484403, 6354.459870910932, 6378.649174295366, 6390.201224846895, 6440.194975628046, 6562.540622156499, 142905.0, 143966.0, 146382.0, 147141.26429367784, 147516.26241868723, 130048.3497582525, 131036.0, 132991.33504332416, 133209.0, 137380.0, 109112.45443772888, 109742.45128774305, 110150.0, 111051.44474277577, 114438.0, 12539.435725392197, 12553.435095420546, 12564.0, 12732.299723514925, 12829.0, 56366.71816640891, 56718.43281567297, 57404.42595574158, 57511.71244143753, 58922.82154356975, 67064.6646766763, 67299.0, 68377.0, 68530.3146968524, 70731.64634176796, 17893.821061789735, 17898.194581243613, 18049.278028879224, 18137.0, 18150.092495375444, 20334.0, 20342.389728308175, 20452.590948181252, 20509.0, 20622.793772062687, 18050.819491804912, 18320.45038648843, 18433.262669493608, 18473.261069557066, 18486.81513184905, 17349.653006940043, 17644.1177941105, 17674.204660790554, 17680.2043908027, 17880.28478860883, 18360.99014554159, 18468.907655462175, 18549.35077272289, 18552.350667726565, 18664.906675466536, 13896.72206555843, 14035.157890526601, 14089.506867259597, 14185.290735463392, 14224.71550568962, 14603.269836508345, 14632.0, 14694.0, 14746.188959607325, 14799.704005920037], "value": 10216.534729140198, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1370.5, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 105.0, 105.0, 104.0, 104.0, 107.0, 107.0, 107.0, 104.0, 105.0], "value": 105.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 116.0, 116.0, 114.0, 114.0, 112.0, 124.0, 141.0, 111.0, 118.0], "value": 118.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 164.0, 165.0, 164.0, 163.0, 164.0, 164.0, 164.0, 165.0, 171.0], "value": 164.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 74.0, 76.0, 75.0, 73.0, 74.0, 73.0, 76.0, 73.0, 73.0], "value": 74.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 77.0, 78.0, 82.0, 78.0, 78.0, 79.0, 78.0, 82.0, 77.0], "value": 78.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 114.0, 118.0, 116.0, 114.0, 110.0, 114.0, 113.0, 115.0], "value": 114.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 149.0, 149.0, 151.0, 150.0, 150.0, 151.0, 150.0, 150.0, 150.0], "value": 149.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 99.0, 95.0, 94.0, 94.0, 94.0, 95.0, 96.0, 94.0], "value": 94.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [46.0, 43.0, 45.0, 43.0, 45.0, 44.0, 44.0, 44.0, 44.0, 44.0], "value": 44.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [35.0, 35.0, 35.0, 35.0, 35.0, 34.0, 34.0, 35.0, 35.0, 35.0], "value": 34.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 71.0, 70.0, 72.0, 70.0, 72.0, 71.0, 72.0, 71.0, 78.0], "value": 71.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 103.0, 104.0, 105.0, 107.0, 106.0, 104.0, 109.0, 112.0, 109.0], "value": 106.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 149.0, 156.0, 146.0, 151.0, 150.0, 149.0, 152.0, 153.0, 153.0], "value": 151.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 61.0, 67.0, 63.0, 60.0, 61.0, 60.0, 60.0, 60.0, 61.0], "value": 61.6, "unit": "score"}]}]} 16:47:55 INFO - Return code: 0 16:47:55 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:47:55 INFO - # TBPL SUCCESS # 16:47:55 INFO - Running post-action listener: _package_coverage_data 16:47:55 INFO - Running post-action listener: _resource_record_post_action 16:47:55 INFO - [mozharness: 2017-09-22 23:47:55.405716Z] Finished run-tests step (success) 16:47:55 INFO - Running post-run listener: _resource_record_post_run 16:47:55 INFO - Total resource usage - Wall time: 1185s; CPU: 13.0%; Read bytes: 8192; Write bytes: 333291520; Read time: 264; Write time: 225820 16:47:55 INFO - TinderboxPrint: CPU usage
13.1% 16:47:55 INFO - TinderboxPrint: I/O read bytes / time
8,192 / 264 16:47:55 INFO - TinderboxPrint: I/O write bytes / time
333,291,520 / 225,820 16:47:55 INFO - TinderboxPrint: CPU idle
8,213.1 (86.9%) 16:47:55 INFO - TinderboxPrint: CPU user
1,186.5 (12.6%) 16:47:55 INFO - TinderboxPrint: Swap in / out
0 / 0 16:47:55 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 12464128; Read time: 0; Write time: 2172 16:47:55 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 16:47:55 INFO - run-tests - Wall time: 1167s; CPU: 13.0%; Read bytes: 8192; Write bytes: 319803392; Read time: 264; Write time: 223632 16:47:56 INFO - Running post-run listener: _upload_blobber_files 16:47:56 INFO - Blob upload gear active. 16:47:56 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:47:56 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:47:56 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:47:56 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:47:56 INFO - (blobuploader) - INFO - Open directory for files ... 16:47:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 16:47:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:47:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:47:57 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 16:47:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:47:57 INFO - (blobuploader) - INFO - Done attempting. 16:47:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 16:47:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:47:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:47:58 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 16:47:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:47:58 INFO - (blobuploader) - INFO - Done attempting. 16:47:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 16:47:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:47:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:47:59 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 16:47:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:47:59 INFO - (blobuploader) - INFO - Done attempting. 16:47:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:47:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:47:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:48:02 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:48:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:48:02 INFO - (blobuploader) - INFO - Done attempting. 16:48:02 INFO - (blobuploader) - INFO - Iteration through files over. 16:48:02 INFO - Return code: 0 16:48:02 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:48:02 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:48:02 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/65128dbcb0e2d330f86966ebad6fb3f83efa25d717053e2b3071bfa7cf834877063bd2cd5f22106aaa591c6d69e781550b7ebcf53c3760d4a33ca259883b84b6", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b8e00045d9c3d85f9f523ebc9ac9e9223b65bd543cfe27e6f8700825fdff0e18fc726feb673810d4e5dd2dd6cc5a5c618602deffc211e2a0f7fb6500ba8dce9b", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8ccab53b8d5af2d145ed426a54ea17565e7610c84860b66384913cc8b78702b1fbbde65102e98d3fb33fd13183d6e12db887ede4d8e553d7a90264f8d5083cec", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e0d0ac9e19e67ab3950f4102d772ddd9ea1ed185b5e90f95626cb6152b9dc5f3722babe9b04053194a3c3607491a09c359c85872c5d126896085eb3fe91e8349"} 16:48:02 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:48:02 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:48:02 INFO - Contents: 16:48:02 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/65128dbcb0e2d330f86966ebad6fb3f83efa25d717053e2b3071bfa7cf834877063bd2cd5f22106aaa591c6d69e781550b7ebcf53c3760d4a33ca259883b84b6", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b8e00045d9c3d85f9f523ebc9ac9e9223b65bd543cfe27e6f8700825fdff0e18fc726feb673810d4e5dd2dd6cc5a5c618602deffc211e2a0f7fb6500ba8dce9b", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8ccab53b8d5af2d145ed426a54ea17565e7610c84860b66384913cc8b78702b1fbbde65102e98d3fb33fd13183d6e12db887ede4d8e553d7a90264f8d5083cec", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e0d0ac9e19e67ab3950f4102d772ddd9ea1ed185b5e90f95626cb6152b9dc5f3722babe9b04053194a3c3607491a09c359c85872c5d126896085eb3fe91e8349"} 16:48:02 INFO - Running post-run listener: copy_logs_to_upload_dir 16:48:02 INFO - Copying logs to upload dir... 16:48:02 INFO - mkdir: /builds/slave/test/build/upload/logs 16:48:02 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1236.568575 ========= master_lag: -0.69 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 35 secs) (at 2017-09-22 16:48:02.244272) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-22 16:48:02.251933) ========= 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=6d15e502d8e29d5d2676d664000001c3-1506122770.22441-1860920291 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/65128dbcb0e2d330f86966ebad6fb3f83efa25d717053e2b3071bfa7cf834877063bd2cd5f22106aaa591c6d69e781550b7ebcf53c3760d4a33ca259883b84b6", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b8e00045d9c3d85f9f523ebc9ac9e9223b65bd543cfe27e6f8700825fdff0e18fc726feb673810d4e5dd2dd6cc5a5c618602deffc211e2a0f7fb6500ba8dce9b", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8ccab53b8d5af2d145ed426a54ea17565e7610c84860b66384913cc8b78702b1fbbde65102e98d3fb33fd13183d6e12db887ede4d8e553d7a90264f8d5083cec", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e0d0ac9e19e67ab3950f4102d772ddd9ea1ed185b5e90f95626cb6152b9dc5f3722babe9b04053194a3c3607491a09c359c85872c5d126896085eb3fe91e8349"} build_url:https://queue.taskcluster.net/v1/task/cbFdpQ8wTE6HRI4Bano6mQ/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011371 build_url: 'https://queue.taskcluster.net/v1/task/cbFdpQ8wTE6HRI4Bano6mQ/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/65128dbcb0e2d330f86966ebad6fb3f83efa25d717053e2b3071bfa7cf834877063bd2cd5f22106aaa591c6d69e781550b7ebcf53c3760d4a33ca259883b84b6", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b8e00045d9c3d85f9f523ebc9ac9e9223b65bd543cfe27e6f8700825fdff0e18fc726feb673810d4e5dd2dd6cc5a5c618602deffc211e2a0f7fb6500ba8dce9b", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8ccab53b8d5af2d145ed426a54ea17565e7610c84860b66384913cc8b78702b1fbbde65102e98d3fb33fd13183d6e12db887ede4d8e553d7a90264f8d5083cec", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e0d0ac9e19e67ab3950f4102d772ddd9ea1ed185b5e90f95626cb6152b9dc5f3722babe9b04053194a3c3607491a09c359c85872c5d126896085eb3fe91e8349"}' ========= master_lag: 0.10 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-22 16:48:02.366194) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-22 16:48:02.366527) ========= 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=6d15e502d8e29d5d2676d664000001c3-1506122770.22441-1860920291 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-22 16:48:02.416855) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-22 16:48:02.417158) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-22 16:48:02.417473) ========= ========= Total master_lag: -0.45 =========