builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-026 starttime: 1505958384.95 results: success (0) revision: f8dd3f21e434be32fe5901849f1723b0e64cf668 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-20 18:46:24.948596) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-20 18:46:24.952245) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-20 18:46:24.952556) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-20 18:46:25.071308) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-20 18:46:25.071676) ========= 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=41bf3e9aed84707956354da8000001c5-1505958174.735341-1296699532 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-20 18:46: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% 39.7M=0s 2017-09-20 18:46:25 (39.7 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.344873 ========= master_lag: 0.11 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-20 18:46:25.530483) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-20 18:46:25.530813) ========= 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=41bf3e9aed84707956354da8000001c5-1505958174.735341-1296699532 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014427 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-20 18:46:25.584046) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-20 18:46:25.584413) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev f8dd3f21e434be32fe5901849f1723b0e64cf668 --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 f8dd3f21e434be32fe5901849f1723b0e64cf668 --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=41bf3e9aed84707956354da8000001c5-1505958174.735341-1296699532 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-20 18:46:25,686 truncating revision to first 12 chars 2017-09-20 18:46:25,686 Setting DEBUG logging. 2017-09-20 18:46:25,686 attempt 1/10 2017-09-20 18:46:25,686 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/f8dd3f21e434?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-20 18:46:25,926 unpacking tar archive at: mozilla-central-f8dd3f21e434/testing/mozharness/ program finished with exit code 0 elapsedTime=0.747259 ========= master_lag: 0.16 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-20 18:46:26.494313) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-20 18:46:26.494612) ========= script_repo_revision: f8dd3f21e434be32fe5901849f1723b0e64cf668 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-20 18:46:26.498044) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-20 18:46:26.498307) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-20 18:46:26.511963) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 44 secs) (at 2017-09-20 18:46:26.512353) ========= /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=41bf3e9aed84707956354da8000001c5-1505958174.735341-1296699532 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 18:46:26 INFO - MultiFileLogger online at 20170920 18:46:26 in /builds/slave/test 18:46: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 18:46:26 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:46:26 INFO - {'append_to_log': False, 18:46:26 INFO - 'base_work_dir': '/builds/slave/test', 18:46:26 INFO - 'blob_upload_branch': 'Firefox', 18:46:26 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:46:26 INFO - 'branch': 'Firefox', 18:46:26 INFO - 'buildbot_json_path': 'buildprops.json', 18:46:26 INFO - 'code_coverage': False, 18:46:26 INFO - 'config_files': ('talos/linux_config.py',), 18:46:26 INFO - 'default_actions': ('clobber', 18:46:26 INFO - 'read-buildbot-config', 18:46:26 INFO - 'download-and-extract', 18:46:26 INFO - 'populate-webroot', 18:46:26 INFO - 'create-virtualenv', 18:46:26 INFO - 'install', 18:46:26 INFO - 'setup-mitmproxy', 18:46:26 INFO - 'run-tests'), 18:46:26 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:46:26 INFO - 'disable_ccov_upload': False, 18:46:26 INFO - 'disable_stylo': False, 18:46:26 INFO - 'download_minidump_stackwalk': True, 18:46:26 INFO - 'download_symbols': 'ondemand', 18:46:26 INFO - 'enable_stylo': False, 18:46:26 INFO - 'enable_webrender': False, 18:46:26 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 18:46:26 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:46:26 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:46:26 INFO - 'gecko_profile': False, 18:46:26 INFO - 'gecko_profile_interval': 0, 18:46:26 INFO - 'installer_path': 'installer.exe', 18:46:26 INFO - 'log_level': 'info', 18:46:26 INFO - 'log_name': 'talos', 18:46:26 INFO - 'log_to_console': True, 18:46:26 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 18:46:26 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 18:46:26 INFO - 'opt_config_files': (), 18:46:26 INFO - 'pip_index': False, 18:46:26 INFO - 'suite': 'dromaeojs-e10s', 18:46:26 INFO - 'system_bits': '32', 18:46:26 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:46:26 INFO - 'title': 'talos-linux64-ix-026', 18:46:26 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:46:26 INFO - 'use_talos_json': True, 18:46:26 INFO - 'verify': 'False', 18:46:26 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:46:26 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:46:26 INFO - 'work_dir': 'build'} 18:46:26 INFO - [mozharness: 2017-09-21 01:46:26.715163Z] Running clobber step. 18:46:26 INFO - Running pre-action listener: _resource_record_pre_action 18:46:26 INFO - Running main action method: clobber 18:46:26 INFO - rmtree: /builds/slave/test/build 18:46:26 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:46:27 INFO - Running post-action listener: _resource_record_post_action 18:46:27 INFO - [mozharness: 2017-09-21 01:46:27.662077Z] Finished clobber step (success) 18:46:27 INFO - [mozharness: 2017-09-21 01:46:27.662174Z] Running read-buildbot-config step. 18:46:27 INFO - Running pre-action listener: _resource_record_pre_action 18:46:27 INFO - Running main action method: read_buildbot_config 18:46:27 INFO - Using buildbot properties: 18:46:27 INFO - { 18:46:27 INFO - "project": "", 18:46:27 INFO - "product": "firefox", 18:46:27 INFO - "who": "kwierso@gmail.com", 18:46:27 INFO - "installer_path": "public/build/target.tar.bz2", 18:46:27 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 18:46:27 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 18:46:27 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs-e10s", 18:46:27 INFO - "stage_platform": "linux64-pgo", 18:46:27 INFO - "basedir": "/builds/slave/test", 18:46:27 INFO - "buildnumber": 189, 18:46:27 INFO - "platform": "ubuntu64_hw", 18:46:27 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 18:46:27 INFO - "slavebuilddir": "test", 18:46:27 INFO - "taskId": "VmR58-ZyTxSPM5QVLldJTA", 18:46:27 INFO - "branch": "mozilla-central", 18:46:27 INFO - "script_repo_revision": "production", 18:46:27 INFO - "revision": "f8dd3f21e434be32fe5901849f1723b0e64cf668", 18:46:27 INFO - "slavename": "talos-linux64-ix-026", 18:46:27 INFO - "repo_path": "mozilla-central" 18:46:27 INFO - } 18:46:27 INFO - Finding installer, test and symbols from parent task. 18:46:27 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VmR58-ZyTxSPM5QVLldJTA'}, attempt #1 18:46:28 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VmR58-ZyTxSPM5QVLldJTA'}, attempt #1 18:46:28 INFO - Task dependencies: BqbOEH4RRjOmGYuwp-1rsQ 18:46:28 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BqbOEH4RRjOmGYuwp-1rsQ'}, attempt #1 18:46:29 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/BqbOEH4RRjOmGYuwp-1rsQ/artifacts/public/build/target.tar.bz2 18:46:29 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/BqbOEH4RRjOmGYuwp-1rsQ/artifacts/public/build/target.test_packages.json 18:46:29 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/BqbOEH4RRjOmGYuwp-1rsQ/artifacts/public/build/target.crashreporter-symbols.zip 18:46:29 INFO - Running post-action listener: _resource_record_post_action 18:46:29 INFO - [mozharness: 2017-09-21 01:46:29.013602Z] Finished read-buildbot-config step (success) 18:46:29 INFO - [mozharness: 2017-09-21 01:46:29.013821Z] Running download-and-extract step. 18:46:29 INFO - Running pre-action listener: _resource_record_pre_action 18:46:29 INFO - Running main action method: download_and_extract 18:46:29 INFO - mkdir: /builds/slave/test/build/tests 18:46:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:46:29 INFO - trying https://queue.taskcluster.net/v1/task/BqbOEH4RRjOmGYuwp-1rsQ/artifacts/public/build/target.test_packages.json 18:46:29 INFO - Downloading https://queue.taskcluster.net/v1/task/BqbOEH4RRjOmGYuwp-1rsQ/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 18:46:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BqbOEH4RRjOmGYuwp-1rsQ/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 18:46:29 INFO - Downloaded 989 bytes. 18:46:29 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 18:46:29 INFO - Using the following test package requirements: 18:46:29 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 18:46:29 INFO - u'common': [u'target.common.tests.zip'], 18:46:29 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 18:46:29 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 18:46:29 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 18:46:29 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 18:46:29 INFO - u'mozbase': [u'target.common.tests.zip'], 18:46:29 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 18:46:29 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 18:46:29 INFO - u'web-platform': [u'target.common.tests.zip', 18:46:29 INFO - u'target.web-platform.tests.tar.gz'], 18:46:29 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 18:46:29 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 18:46:29 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/BqbOEH4RRjOmGYuwp-1rsQ/artifacts/public/build/target.common.tests.zip 18:46:29 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/BqbOEH4RRjOmGYuwp-1rsQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 18:46:29 INFO - Fetch https://queue.taskcluster.net/v1/task/BqbOEH4RRjOmGYuwp-1rsQ/artifacts/public/build/target.common.tests.zip into memory 18:46:31 INFO - Content-Length response header: 55285111 18:46:31 INFO - Bytes received: 55285111 18:46:42 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/BqbOEH4RRjOmGYuwp-1rsQ/artifacts/public/build/target.talos.tests.zip 18:46:42 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/BqbOEH4RRjOmGYuwp-1rsQ/artifacts/public/build/target.talos.tests.zip'}, attempt #1 18:46:42 INFO - Fetch https://queue.taskcluster.net/v1/task/BqbOEH4RRjOmGYuwp-1rsQ/artifacts/public/build/target.talos.tests.zip into memory 18:46:44 INFO - Content-Length response header: 14512509 18:46:44 INFO - Bytes received: 14512509 18:46:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:46:44 INFO - trying https://queue.taskcluster.net/v1/task/BqbOEH4RRjOmGYuwp-1rsQ/artifacts/public/build/target.tar.bz2 18:46:44 INFO - Downloading https://queue.taskcluster.net/v1/task/BqbOEH4RRjOmGYuwp-1rsQ/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 18:46:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BqbOEH4RRjOmGYuwp-1rsQ/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 18:46:47 INFO - Downloaded 62235041 bytes. 18:46:47 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/BqbOEH4RRjOmGYuwp-1rsQ/artifacts/public/build/target.tar.bz2 18:46:47 INFO - mkdir: /builds/slave/test/properties 18:46:47 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:46:47 INFO - Writing to file /builds/slave/test/properties/build_url 18:46:47 INFO - Contents: 18:46:47 INFO - build_url:https://queue.taskcluster.net/v1/task/BqbOEH4RRjOmGYuwp-1rsQ/artifacts/public/build/target.tar.bz2 18:46:47 INFO - Running post-action listener: _resource_record_post_action 18:46:47 INFO - Running post-action listener: find_tests_for_verification 18:46:47 INFO - Running post-action listener: set_extra_try_arguments 18:46:47 INFO - [mozharness: 2017-09-21 01:46:47.135036Z] Finished download-and-extract step (success) 18:46:47 INFO - [mozharness: 2017-09-21 01:46:47.135134Z] Running populate-webroot step. 18:46:47 INFO - Running pre-action listener: _resource_record_pre_action 18:46:47 INFO - Running main action method: populate_webroot 18:46:47 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:46:47 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 18:46:47 INFO - 'tests': ['tresize', 18:46:47 INFO - 'tcanvasmark']}, 18:46:47 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:46:47 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 18:46:47 INFO - 'tests': ['dromaeo_css', 18:46:47 INFO - 'kraken']}, 18:46:47 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 18:46:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:46:47 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 18:46:47 INFO - 'talos_options': ['--disable-stylo'], 18:46:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:46:47 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 18:46:47 INFO - 'tests': ['damp', 'tps']}, 18:46:47 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 18:46:47 INFO - 'talos_options': ['--disable-stylo'], 18:46:47 INFO - 'tests': ['damp', 'tps']}, 18:46:47 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:46:47 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 18:46:47 INFO - 'tests': ['dromaeo_dom']}, 18:46:47 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 18:46:47 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 18:46:47 INFO - 'tests': ['basic_compositor_video', 18:46:47 INFO - 'glvideo']}, 18:46:47 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 18:46:47 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 18:46:47 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 18:46:47 INFO - 'talos_options': ['--disable-stylo'], 18:46:47 INFO - 'tests': ['ts_paint_webext', 18:46:47 INFO - 'tp5o_webext']}, 18:46:47 INFO - 'other-e10s': {'tests': ['a11yr', 18:46:47 INFO - 'ts_paint', 18:46:47 INFO - 'tpaint', 18:46:47 INFO - 'sessionrestore', 18:46:47 INFO - 'sessionrestore_many_windows', 18:46:47 INFO - 'sessionrestore_no_auto_restore', 18:46:47 INFO - 'tabpaint']}, 18:46:47 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 18:46:47 INFO - 'tests': ['a11yr', 18:46:47 INFO - 'ts_paint', 18:46:47 INFO - 'tpaint', 18:46:47 INFO - 'sessionrestore', 18:46:47 INFO - 'sessionrestore_many_windows', 18:46:47 INFO - 'sessionrestore_no_auto_restore', 18:46:47 INFO - 'tabpaint']}, 18:46:47 INFO - 'perf-reftest-e10s': {'tests': ['perf_reftest']}, 18:46:47 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']}, 18:46:47 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 18:46:47 INFO - 'tests': ['perf_reftest_singletons']}, 18:46:47 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 18:46:47 INFO - 'tests': ['bloom_basic']}, 18:46:47 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:46:47 INFO - 'tsvgr_opacity', 18:46:47 INFO - 'tart', 18:46:47 INFO - 'tscrollx', 18:46:47 INFO - 'tsvg_static']}, 18:46:47 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 18:46:47 INFO - 'tests': ['tsvgx', 18:46:47 INFO - 'tsvgr_opacity', 18:46:47 INFO - 'tart', 18:46:47 INFO - 'tscrollx', 18:46:47 INFO - 'tsvg_static']}, 18:46:47 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 18:46:47 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 18:46:47 INFO - 'talos_options': ['--disable-stylo'], 18:46:47 INFO - 'tests': ['tp5o']}, 18:46:47 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 18:46:47 INFO - 'talos_options': ['--mitmproxy', 18:46:47 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 18:46:47 INFO - '--firstNonBlankPaint'], 18:46:47 INFO - 'tests': ['tp6_google', 18:46:47 INFO - 'tp6_youtube', 18:46:47 INFO - 'tp6_amazon', 18:46:47 INFO - 'tp6_facebook']}, 18:46:47 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 18:46:47 INFO - 'talos_options': ['--disable-stylo', 18:46:47 INFO - '--mitmproxy', 18:46:47 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 18:46:47 INFO - '--firstNonBlankPaint'], 18:46:47 INFO - 'tests': ['tp6_google', 18:46:47 INFO - 'tp6_youtube', 18:46:47 INFO - 'tp6_amazon', 18:46:47 INFO - 'tp6_facebook']}, 18:46:47 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 18:46:47 INFO - 'talos_options': ['--stylo-threads=1', 18:46:47 INFO - '--mitmproxy', 18:46:47 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 18:46:47 INFO - '--firstNonBlankPaint'], 18:46:47 INFO - 'tests': ['tp6_google', 18:46:47 INFO - 'tp6_youtube', 18:46:47 INFO - 'tp6_amazon', 18:46:47 INFO - 'tp6_facebook']}, 18:46:47 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 18:46:47 INFO - 'talos_options': ['--xperf_path', 18:46:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:46:47 INFO - 'tests': ['tp5n']}, 18:46:47 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 18:46:47 INFO - 'talos_options': ['--disable-stylo', 18:46:47 INFO - '--xperf_path', 18:46:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:46:47 INFO - 'tests': ['tp5n']}}} 18:46:47 INFO - Running post-action listener: _resource_record_post_action 18:46:47 INFO - [mozharness: 2017-09-21 01:46:47.151796Z] Finished populate-webroot step (success) 18:46:47 INFO - [mozharness: 2017-09-21 01:46:47.151886Z] Running create-virtualenv step. 18:46:47 INFO - Running pre-action listener: _resource_record_pre_action 18:46:47 INFO - Running main action method: create_virtualenv 18:46:47 INFO - Creating virtualenv /builds/slave/test/build/venv 18:46:47 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 18:46:47 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 18:46:47 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 18:46:47 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:46:47 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:46:48 INFO - Installing distribute.............................................................................................................................................................................................done. 18:46:48 INFO - Installing pip...............done. 18:46:48 INFO - Return code: 0 18:46:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 18:46:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 18:46:48 INFO - Reading from file tmpfile_stdout 18:46:48 INFO - Output received: 18:46:48 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 18:46:48 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:46:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:46:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:46:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:46:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:46:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ded1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fb6a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2159a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x215d090>, '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 0x215dd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2157f90>, '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': '41bf3e9aed84707956354da8000001c5-1505958174.735341-1296699532', '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 18:46:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 18:46:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 18:46:48 INFO - Using env: {'DISPLAY': ':0', 18:46:48 INFO - 'HOME': '/home/cltbld', 18:46:48 INFO - 'LANG': 'en_US.UTF-8', 18:46:48 INFO - 'LANGUAGE': 'en_US:en', 18:46:48 INFO - 'LOGNAME': 'cltbld', 18:46:48 INFO - 'MAIL': '/var/mail/cltbld', 18:46:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:46:48 INFO - 'MOZ_NO_REMOTE': '1', 18:46:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:46:48 INFO - 'NO_EM_RESTART': '1', 18:46:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:46:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:46:48 INFO - 'PWD': '/builds/slave/test', 18:46:48 INFO - 'SHELL': '/bin/bash', 18:46:48 INFO - 'SHLVL': '1', 18:46:48 INFO - 'TERM': 'linux', 18:46:48 INFO - 'TMOUT': '86400', 18:46:48 INFO - 'USER': 'cltbld', 18:46:48 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1505958174.735341-1296699532', 18:46:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:46:48 INFO - '_': '/tools/buildbot/bin/python'} 18:46:49 INFO - Ignoring indexes: http://pypi.python.org/simple/ 18:46:49 INFO - Downloading/unpacking pip>=1.5 18:46:49 INFO - Running setup.py egg_info for package pip 18:46:49 INFO - warning: no files found matching 'pip/cacert.pem' 18:46:49 INFO - warning: no files found matching '*.html' under directory 'docs' 18:46:49 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 18:46:49 INFO - no previously-included directories found matching 'docs/_build/_sources' 18:46:49 INFO - Installing collected packages: pip 18:46:49 INFO - Found existing installation: pip 1.1 18:46:49 INFO - Uninstalling pip: 18:46:49 INFO - Successfully uninstalled pip 18:46:49 INFO - Running setup.py install for pip 18:46:49 INFO - warning: no files found matching 'pip/cacert.pem' 18:46:49 INFO - warning: no files found matching '*.html' under directory 'docs' 18:46:49 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 18:46:49 INFO - no previously-included directories found matching 'docs/_build/_sources' 18:46:49 INFO - Installing pip script to /builds/slave/test/build/venv/bin 18:46:49 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 18:46:49 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 18:46:49 INFO - Successfully installed pip 18:46:49 INFO - Cleaning up... 18:46:49 INFO - Return code: 0 18:46:49 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:46:49 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')]} 18:46:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:46:49 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')]} 18:46:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:46:49 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 0x1ded1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fb6a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2159a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x215d090>, '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 0x215dd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2157f90>, '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': '41bf3e9aed84707956354da8000001c5-1505958174.735341-1296699532', '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 18:46:49 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 18:46:49 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 18:46:49 INFO - Using env: {'DISPLAY': ':0', 18:46:49 INFO - 'HOME': '/home/cltbld', 18:46:49 INFO - 'LANG': 'en_US.UTF-8', 18:46:49 INFO - 'LANGUAGE': 'en_US:en', 18:46:49 INFO - 'LOGNAME': 'cltbld', 18:46:49 INFO - 'MAIL': '/var/mail/cltbld', 18:46:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:46:49 INFO - 'MOZ_NO_REMOTE': '1', 18:46:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:46:49 INFO - 'NO_EM_RESTART': '1', 18:46:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:46:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:46:49 INFO - 'PWD': '/builds/slave/test', 18:46:49 INFO - 'SHELL': '/bin/bash', 18:46:49 INFO - 'SHLVL': '1', 18:46:49 INFO - 'TERM': 'linux', 18:46:49 INFO - 'TMOUT': '86400', 18:46:49 INFO - 'USER': 'cltbld', 18:46:49 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1505958174.735341-1296699532', 18:46:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:46:49 INFO - '_': '/tools/buildbot/bin/python'} 18:46:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:46:49 INFO - Downloading/unpacking psutil>=3.1.1 18:46:49 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 18:46:49 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 18:46:49 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 18:46:49 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 18:46:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:46:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:46:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:46:51 INFO - Installing collected packages: psutil 18:46:51 INFO - Running setup.py install for psutil 18:46:51 INFO - building 'psutil._psutil_linux' extension 18:46:51 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 18:46:54 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 18:46:54 INFO - building 'psutil._psutil_posix' extension 18:46:54 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 18:46:54 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 18:46:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:46:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:46:54 INFO - Successfully installed psutil 18:46:54 INFO - Cleaning up... 18:46:54 INFO - Return code: 0 18:46:54 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:46: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')]} 18:46:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:46: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')]} 18:46:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:46: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 0x1ded1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fb6a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2159a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x215d090>, '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 0x215dd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2157f90>, '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': '41bf3e9aed84707956354da8000001c5-1505958174.735341-1296699532', '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 18:46: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 18:46: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 18:46:54 INFO - Using env: {'DISPLAY': ':0', 18:46:54 INFO - 'HOME': '/home/cltbld', 18:46:54 INFO - 'LANG': 'en_US.UTF-8', 18:46:54 INFO - 'LANGUAGE': 'en_US:en', 18:46:54 INFO - 'LOGNAME': 'cltbld', 18:46:54 INFO - 'MAIL': '/var/mail/cltbld', 18:46:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:46:54 INFO - 'MOZ_NO_REMOTE': '1', 18:46:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:46:54 INFO - 'NO_EM_RESTART': '1', 18:46:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:46:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:46:54 INFO - 'PWD': '/builds/slave/test', 18:46:54 INFO - 'SHELL': '/bin/bash', 18:46:54 INFO - 'SHLVL': '1', 18:46:54 INFO - 'TERM': 'linux', 18:46:54 INFO - 'TMOUT': '86400', 18:46:54 INFO - 'USER': 'cltbld', 18:46:54 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1505958174.735341-1296699532', 18:46:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:46:54 INFO - '_': '/tools/buildbot/bin/python'} 18:46:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:46:54 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:46: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 18:46: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 18:46: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 18:46: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 18:46:55 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:46:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:46:55 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:46:55 INFO - Installing collected packages: mozsystemmonitor 18:46:55 INFO - Running setup.py install for mozsystemmonitor 18:46:56 INFO - Successfully installed mozsystemmonitor 18:46:56 INFO - Cleaning up... 18:46:56 INFO - Return code: 0 18:46:56 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:46: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')]} 18:46:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:46: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')]} 18:46:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:46: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 0x1ded1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fb6a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2159a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x215d090>, '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 0x215dd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2157f90>, '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': '41bf3e9aed84707956354da8000001c5-1505958174.735341-1296699532', '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 18:46: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 18:46: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 18:46:56 INFO - Using env: {'DISPLAY': ':0', 18:46:56 INFO - 'HOME': '/home/cltbld', 18:46:56 INFO - 'LANG': 'en_US.UTF-8', 18:46:56 INFO - 'LANGUAGE': 'en_US:en', 18:46:56 INFO - 'LOGNAME': 'cltbld', 18:46:56 INFO - 'MAIL': '/var/mail/cltbld', 18:46:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:46:56 INFO - 'MOZ_NO_REMOTE': '1', 18:46:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:46:56 INFO - 'NO_EM_RESTART': '1', 18:46:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:46:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:46:56 INFO - 'PWD': '/builds/slave/test', 18:46:56 INFO - 'SHELL': '/bin/bash', 18:46:56 INFO - 'SHLVL': '1', 18:46:56 INFO - 'TERM': 'linux', 18:46:56 INFO - 'TMOUT': '86400', 18:46:56 INFO - 'USER': 'cltbld', 18:46:56 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1505958174.735341-1296699532', 18:46:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:46:56 INFO - '_': '/tools/buildbot/bin/python'} 18:46:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:46:56 INFO - Downloading/unpacking jsonschema==2.5.1 18:46: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 18:46: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 18:46: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 18:46: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 18:46:57 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:46:57 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:46: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 18:46: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 18:46: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 18:46: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 18:46:57 INFO - Downloading functools32-3.2.3-2.tar.gz 18:46:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:46:57 INFO - warning: no files found matching '*.txt' 18:46:57 INFO - no previously-included directories found matching 'build' 18:46:57 INFO - no previously-included directories found matching 'dist' 18:46:57 INFO - no previously-included directories found matching '.git*' 18:46:57 INFO - Installing collected packages: jsonschema, functools32 18:46:57 INFO - Running setup.py install for functools32 18:46:57 INFO - warning: no files found matching '*.txt' 18:46:57 INFO - no previously-included directories found matching 'build' 18:46:57 INFO - no previously-included directories found matching 'dist' 18:46:57 INFO - no previously-included directories found matching '.git*' 18:46:57 INFO - Successfully installed jsonschema functools32 18:46:57 INFO - Cleaning up... 18:46:57 INFO - Return code: 0 18:46:57 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:46:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:46:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:46:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:46:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:46:57 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 0x1ded1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fb6a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2159a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x215d090>, '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 0x215dd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2157f90>, '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': '41bf3e9aed84707956354da8000001c5-1505958174.735341-1296699532', '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 18:46:57 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 18:46:57 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 18:46:57 INFO - Using env: {'DISPLAY': ':0', 18:46:57 INFO - 'HOME': '/home/cltbld', 18:46:57 INFO - 'LANG': 'en_US.UTF-8', 18:46:57 INFO - 'LANGUAGE': 'en_US:en', 18:46:57 INFO - 'LOGNAME': 'cltbld', 18:46:57 INFO - 'MAIL': '/var/mail/cltbld', 18:46:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:46:57 INFO - 'MOZ_NO_REMOTE': '1', 18:46:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:46:57 INFO - 'NO_EM_RESTART': '1', 18:46:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:46:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:46:57 INFO - 'PWD': '/builds/slave/test', 18:46:57 INFO - 'SHELL': '/bin/bash', 18:46:57 INFO - 'SHLVL': '1', 18:46:57 INFO - 'TERM': 'linux', 18:46:57 INFO - 'TMOUT': '86400', 18:46:57 INFO - 'USER': 'cltbld', 18:46:57 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1505958174.735341-1296699532', 18:46:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:46:57 INFO - '_': '/tools/buildbot/bin/python'} 18:46:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:46:57 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:46:57 INFO - Cleaning up... 18:46:57 INFO - Return code: 0 18:46:57 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:46:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:46:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:46:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:46:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:46: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 0x1ded1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fb6a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2159a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x215d090>, '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 0x215dd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2157f90>, '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': '41bf3e9aed84707956354da8000001c5-1505958174.735341-1296699532', '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 18:46: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 18:46: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 18:46:58 INFO - Using env: {'DISPLAY': ':0', 18:46:58 INFO - 'HOME': '/home/cltbld', 18:46:58 INFO - 'LANG': 'en_US.UTF-8', 18:46:58 INFO - 'LANGUAGE': 'en_US:en', 18:46:58 INFO - 'LOGNAME': 'cltbld', 18:46:58 INFO - 'MAIL': '/var/mail/cltbld', 18:46:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:46:58 INFO - 'MOZ_NO_REMOTE': '1', 18:46:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:46:58 INFO - 'NO_EM_RESTART': '1', 18:46:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:46:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:46:58 INFO - 'PWD': '/builds/slave/test', 18:46:58 INFO - 'SHELL': '/bin/bash', 18:46:58 INFO - 'SHLVL': '1', 18:46:58 INFO - 'TERM': 'linux', 18:46:58 INFO - 'TMOUT': '86400', 18:46:58 INFO - 'USER': 'cltbld', 18:46:58 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1505958174.735341-1296699532', 18:46:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:46:58 INFO - '_': '/tools/buildbot/bin/python'} 18:46:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:46:58 INFO - Downloading/unpacking blobuploader==1.2.4 18:46: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 18:46: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 18:46: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 18:46: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 18:46:59 INFO - Downloading blobuploader-1.2.4.tar.gz 18:46:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:46:59 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:46: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 18:46: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 18:46: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 18:46: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 18:46:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:46:59 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:46: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 18:46: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 18:46: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 18:46: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 18:46:59 INFO - Downloading docopt-0.6.1.tar.gz 18:46:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:46:59 INFO - Installing collected packages: blobuploader, requests, docopt 18:46:59 INFO - Running setup.py install for blobuploader 18:46:59 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:47:00 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:47:00 INFO - Running setup.py install for requests 18:47:00 INFO - Running setup.py install for docopt 18:47:00 INFO - Successfully installed blobuploader requests docopt 18:47:00 INFO - Cleaning up... 18:47:00 INFO - Return code: 0 18:47:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:47:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:47:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:47:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:47:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:47:00 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 0x1ded1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fb6a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2159a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x215d090>, '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 0x215dd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2157f90>, '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': '41bf3e9aed84707956354da8000001c5-1505958174.735341-1296699532', '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 18:47:00 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 18:47:00 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 18:47:00 INFO - Using env: {'DISPLAY': ':0', 18:47:00 INFO - 'HOME': '/home/cltbld', 18:47:00 INFO - 'LANG': 'en_US.UTF-8', 18:47:00 INFO - 'LANGUAGE': 'en_US:en', 18:47:00 INFO - 'LOGNAME': 'cltbld', 18:47:00 INFO - 'MAIL': '/var/mail/cltbld', 18:47:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:47:00 INFO - 'MOZ_NO_REMOTE': '1', 18:47:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:47:00 INFO - 'NO_EM_RESTART': '1', 18:47:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:47:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:47:00 INFO - 'PWD': '/builds/slave/test', 18:47:00 INFO - 'SHELL': '/bin/bash', 18:47:00 INFO - 'SHLVL': '1', 18:47:00 INFO - 'TERM': 'linux', 18:47:00 INFO - 'TMOUT': '86400', 18:47:00 INFO - 'USER': 'cltbld', 18:47:00 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1505958174.735341-1296699532', 18:47:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:47:00 INFO - '_': '/tools/buildbot/bin/python'} 18:47:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:47:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:47:00 INFO - Running setup.py (path:/tmp/pip-MkZH8y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:47:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:47:00 INFO - Running setup.py (path:/tmp/pip-Vn6XK1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:47:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:47:00 INFO - Running setup.py (path:/tmp/pip-viNtnI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:47:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:47:00 INFO - Running setup.py (path:/tmp/pip-64Yqoi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:47:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:47:01 INFO - Running setup.py (path:/tmp/pip-My6R8q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:47:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:47:01 INFO - Running setup.py (path:/tmp/pip-cJVbEm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:47:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:47:01 INFO - Running setup.py (path:/tmp/pip-LSQvi8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:47:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:47:01 INFO - Running setup.py (path:/tmp/pip-cKXI4R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:47:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:47:01 INFO - Running setup.py (path:/tmp/pip-b8lHiK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:47:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:47:01 INFO - Running setup.py (path:/tmp/pip-AUOFde-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:47:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:47:01 INFO - Running setup.py (path:/tmp/pip-YhlCYk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:47:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:47:01 INFO - Running setup.py (path:/tmp/pip-j0_6qg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:47:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:47:01 INFO - Running setup.py (path:/tmp/pip-sJEAMA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:47:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:47:01 INFO - Running setup.py (path:/tmp/pip-kpsKR5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:47:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:47:01 INFO - Running setup.py (path:/tmp/pip-yNM9oF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:47:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:47:01 INFO - Running setup.py (path:/tmp/pip-A_1qgJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:47:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:47:01 INFO - Running setup.py (path:/tmp/pip-29D2R5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:47:01 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:47:01 INFO - Running setup.py install for manifestparser 18:47:02 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:47:02 INFO - Running setup.py install for mozcrash 18:47:02 INFO - Running setup.py install for mozdebug 18:47:02 INFO - Running setup.py install for mozdevice 18:47:02 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:47:02 INFO - Running setup.py install for mozfile 18:47:02 INFO - Running setup.py install for mozhttpd 18:47:02 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:47:02 INFO - Running setup.py install for mozinfo 18:47:02 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:47:02 INFO - Running setup.py install for mozInstall 18:47:02 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:47:02 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:47:02 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:47:02 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:47:02 INFO - Running setup.py install for mozleak 18:47:02 INFO - Running setup.py install for mozlog 18:47:02 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:47:02 INFO - Running setup.py install for moznetwork 18:47:03 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:47:03 INFO - Running setup.py install for mozprocess 18:47:03 INFO - Running setup.py install for mozprofile 18:47:03 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:47:03 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:47:03 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:47:03 INFO - Running setup.py install for mozrunner 18:47:03 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:47:03 INFO - Running setup.py install for mozscreenshot 18:47:03 INFO - Running setup.py install for moztest 18:47:03 INFO - Running setup.py install for mozversion 18:47:03 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:47:03 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:47:03 INFO - Cleaning up... 18:47:03 INFO - Return code: 0 18:47:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:47:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:47:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:47:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:47:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:47:03 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 0x1ded1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fb6a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2159a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x215d090>, '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 0x215dd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2157f90>, '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': '41bf3e9aed84707956354da8000001c5-1505958174.735341-1296699532', '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 18:47:03 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 18:47:03 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 18:47:03 INFO - Using env: {'DISPLAY': ':0', 18:47:03 INFO - 'HOME': '/home/cltbld', 18:47:03 INFO - 'LANG': 'en_US.UTF-8', 18:47:03 INFO - 'LANGUAGE': 'en_US:en', 18:47:03 INFO - 'LOGNAME': 'cltbld', 18:47:03 INFO - 'MAIL': '/var/mail/cltbld', 18:47:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:47:03 INFO - 'MOZ_NO_REMOTE': '1', 18:47:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:47:03 INFO - 'NO_EM_RESTART': '1', 18:47:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:47:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:47:03 INFO - 'PWD': '/builds/slave/test', 18:47:03 INFO - 'SHELL': '/bin/bash', 18:47:03 INFO - 'SHLVL': '1', 18:47:03 INFO - 'TERM': 'linux', 18:47:03 INFO - 'TMOUT': '86400', 18:47:03 INFO - 'USER': 'cltbld', 18:47:03 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1505958174.735341-1296699532', 18:47:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:47:03 INFO - '_': '/tools/buildbot/bin/python'} 18:47:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:47:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:47:03 INFO - Running setup.py (path:/tmp/pip-ec73yi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:47: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)) 18:47:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:47:04 INFO - Running setup.py (path:/tmp/pip-KflkTi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:47: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)) 18:47:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:47:04 INFO - Running setup.py (path:/tmp/pip-CDWvr2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:47: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)) 18:47:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:47:04 INFO - Running setup.py (path:/tmp/pip-lvXZTK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:47: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)) 18:47:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:47:04 INFO - Running setup.py (path:/tmp/pip-JZ_guf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:47: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)) 18:47:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:47:04 INFO - Running setup.py (path:/tmp/pip-8Bn9mJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:47: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)) 18:47:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:47:04 INFO - Running setup.py (path:/tmp/pip-3hwLgd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:47: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)) 18:47:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:47:04 INFO - Running setup.py (path:/tmp/pip-KTDq9Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:47:04 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)) 18:47:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:47:04 INFO - Running setup.py (path:/tmp/pip-xDJOtH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:47:04 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)) 18:47:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:47:04 INFO - Running setup.py (path:/tmp/pip-hT0Lmr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:47:04 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)) 18:47:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:47:04 INFO - Running setup.py (path:/tmp/pip-jKxldx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:47:04 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)) 18:47:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:47:04 INFO - Running setup.py (path:/tmp/pip-ii_N9x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:47:04 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)) 18:47:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:47:04 INFO - Running setup.py (path:/tmp/pip-e9BCPH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:47:04 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)) 18:47:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:47:04 INFO - Running setup.py (path:/tmp/pip-mRblTk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:47:04 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)) 18:47:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:47:04 INFO - Running setup.py (path:/tmp/pip-zWFBsO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:47: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)) 18:47:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:47:05 INFO - Running setup.py (path:/tmp/pip-hSAAEV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:47: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)) 18:47:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:47:05 INFO - Running setup.py (path:/tmp/pip-OZRdDV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:47: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)) 18:47: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)) 18:47: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)) 18:47: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)) 18:47: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)) 18:47: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)) 18:47: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)) 18:47:05 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:47: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 18:47: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 18:47: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 18:47: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 18:47:06 INFO - Downloading blessings-1.6.tar.gz 18:47:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:47:06 INFO - Installing collected packages: blessings 18:47:06 INFO - Running setup.py install for blessings 18:47:06 INFO - Successfully installed blessings 18:47:06 INFO - Cleaning up... 18:47:06 INFO - Return code: 0 18:47:06 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:47:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:47:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:47:06 INFO - Reading from file tmpfile_stdout 18:47:06 INFO - Current package versions: 18:47:06 INFO - argparse == 1.2.1 18:47:06 INFO - blessings == 1.6 18:47:06 INFO - blobuploader == 1.2.4 18:47:06 INFO - docopt == 0.6.1 18:47:06 INFO - functools32 == 3.2.3-2 18:47:06 INFO - jsonschema == 2.5.1 18:47:06 INFO - manifestparser == 1.1 18:47:06 INFO - mozInstall == 1.13 18:47:06 INFO - mozcrash == 1.0 18:47:06 INFO - mozdebug == 0.1 18:47:06 INFO - mozdevice == 0.51 18:47:06 INFO - mozfile == 1.2 18:47:06 INFO - mozhttpd == 0.7 18:47:06 INFO - mozinfo == 0.10 18:47:06 INFO - mozleak == 0.1 18:47:06 INFO - mozlog == 3.5 18:47:06 INFO - moznetwork == 0.27 18:47:06 INFO - mozprocess == 0.25 18:47:06 INFO - mozprofile == 0.28 18:47:06 INFO - mozrunner == 6.13 18:47:06 INFO - mozscreenshot == 0.1 18:47:06 INFO - mozsystemmonitor == 0.3 18:47:06 INFO - moztest == 0.8 18:47:06 INFO - mozversion == 1.4 18:47:06 INFO - psutil == 3.1.1 18:47:06 INFO - requests == 1.2.3 18:47:06 INFO - wsgiref == 0.1.2 18:47:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:47:06 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')]} 18:47:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:47:06 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')]} 18:47:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:47:06 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 0x1ded1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fb6a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2159a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x215d090>, '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 0x215dd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2157f90>, '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': '41bf3e9aed84707956354da8000001c5-1505958174.735341-1296699532', '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 18:47:06 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 18:47:06 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 18:47:06 INFO - Using env: {'DISPLAY': ':0', 18:47:06 INFO - 'HOME': '/home/cltbld', 18:47:06 INFO - 'LANG': 'en_US.UTF-8', 18:47:06 INFO - 'LANGUAGE': 'en_US:en', 18:47:06 INFO - 'LOGNAME': 'cltbld', 18:47:06 INFO - 'MAIL': '/var/mail/cltbld', 18:47:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:47:06 INFO - 'MOZ_NO_REMOTE': '1', 18:47:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:47:06 INFO - 'NO_EM_RESTART': '1', 18:47:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:47:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:47:06 INFO - 'PWD': '/builds/slave/test', 18:47:06 INFO - 'SHELL': '/bin/bash', 18:47:06 INFO - 'SHLVL': '1', 18:47:06 INFO - 'TERM': 'linux', 18:47:06 INFO - 'TMOUT': '86400', 18:47:06 INFO - 'USER': 'cltbld', 18:47:06 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1505958174.735341-1296699532', 18:47:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:47:06 INFO - '_': '/tools/buildbot/bin/python'} 18:47:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:47:06 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)) 18:47:06 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)) 18:47:06 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)) 18:47:06 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)) 18:47:06 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)) 18:47:06 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)) 18:47:06 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)) 18:47:06 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)) 18:47:06 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)) 18:47:06 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 18:47:06 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 18:47:06 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 18:47:06 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 18:47:06 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 18:47:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 18:47: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)) 18:47: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)) 18:47:08 INFO - Installing collected packages: simplejson 18:47:08 INFO - Running setup.py install for simplejson 18:47:08 INFO - building 'simplejson._speedups' extension 18:47: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 18:47: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 18:47:09 INFO - Successfully installed simplejson 18:47:09 INFO - Cleaning up... 18:47:09 INFO - Return code: 0 18:47:09 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:47: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')]} 18:47:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:47: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')]} 18:47:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:47: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 0x1ded1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fb6a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2159a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x215d090>, '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 0x215dd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2157f90>, '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': '41bf3e9aed84707956354da8000001c5-1505958174.735341-1296699532', '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 18:47: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 18:47: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 18:47:09 INFO - Using env: {'DISPLAY': ':0', 18:47:09 INFO - 'HOME': '/home/cltbld', 18:47:09 INFO - 'LANG': 'en_US.UTF-8', 18:47:09 INFO - 'LANGUAGE': 'en_US:en', 18:47:09 INFO - 'LOGNAME': 'cltbld', 18:47:09 INFO - 'MAIL': '/var/mail/cltbld', 18:47:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:47:09 INFO - 'MOZ_NO_REMOTE': '1', 18:47:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:47:09 INFO - 'NO_EM_RESTART': '1', 18:47:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:47:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:47:09 INFO - 'PWD': '/builds/slave/test', 18:47:09 INFO - 'SHELL': '/bin/bash', 18:47:09 INFO - 'SHLVL': '1', 18:47:09 INFO - 'TERM': 'linux', 18:47:09 INFO - 'TMOUT': '86400', 18:47:09 INFO - 'USER': 'cltbld', 18:47:09 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1505958174.735341-1296699532', 18:47:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:47:09 INFO - '_': '/tools/buildbot/bin/python'} 18:47:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:47:09 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:47:09 INFO - Cleaning up... 18:47:09 INFO - Return code: 0 18:47:09 INFO - Running post-action listener: _resource_record_post_action 18:47:09 INFO - Running post-action listener: _start_resource_monitoring 18:47:09 INFO - Starting resource monitoring. 18:47:09 INFO - [mozharness: 2017-09-21 01:47:09.483850Z] Finished create-virtualenv step (success) 18:47:09 INFO - [mozharness: 2017-09-21 01:47:09.484956Z] Running install step. 18:47:09 INFO - Running pre-action listener: _resource_record_pre_action 18:47:09 INFO - Running main action method: install 18:47:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:47:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:47:09 INFO - Reading from file tmpfile_stdout 18:47:09 INFO - Detecting whether we're running mozinstall >=1.0... 18:47:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:47:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:47:09 INFO - Reading from file tmpfile_stdout 18:47:09 INFO - Output received: 18:47:09 INFO - Usage: mozinstall [options] installer 18:47:09 INFO - Options: 18:47:09 INFO - -h, --help show this help message and exit 18:47:09 INFO - -d DEST, --destination=DEST 18:47:09 INFO - Directory to install application into. [default: 18:47:09 INFO - "/builds/slave/test"] 18:47:09 INFO - --app=APP Application being installed. [default: firefox] 18:47:09 INFO - mkdir: /builds/slave/test/build/application 18:47:09 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'] 18:47:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 18:47:27 INFO - Reading from file tmpfile_stdout 18:47:27 INFO - Output received: 18:47:27 INFO - /builds/slave/test/build/application/firefox/firefox 18:47:27 INFO - Running post-action listener: _resource_record_post_action 18:47:27 INFO - [mozharness: 2017-09-21 01:47:27.447817Z] Finished install step (success) 18:47:27 INFO - [mozharness: 2017-09-21 01:47:27.448067Z] Running setup-mitmproxy step. 18:47:27 INFO - Running pre-action listener: _resource_record_pre_action 18:47:27 INFO - Running main action method: setup_mitmproxy 18:47:27 INFO - Skipping: mitmproxy is not required 18:47:27 INFO - Running post-action listener: _resource_record_post_action 18:47:27 INFO - [mozharness: 2017-09-21 01:47:27.449250Z] Finished setup-mitmproxy step (success) 18:47:27 INFO - [mozharness: 2017-09-21 01:47:27.449471Z] Running run-tests step. 18:47:27 INFO - Running pre-action listener: _resource_record_pre_action 18:47:27 INFO - Running pre-action listener: _set_gcov_prefix 18:47:27 INFO - Running main action method: run_tests 18:47:27 WARNING - Try message not found. 18:47:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:47:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:47:27 INFO - Python 2.7.3 18:47:27 INFO - Return code: 0 18:47:27 INFO - grabbing minidump binary from tooltool 18:47:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:47:27 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 0x215d090>, '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 0x215dd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2157f90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:47:27 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 18:47:27 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 18:47:27 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 18:47:27 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:47:27 INFO - Return code: 0 18:47:27 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 18:47:27 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:47:27 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:47:27 INFO - ENV: RUST_BACKTRACE is now full 18:47:27 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 18:47:27 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:47:27 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/BqbOEH4RRjOmGYuwp-1rsQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-026', '--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 18:47:27 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/BqbOEH4RRjOmGYuwp-1rsQ/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-026 --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 18:47:27 INFO - Using env: {'DISPLAY': ':0', 18:47:27 INFO - 'HOME': '/home/cltbld', 18:47:27 INFO - 'LANG': 'en_US.UTF-8', 18:47:27 INFO - 'LANGUAGE': 'en_US:en', 18:47:27 INFO - 'LOGNAME': 'cltbld', 18:47:27 INFO - 'MAIL': '/var/mail/cltbld', 18:47:27 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:47:27 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:47:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:47:27 INFO - 'MOZ_NO_REMOTE': '1', 18:47:27 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:47:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:47:27 INFO - 'NO_EM_RESTART': '1', 18:47:27 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:47:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:47:27 INFO - 'PWD': '/builds/slave/test', 18:47:27 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:47:27 INFO - 'RUST_BACKTRACE': 'full', 18:47:27 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 18:47:27 INFO - 'SHELL': '/bin/bash', 18:47:27 INFO - 'SHLVL': '1', 18:47:27 INFO - 'TERM': 'linux', 18:47:27 INFO - 'TMOUT': '86400', 18:47:27 INFO - 'USER': 'cltbld', 18:47:27 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1505958174.735341-1296699532', 18:47:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:47:27 INFO - '_': '/tools/buildbot/bin/python'} 18:47:27 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/BqbOEH4RRjOmGYuwp-1rsQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-026', '--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 18:47:27 INFO - mozversion application_buildid: 20170921003054 18:47:27 INFO - mozversion application_changeset: f8dd3f21e434be32fe5901849f1723b0e64cf668 18:47:27 INFO - mozversion application_display_name: Nightly 18:47:27 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:47:27 INFO - mozversion application_name: Firefox 18:47:27 INFO - mozversion application_remotingname: firefox 18:47:27 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 18:47:27 INFO - mozversion application_vendor: Mozilla 18:47:27 INFO - mozversion application_version: 57.0a1 18:47:27 INFO - mozversion platform_buildid: 20170921003054 18:47:27 INFO - mozversion platform_changeset: f8dd3f21e434be32fe5901849f1723b0e64cf668 18:47:27 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 18:47:27 INFO - mozversion platform_version: 57.0a1 18:47:27 INFO - using testdate: 1505958447 18:47:27 INFO - actual date: 1505958447 18:47:27 INFO - starting webserver on 'localhost:40619' 18:47:27 INFO - SUITE-START | Running 2 tests 18:47:27 INFO - TEST-START | dromaeo_css 18:47:27 INFO - Initialising browser for dromaeo_css test... 18:47:27 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:40619/getInfo.html -profile /tmp/tmpU2bMKp/profile 18:47:27 INFO - TEST-INFO | started process 3576 (/builds/slave/test/build/application/firefox/firefox http://localhost:40619/getInfo.html) 18:47:36 INFO - TEST-INFO | 3576: exit 0 18:47:36 INFO - Browser initialized. 18:47:36 INFO - Running cycle 1/1 for dromaeo_css test... 18:47:36 INFO - Using env: {'DISPLAY': ':0', 18:47:36 INFO - 'HOME': '/home/cltbld', 18:47:36 INFO - 'JSGC_DISABLE_POISONING': '1', 18:47:36 INFO - 'LANG': 'en_US.UTF-8', 18:47:36 INFO - 'LANGUAGE': 'en_US:en', 18:47:36 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 18:47:36 INFO - 'LOGNAME': 'cltbld', 18:47:36 INFO - 'MAIL': '/var/mail/cltbld', 18:47:36 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:47:36 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:47:36 INFO - 'MOZ_CRASHREPORTER': '1', 18:47:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:47:36 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 18:47:36 INFO - 'MOZ_NO_REMOTE': '1', 18:47:36 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:47:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:47:36 INFO - 'NO_EM_RESTART': '1', 18:47:36 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:47:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:47:36 INFO - 'PWD': '/builds/slave/test', 18:47:36 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:47:36 INFO - 'RUST_BACKTRACE': 'full', 18:47:36 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 18:47:36 INFO - 'SHELL': '/bin/bash', 18:47:36 INFO - 'SHLVL': '1', 18:47:36 INFO - 'TERM': 'linux', 18:47:36 INFO - 'TMOUT': '86400', 18:47:36 INFO - 'USER': 'cltbld', 18:47:36 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1505958174.735341-1296699532', 18:47:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:47:36 INFO - '_': '/tools/buildbot/bin/python'} 18:47:36 INFO - TEST-INFO | started process 3815 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpU2bMKp/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 18:47:37 INFO - PID 3815 | 18:47:37 INFO - PID 3815 | (/builds/slave/test/build/application/firefox/firefox:3865): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 18:47:37 INFO - PID 3815 | 18:47:47 INFO - PID 3815 | RSS: Main: 144199680 18:47:47 INFO - PID 3815 | 18:50:45 INFO - PID 3815 | Cycle 1(1): loaded http://localhost:40619/tests/dromaeo/cssquery-dojo.html (next: http://localhost:40619/tests/dromaeo/cssquery-ext.html) 18:50:46 INFO - PID 3815 | RSS: Main: 148361216 18:50:46 INFO - PID 3815 | 18:53:47 INFO - PID 3815 | Cycle 1(1): loaded http://localhost:40619/tests/dromaeo/cssquery-ext.html (next: http://localhost:40619/tests/dromaeo/cssquery-jquery.html) 18:53:48 INFO - PID 3815 | RSS: Main: 146833408 18:53:48 INFO - PID 3815 | 18:56:50 INFO - PID 3815 | Cycle 1(1): loaded http://localhost:40619/tests/dromaeo/cssquery-jquery.html (next: http://localhost:40619/tests/dromaeo/cssquery-mootools.html) 18:56:50 INFO - PID 3815 | RSS: Main: 150704128 18:56:50 INFO - PID 3815 | 18:59:52 INFO - PID 3815 | Cycle 1(1): loaded http://localhost:40619/tests/dromaeo/cssquery-mootools.html (next: http://localhost:40619/tests/dromaeo/cssquery-prototype.html) 18:59:52 INFO - PID 3815 | RSS: Main: 149061632 18:59:52 INFO - PID 3815 | 19:02:55 INFO - PID 3815 | Cycle 1(1): loaded http://localhost:40619/tests/dromaeo/cssquery-prototype.html (next: http://localhost:40619/tests/dromaeo/cssquery-yui.html) 19:02:56 INFO - PID 3815 | RSS: Main: 150118400 19:02:56 INFO - PID 3815 | 19:05:57 INFO - PID 3815 | Cycle 1(1): loaded http://localhost:40619/tests/dromaeo/cssquery-yui.html (next: http://localhost:40619/tests/dromaeo/cssquery-dojo.html) 19:05:58 INFO - PID 3815 | RSS: Main: 152195072 19:05:58 INFO - PID 3815 | 19:05:58 INFO - PID 3815 | __start_tp_report 19:05:58 INFO - PID 3815 | _x_x_mozilla_page_load 19:05:58 INFO - PID 3815 | _x_x_mozilla_page_load_details 19:05:58 INFO - PID 3815 | |i|pagename|runs| 19:05:58 INFO - PID 3815 | |0;dojo.html;3866.2074274773668;3879.224155168966;3902.746321489105;3909.8045097745126;3920.666743326817;6276.780312689057;6309.810705678837;6351.047342898568;6362.300146983828;6447.065175549543;6414.871702565946;6420.71106800194;6421.64680942548;6431.7427302907945;6440.194975628035;5695.401982791809;5700.7719691212405;5725.627834190765;5736.196932429463;5744.770209191627;5796.188533605298;5807.419258074201;5819.330776960662;5835.533157347402;5845.035569131088;5754.798582049629;5768.682722450264;5818.970905145489;5858.297004359461;5859.4140585941495;768.8753923314085;5677.17680936262;5695.601307908448;5706.857328566819;5817.331006934193;5675.659460432346;5742.913856292117;5757.424257574209;5772.7113644317615;5781.508571771405;6262.248530176363;6293.339199384044;6333.714982825784;6358.268799088118;6373.585716928431;6859.142607174103;6878.759243426506;6895.586264824126;6896.482763792736;6985.091938048072;6922.58464492127;6945.791626251221;7005.824854378681;7022.754203602901;7036.542624729376;6330.208723909512;6408.391202835724;6422.389872962061;6426.550141490097;6431.163948686688;7026.191987921455;7067.257937916547;7086.078809754699;7136.607486588184;7163.140423149255;6827.590344579242;6869.896951545731;6897.068895699117;6913.308669133046;6924.065251191126;6907.516473846783;6953.89569156463;6971.024056632075;7003.299670033058;7034.402075823612;6834.077399550997;6864.828379290558;6913.343232392915;6925.515213934977;6934.653267336613;6867.141607299088;6873.072135261776;6881.931180688229;6931.306869313129;6973.93026069743;4917.508249175054;4945.307656928033;5002.774875130628;5015.949840501621;5018.021485810232;4432.955670443254;4467.620252278528;4482.148391805546;4500.639948804153;4506.324051392317;6306.653134077578;6338.207724034496;6340.714667839959;6366.013267943476;6393.264774550893;6847.075644787989;6858.931410685829;6936.063631409796;6940.09778728762;6945.305469453015;6272.40412160844;6283.717232724538;6380.234371875312;6383.808485745435;6403.903941440882;5517.641353312022;5548.583856210719;5577;5595.580331475156;5607.271054762937;7101.414719440487;7111.253318401494;7210.96394518024;7211.278872112747;7231.204567497571;6785;6829.385355318045;6879.759208427681;6881.621510816753;6907.930920690729;7036.788896333118;7042.718291268292;7059.5764254144915;7065.0815393998455;7070.39901608369;6731.932680673131;6736.090627765189;6737.79786606383;6739.292374300824;6888.483363747639;6843.418309443753;6911.827204319731;6950.79147625572;6996.160460744743;6997.650117494207;6669.699863506057;6675.966620166868;6750.594964302158;6754.459643228628;6764.763233286812;6467.644279564669;6596.604203747598;6648.667566621552;6668.06647069401;6759.831004224934;6577.934220657732;6607.141071660845;6608.636524991171;6610.8016759497295;6612.173478315211;6642.368974947372;6648.634325112165;6674.065630811593;6731.528792984444;6738.326167383419;6351.872962540816;6430.3891130342545;6437.645929473924;6529.281779004401;6530 19:05:58 INFO - PID 3815 | |1;ext.html;960.9231261499081;972.907573780491;999.7950420163867;1019.7093828258943;1033.0083120204613;41305;42929;42943.35584966228;43043;43086.13827723444;39315;39316.410253846145;39346.40980385289;39522.209555808935;39554.60445395545;7602.467827252094;7621.123570789375;7776.416768742339;7878.251566101211;7887.014123234596;7690.884636730426;7766.06807183136;7768.417368697342;7773.416993725465;7776.766696999099;8368.121347258542;8570.742877713647;8574.013988391322;8576.05663377028;8579.785505362353;8452;8508.787280318042;8516.957415212915;8528.658853645878;8532.914670853273;8341.290990265774;8423.452475589129;8486.957565212197;8521.829563408759;8525.658973641046;39920.401193982114;39934.201315973805;40011.59988400108;40174.19651606952;40371.7981410094;3773;3815.2178803345246;3848.230353929225;3852.383618621035;3860.768353898775;32233.355332893447;32300.83849580737;32325.35349293001;32361;32410.351792964244;5495.95576840403;5552.444755524456;5583.916241256385;5585.162225666142;5700.971495142539;201341;204174;204305;205419;205644;51542.484575153765;51655.7417212919;51754.22368664473;51851.48148518467;51984.740076300135;54255;54259.72870135703;54559;54628.45371546234;54672;16145.515634530982;16462.670746585;16505.752413713803;16508.75236871448;16516.256768445448;13252.27112508802;13254.734905302033;13303.3348332583;13306.268155251553;13307.201567905957;15098.773518397233;15300.693986120215;15303.38786448552;15391.461298854487;15441.691166176613;5839.153322768175;5883.17635531026;6010.158577799111;6026.578139530191;6198.349173336825;1063.2031866146187;1073.7047311989265;1079.2768844873954;1080.864891888514;1094.075506197262;24711.206918060125;24765.380865478506;24782.256532304058;24857.87571062133;24875.62686559703;1172.1853311948114;1184.9348285844362;1200.3098218524383;1201.1831954271179;1218.4456072487174;13082.934585327013;13174.275414852274;13452.394642241123;13461.932690336485;13600.251986140856;280090;280476;280752;282313;282444;56136;56356.71821640948;60175.39824601785;60222;60328.698356507935;1500.18240059168;1501.1218437214352;1508.2835653064706;1519.187234829383;1523.489630973614;29071.27321816887;29470.410591788474;29762.553561696157;29807;30412.391752165277;35319.64680353266;38816.80591597024;39062;39422;39475;226549;227014;227462;227805;228174;34147.65852341544;34223;34289.657103428646;34405.655943440244;34435.82782086074;42667.78666106649;43669;44131;44133.55866441423;44336;33355;33528.664713352555;33730.8313458441;33731.662683373834;34035.48946765751;33371.666283336854;33837.32325353529;33842.830785845916;33883.4917476243;33893.152671183416;38029.2394152121;38456.23087538178;38605;38873.80563097166;38886;32435.35129297448;32439.189020274684;32566.511502327958;32643;32708.836455817567;24152.75847241505;24469.265922021656;24713.87643061845;25350.49299014046;25592.744072559035 19:05:58 INFO - PID 3815 | |2;jquery.html;595.8421018430117;613.3529126771257;613.4724137241968;614.5882258886545;623.3859648246478;23279.883600581994;23929.324947253677;23975.400614984635;24004.39989000276;24016.75983240167;21846.781532184672;22704.772952270472;22756.544869102607;22773.544529109407;22794.88602556985;6848.82877928052;6850.897236541456;6903.689333979958;6942.7917162484955;6987.930120698778;6530.314316996718;6803.421709154728;6813.625250611214;6843.62360070196;6856.142982127234;6809.489288303373;6810.829729256783;6824.419924306415;6838.589684618939;6838.760643377483;6656.534042617019;6828.897566536506;6834.4190743786585;6835.213950395693;6909;6379.266384365765;6424.903626445607;6440.4525615322755;6441.420272175526;6445.129907462479;29528;29903.551446728317;29934.401311973856;30163.547546786816;30177;2301.8158547316175;2316.9652455213186;2332.2536788227776;2338.1582630253097;2345.2846881701075;19035.524111897175;19469.610607787905;19549;19609.607807843906;19660.606787864304;2600.6749156355454;2610.517054344933;2652.668416447944;2668.3996100877343;2673.585594232897;137609.3119534406;144979;150757;156495;156686;15339.309731062129;15443.61390965235;15677.451289204822;15843.88059104618;16381;24632.13787517464;24849.254522364357;25001.499970000863;25016.37459063538;25056.624150637756;10288.639897603547;10480.947595262127;10519.84220236697;10570.365778053341;10600;9448.95275523618;9544.141027307576;9573.28200384974;9587.137157655845;9589.328746987783;9409;9410.435373877452;9411.764705882408;9473.484242521301;9604.66383676582;2689.300781796747;2715.8506282154276;2716.307341627872;2881.1932658855553;2955.600993865801;1018.4703953943907;1064.6965614799744;1067.3009178987775;1069.2087854987408;1079.6113399176447;20207.393778186677;20843;20944.162233510793;21295.68056479154;21417.92910354476;1093.2729734726317;1103.542030057533;1107.2968664897687;1115.654147214366;1118.4128332625273;11608.129390295593;11662.358570278717;11801.350925698996;11858.703532411586;11892.405379730979;546549;567472;574367;577596;601372;131877;132081;133129.3343533276;133179;133703;1239.6590937492263;1250.2685928731794;1270.955516556916;1281.077624110639;1289.8516670582815;18436.907815460836;18832.62334753325;18860.339888103852;18943.43169704911;19099.04504774728;60965.39034609597;62513.37486625076;62610.68694656497;62746.37253627588;63261.683691581246;77566;77891.6105419492;78323.60838195773;81088.18911811043;82956;15309.387624494895;15401.922990385425;15511.37954481808;15531.223438827787;15584.610384740019;23136.6529502061;23583.41041473977;23584.646230306214;23724.169654062025;23784.286471405885;19193.32823351176;19247.326343577908;19446.61106777828;19679.21283148658;19743.506412339808;16792.24434900408;16991.405300814906;17016;17350.132493375535;17416.564585884946;19560.60878782388;19628.018599070274;20195;20260.493487662927;20339.79660203438;14976.625584360478;15159.848401515843;15198.696026079195;15260;15320.38718451294;19027.90486047561;19201.903990479957;19208.807911920703;19408.902955485133;19423.708644370065 19:05:58 INFO - PID 3815 | |3;mootools.html;1467.8238611366635;1516.2873449478748;1531.1731664900954;1536.1244090868202;1552.0454920224058;16333.428330008452;17066.91466542667;17129;17138.828611713878;17144.314227430918;15819.367225311002;15956.122413267265;16021;16023.679526409465;16047;2494.625806129077;2511.4098186926058;2542.5677634802128;2544.8854801533885;2545.6563363945907;2762.6684797824187;2782.874770635316;2795.9440811183763;2814.2964258935267;2815.1132393296048;2748.7663548598293;2803.355228297493;2845.2175651695743;2845.430913817245;2848.829070255791;2825.5055365310986;2834.305595129186;2838.2620518665194;2838.787090968175;2862.5992361069466;2320.582295186866;2413.6138217885054;2781.7913656475644;2791.8324900506036;2793.888244470219;16532.256048477848;16698.916505417517;16709.248083836257;16720;16721.247543860554;8238.382121340923;8398.496090234605;8422.115677853799;8436.114208008194;8453.873191902127;5440.036188237846;5525.557955363562;5556.638818476827;5668.773249070074;5681.488666020036;2196.934091977243;2198.8460807743463;2220.7890250426185;2224.63293556563;2265.569541787055;29308.267293317625;29440;29534.409311813644;29999.700002999907;30798.692013079806;6783.728650853911;6842.4868134890085;6900.378965893094;7008.123984501937;7033.577985320897;18756.343527976456;19149.712754308697;19166.71249931252;19428.417147485598;19456.221751129797;8357.080721120672;8596.828063438696;8600.139986001475;8601.440906341068;8609.053004169537;7572.7728168155045;7758.030246219223;7797;7803.102643196105;7890.447668663139;5478.58910581708;5685.687287199244;5708.628939118944;5719.142128680648;5735.913961290584;1598.296749430247;1611.9113448760431;1628.3242454381536;1630.6168050508122;1640.6636639488877;985.5121714751244;998.1665309466531;998.4009594243396;1004.2116938203475;1022.3048327137469;8482.491050536864;9295.581698823435;9315.115064068903;9487.098725621056;9645.276604254708;776.856281587908;793.8134538383475;802.3821657323828;802.574635443216;819.5451524403894;5272.525472707474;5374.328208973879;5384.380796208407;5412.891742165213;5866.384029676822;23567.764322356557;24620.6306905393;25289.367765805633;25344.619830702555;25350.971922246175;23294.53410931772;24521.87739061293;24528.754712453003;24583.754162458503;24677.38306542351;12637.936810316074;12691.555795547112;12727.10910236293;12808.80786788199;12840.486380544673;9236.861447078434;9387;9402.106799854002;9416.387934784218;9491.145796878314;20489.897550512153;20590;21185.152593896073;21485.248016319354;21917.671234931808;21313.360599182048;21528;21640;21691.349259522238;21870.23454179096;11808.291502509877;11854.94072529632;11858.288502689866;11998.100142489522;12021.639350819489;15220.771688424462;15317.157556334509;15396.538103856903;15416.845831541541;15449.304781284643;11985.34080625574;12375.566855160027;12618.36908154607;12777.552785652457;12930.418131183931;11808.468618911998;12016.399180040788;12282.201656892274;12284.692882678004;12300.692482688004;11057.11543076568;11252.099832013255;11267.605633802777;11290.887091128983;11303;8213.753587392388;8257.504549726795;8370.665173392996;8404.243618074403;8441;8270.503769773833;8275.917240827515;8308.459950103224;8317.625706843328;8346.624401901807 19:05:58 INFO - PID 3815 | |4;prototype.html;549.1597855281418;563.5716855190054;577.0680351232759;584.1086621224938;586.3784388548137;6331.873362532747;6371.235451745792;6383.265924418687;6411.455026322768;6439.645819479927;6280.801719398209;6303.338149494306;6350.110984462179;6359.71381287842;6421.711023003964;5104.157813960701;5156.74216289184;5172.456892026339;5186.974065129669;5190.377154741417;4889.486603906592;5018.623603229754;5033.572146367563;5044.369453818273;5073.594112471012;4998.200287953928;5008.574271186953;5051.621128415365;5060.11447996601;5061.54446099851;4886;4993.850184494471;4996.700197988114;5026.673266237683;5071.644984851062;4522.29904364824;4538.909221815578;4544.7045942013665;4589.816407343702;4596.770161491945;6038.245219347582;6107.206063211798;6155.214543056033;6244.687765611701;6267.686615669244;5922.200502932092;5955.7541140078765;5981.970090149522;5989.670568118751;6000.249968753906;6715.294894036105;6770.49221308399;6800.557963732391;6816.693248803816;6843.178818541807;5105.974470127663;5109.080365534202;5116.744162791882;5151.742412879341;5164.1995490699;6722.9663851680425;6901.827454313632;6939.167299923991;7014.123234595676;7044.647767611599;5209.244659524424;5229.320188375486;5240.528352448298;5296.682199068068;5296.947030529645;5289.947100528945;5293.497117773805;5299.496547828027;5319.627626066215;5353.652012619167;5834.978878696211;5886.1465087562065;5960.5827592068135;5965.582409231399;5979.774146300085;6710.530262881649;6745.291744366771;6758.668259450482;6820.965895170492;6824.283450237753;1838.6598479281108;1840.2363019346822;1844.3913508542148;1857.5913299074182;1868.5235265007336;1234.3519652182497;1290.3096843188848;1308.8809068246703;1311.658968668134;1317.4137508808487;6028.216331876828;6077.270727512727;6189.783357582553;6191.9690401547705;6194.721237544321;6741.696623651849;6745.426638735762;6798.94616334469;6833.384995350442;6834.2140653825445;6222.968885155636;6273.153124328157;6275.337035685547;6281.560290779602;6310.495160387158;3574.7140228781636;3600.801955892451;3616.638336166415;3629.8729544466346;3643.9453408198897;6919.823629982915;6920.446364290845;7032.964835175791;7048.859022819515;7114.573125612376;6912.343327383891;6920.4809639277255;6969.721211151497;6984.126984126984;7026.683799229047;7092.858142837114;7126.966589844444;7127.394171495377;7135.6432178391915;7180.317869802465;6850.794476165723;6882.8623427532175;6896.517243792886;6903.309669033057;6932.168139823052;6781.23687841155;6812.8978065328065;6825.52221344521;6871.828204294932;6933.965330173317;6931;6983.75556855528;7018.368346848808;7072.190910181126;7101.928980710333;5170.121079416584;5225.477452254744;5238.790448382022;5259.973700131475;5270.156774915995;6724.596524208563;6822.94035049145;6835.248122706399;6844.178698556205;6864.725410983505;6320.241571011508;6448.451881590117;6522.380373864476;6544.149260596092;6616.801495955129;6519.576227545194;6543.247526534509;6570.211574611078;6647.4682025438815;6660.600363978177;6261.968690156702;6461.160049193575;6555.6066636002;6583.144191255106;6586.506012049212;6531.248906375828;6665.600063996176;6713.362230588086;6732.562383445061;6747.527673062838;6400.319816105851;6465.1595292611655;6482.222133344029;6511.47908167336;6532.510061745293 19:05:58 INFO - PID 3815 | |5;yui.html;956.4118067388549;987.6049580167933;997.0328781082352;1003.7841863999239;1017.8218811707949;20284.492887677796;20564.280250191245;20574.38276851693;20578.279760208414;20890.477738056557;18614.813851861476;18810.24759009638;18876;19284.51788705283;19569.902150489263;1036.2746077745571;1062.8777690565569;1065.1532032034552;1065.2277099103167;1065.8081545321838;1083.409541799717;1089.1722291058813;1094.8795632480421;1095.5015467962098;1098.1708809848553;1097.401915955806;1128.5203788389942;1128.9830652540218;1139.4188963628567;1147.6499667601379;1117.8826223246524;1124.449019980212;1125.6341688951043;1125.6679279612533;1136.2160109524402;788.3102285500187;824.5547404401616;826.4256341842422;826.7437094500724;831.7380025292074;18469.076546172637;18847.151878165514;18950.147243374082;18989.430317090508;19065.80934190654;21054;21327.93166759807;22365;22402.775972240233;22767.88616056926;18417.447476575726;18485.907570462197;18599.53501162468;18663.62672746551;18669.906650466797;4032.2741906456827;4043.0498832774274;4067.633912947849;4068.3694027425795;4079.530853951774;153996;154745.22627386905;156156.2192189032;157490;159018;17733.202005909767;18002.909985449987;18041.368552100612;18092.547686307684;18136.546586335182;31761;32547.51178732321;32563.83718081442;32571.34857302841;32598;9617.51912404377;9689.27330450219;9735.123838854537;9797.265205109645;9810.558524866256;4490.169318676056;4553.385292985471;4555.088982203506;4578.03861189154;4610.97694511532;5914.290285165807;5920.082387229986;5993.670348130895;5995.2206213192;6045.969770151121;1100.9009189173014;1101.6639924822846;1117.1677100559973;1117.2737720481753;1117.4803716271972;729.6607077708891;730.4631096144329;746.8879668049836;748.0835975929443;764.228129589119;12303.384830758605;12316.81524777129;12418.565350212699;12638.368081596067;13078.907374819983;729.2306616519517;737.71229220604;738.1584993107862;743.8028922335477;762.2644148396823;5012.598992080625;5084.186530155232;5095.8980820383385;5113.53978141969;5128.589712822965;152185;156487;156974;158138;159845.2007739954;120600;124785;127161.36419317844;129579.99999999811;131872;110078;113775.4311228455;116044.8395516051;117588;122949.38525307317;13030.153040052366;13164.341782910624;13193.20840749558;13228.338583071001;13331.600051998455;54590.72704636451;55039.724801375734;55526.72236638791;55856.441435585126;56071;70576.29423705697;70759;70939.64530177315;71361.6431917837;73304.63347683227;19075.141618626916;19483.415497535098;19536;19705.211791528178;19736.506587334858;20889.686654700494;20910.268140615004;20986.580268394853;21038.89480552649;21156.047977841343;18231.270749169886;18407.723884142015;18436.723449148543;18538.907305463927;18563.628727425647;18403.81596184021;18542.536436589195;18580.25678972826;18583.907080464513;18854.52863678419;18937.715934261272;19011.904940475208;19019.809801901803;19063.332783352515;19309.51726206856;13030.674233144247;13064.21614703121;13187.142835715647;13389.732205356033;13466.259355735529;13146.474141034634;13180.472781088649;13324.933375333449;13328.200307981551;13543.86456135426 19:05:58 INFO - PID 3815 | __end_tp_report 19:05:58 INFO - PID 3815 | __start_cc_report 19:05:58 INFO - PID 3815 | _x_x_mozilla_cycle_collect,273 19:05:58 INFO - PID 3815 | __end_cc_report 19:05:58 INFO - PID 3815 | __startTimestamp1505959558248__endTimestamp 19:05:58 INFO - PID 3815 | 19:05:58 INFO - PID 3815 | ------- Summary: start ------- 19:05:58 INFO - PID 3815 | Number of tests: 6 19:05:58 INFO - PID 3815 | 19:05:58 INFO - PID 3815 | [#0] dojo.html Cycles:165 Average:6314.93 Median:6607.14 stddev:875.45 (13.3%) stddev-sans-first:856.80 19:05:58 INFO - PID 3815 | Values: 3866.2 3879.2 3902.7 3909.8 3920.7 6276.8 6309.8 6351.0 6362.3 6447.1 6414.9 6420.7 6421.6 6431.7 6440.2 5695.4 5700.8 5725.6 5736.2 5744.8 5796.2 5807.4 5819.3 5835.5 5845.0 5754.8 5768.7 5819.0 5858.3 5859.4 768.9 5677.2 5695.6 5706.9 5817.3 5675.7 5742.9 5757.4 5772.7 5781.5 6262.2 6293.3 6333.7 6358.3 6373.6 6859.1 6878.8 6895.6 6896.5 6985.1 6922.6 6945.8 7005.8 7022.8 7036.5 6330.2 6408.4 6422.4 6426.6 6431.2 7026.2 7067.3 7086.1 7136.6 7163.1 6827.6 6869.9 6897.1 6913.3 6924.1 6907.5 6953.9 6971.0 7003.3 7034.4 6834.1 6864.8 6913.3 6925.5 6934.7 6867.1 6873.1 6881.9 6931.3 6973.9 4917.5 4945.3 5002.8 5015.9 5018.0 4433.0 4467.6 4482.1 4500.6 4506.3 6306.7 6338.2 6340.7 6366.0 6393.3 6847.1 6858.9 6936.1 6940.1 6945.3 6272.4 6283.7 6380.2 6383.8 6403.9 5517.6 5548.6 5577.0 5595.6 5607.3 7101.4 7111.3 7211.0 7211.3 7231.2 6785.0 6829.4 6879.8 6881.6 6907.9 7036.8 7042.7 7059.6 7065.1 7070.4 6731.9 6736.1 6737.8 6739.3 6888.5 6843.4 6911.8 6950.8 6996.2 6997.7 6669.7 6676.0 6750.6 6754.5 6764.8 6467.6 6596.6 6648.7 6668.1 6759.8 6577.9 6607.1 6608.6 6610.8 6612.2 6642.4 6648.6 6674.1 6731.5 6738.3 6351.9 6430.4 6437.6 6529.3 6530.0 19:05:58 INFO - PID 3815 | 19:05:58 INFO - PID 3815 | [#1] ext.html Cycles:180 Average:41282.12 Median:34420.74 stddev:62320.57 (181.1%) stddev-sans-first:62421.85 19:05:58 INFO - PID 3815 | Values: 960.9 972.9 999.8 1019.7 1033.0 41305.0 42929.0 42943.4 43043.0 43086.1 39315.0 39316.4 39346.4 39522.2 39554.6 7602.5 7621.1 7776.4 7878.3 7887.0 7690.9 7766.1 7768.4 7773.4 7776.8 8368.1 8570.7 8574.0 8576.1 8579.8 8452.0 8508.8 8517.0 8528.7 8532.9 8341.3 8423.5 8487.0 8521.8 8525.7 39920.4 39934.2 40011.6 40174.2 40371.8 3773.0 3815.2 3848.2 3852.4 3860.8 32233.4 32300.8 32325.4 32361.0 32410.4 5496.0 5552.4 5583.9 5585.2 5701.0 201341.0 204174.0 204305.0 205419.0 205644.0 51542.5 51655.7 51754.2 51851.5 51984.7 54255.0 54259.7 54559.0 54628.5 54672.0 16145.5 16462.7 16505.8 16508.8 16516.3 13252.3 13254.7 13303.3 13306.3 13307.2 15098.8 15300.7 15303.4 15391.5 15441.7 5839.2 5883.2 6010.2 6026.6 6198.3 1063.2 1073.7 1079.3 1080.9 1094.1 24711.2 24765.4 24782.3 24857.9 24875.6 1172.2 1184.9 1200.3 1201.2 1218.4 13082.9 13174.3 13452.4 13461.9 13600.3 280090.0 280476.0 280752.0 282313.0 282444.0 56136.0 56356.7 60175.4 60222.0 60328.7 1500.2 1501.1 1508.3 1519.2 1523.5 29071.3 29470.4 29762.6 29807.0 30412.4 35319.6 38816.8 39062.0 39422.0 39475.0 226549.0 227014.0 227462.0 227805.0 228174.0 34147.7 34223.0 34289.7 34405.7 34435.8 42667.8 43669.0 44131.0 44133.6 44336.0 33355.0 33528.7 33730.8 33731.7 34035.5 33371.7 33837.3 33842.8 33883.5 33893.2 38029.2 38456.2 38605.0 38873.8 38886.0 32435.4 32439.2 32566.5 32643.0 32708.8 24152.8 24469.3 24713.9 25350.5 25592.7 19:05:58 INFO - PID 3815 | 19:05:58 INFO - PID 3815 | [#2] jquery.html Cycles:180 Average:38629.28 Median:12719.23 stddev:96421.55 (758.1%) stddev-sans-first:96649.75 19:05:58 INFO - PID 3815 | Values: 595.8 613.4 613.5 614.6 623.4 23279.9 23929.3 23975.4 24004.4 24016.8 21846.8 22704.8 22756.5 22773.5 22794.9 6848.8 6850.9 6903.7 6942.8 6987.9 6530.3 6803.4 6813.6 6843.6 6856.1 6809.5 6810.8 6824.4 6838.6 6838.8 6656.5 6828.9 6834.4 6835.2 6909.0 6379.3 6424.9 6440.5 6441.4 6445.1 29528.0 29903.6 29934.4 30163.5 30177.0 2301.8 2317.0 2332.3 2338.2 2345.3 19035.5 19469.6 19549.0 19609.6 19660.6 2600.7 2610.5 2652.7 2668.4 2673.6 137609.3 144979.0 150757.0 156495.0 156686.0 15339.3 15443.6 15677.5 15843.9 16381.0 24632.1 24849.3 25001.5 25016.4 25056.6 10288.6 10480.9 10519.8 10570.4 10600.0 9449.0 9544.1 9573.3 9587.1 9589.3 9409.0 9410.4 9411.8 9473.5 9604.7 2689.3 2715.9 2716.3 2881.2 2955.6 1018.5 1064.7 1067.3 1069.2 1079.6 20207.4 20843.0 20944.2 21295.7 21417.9 1093.3 1103.5 1107.3 1115.7 1118.4 11608.1 11662.4 11801.4 11858.7 11892.4 546549.0 567472.0 574367.0 577596.0 601372.0 131877.0 132081.0 133129.3 133179.0 133703.0 1239.7 1250.3 1271.0 1281.1 1289.9 18436.9 18832.6 18860.3 18943.4 19099.0 60965.4 62513.4 62610.7 62746.4 63261.7 77566.0 77891.6 78323.6 81088.2 82956.0 15309.4 15401.9 15511.4 15531.2 15584.6 23136.7 23583.4 23584.6 23724.2 23784.3 19193.3 19247.3 19446.6 19679.2 19743.5 16792.2 16991.4 17016.0 17350.1 17416.6 19560.6 19628.0 20195.0 20260.5 20339.8 14976.6 15159.8 15198.7 15260.0 15320.4 19027.9 19201.9 19208.8 19408.9 19423.7 19:05:58 INFO - PID 3815 | 19:05:58 INFO - PID 3815 | [#3] mootools.html Cycles:180 Average:10299.97 Median:13946.77 stddev:7583.88 (54.4%) stddev-sans-first:7576.12 19:05:58 INFO - PID 3815 | Values: 1467.8 1516.3 1531.2 1536.1 1552.0 16333.4 17066.9 17129.0 17138.8 17144.3 15819.4 15956.1 16021.0 16023.7 16047.0 2494.6 2511.4 2542.6 2544.9 2545.7 2762.7 2782.9 2795.9 2814.3 2815.1 2748.8 2803.4 2845.2 2845.4 2848.8 2825.5 2834.3 2838.3 2838.8 2862.6 2320.6 2413.6 2781.8 2791.8 2793.9 16532.3 16698.9 16709.2 16720.0 16721.2 8238.4 8398.5 8422.1 8436.1 8453.9 5440.0 5525.6 5556.6 5668.8 5681.5 2196.9 2198.8 2220.8 2224.6 2265.6 29308.3 29440.0 29534.4 29999.7 30798.7 6783.7 6842.5 6900.4 7008.1 7033.6 18756.3 19149.7 19166.7 19428.4 19456.2 8357.1 8596.8 8600.1 8601.4 8609.1 7572.8 7758.0 7797.0 7803.1 7890.4 5478.6 5685.7 5708.6 5719.1 5735.9 1598.3 1611.9 1628.3 1630.6 1640.7 985.5 998.2 998.4 1004.2 1022.3 8482.5 9295.6 9315.1 9487.1 9645.3 776.9 793.8 802.4 802.6 819.5 5272.5 5374.3 5384.4 5412.9 5866.4 23567.8 24620.6 25289.4 25344.6 25351.0 23294.5 24521.9 24528.8 24583.8 24677.4 12637.9 12691.6 12727.1 12808.8 12840.5 9236.9 9387.0 9402.1 9416.4 9491.1 20489.9 20590.0 21185.2 21485.2 21917.7 21313.4 21528.0 21640.0 21691.3 21870.2 11808.3 11854.9 11858.3 11998.1 12021.6 15220.8 15317.2 15396.5 15416.8 15449.3 11985.3 12375.6 12618.4 12777.6 12930.4 11808.5 12016.4 12282.2 12284.7 12300.7 11057.1 11252.1 11267.6 11290.9 11303.0 8213.8 8257.5 8370.7 8404.2 8441.0 8270.5 8275.9 8308.5 8317.6 8346.6 19:05:58 INFO - PID 3815 | 19:05:58 INFO - PID 3815 | [#4] prototype.html Cycles:180 Average:5691.64 Median:6292.45 stddev:1588.70 (25.2%) stddev-sans-first:1545.56 19:05:58 INFO - PID 3815 | Values: 549.2 563.6 577.1 584.1 586.4 6331.9 6371.2 6383.3 6411.5 6439.6 6280.8 6303.3 6350.1 6359.7 6421.7 5104.2 5156.7 5172.5 5187.0 5190.4 4889.5 5018.6 5033.6 5044.4 5073.6 4998.2 5008.6 5051.6 5060.1 5061.5 4886.0 4993.9 4996.7 5026.7 5071.6 4522.3 4538.9 4544.7 4589.8 4596.8 6038.2 6107.2 6155.2 6244.7 6267.7 5922.2 5955.8 5982.0 5989.7 6000.2 6715.3 6770.5 6800.6 6816.7 6843.2 5106.0 5109.1 5116.7 5151.7 5164.2 6723.0 6901.8 6939.2 7014.1 7044.6 5209.2 5229.3 5240.5 5296.7 5296.9 5289.9 5293.5 5299.5 5319.6 5353.7 5835.0 5886.1 5960.6 5965.6 5979.8 6710.5 6745.3 6758.7 6821.0 6824.3 1838.7 1840.2 1844.4 1857.6 1868.5 1234.4 1290.3 1308.9 1311.7 1317.4 6028.2 6077.3 6189.8 6192.0 6194.7 6741.7 6745.4 6798.9 6833.4 6834.2 6223.0 6273.2 6275.3 6281.6 6310.5 3574.7 3600.8 3616.6 3629.9 3643.9 6919.8 6920.4 7033.0 7048.9 7114.6 6912.3 6920.5 6969.7 6984.1 7026.7 7092.9 7127.0 7127.4 7135.6 7180.3 6850.8 6882.9 6896.5 6903.3 6932.2 6781.2 6812.9 6825.5 6871.8 6934.0 6931.0 6983.8 7018.4 7072.2 7101.9 5170.1 5225.5 5238.8 5260.0 5270.2 6724.6 6822.9 6835.2 6844.2 6864.7 6320.2 6448.5 6522.4 6544.1 6616.8 6519.6 6543.2 6570.2 6647.5 6660.6 6262.0 6461.2 6555.6 6583.1 6586.5 6531.2 6665.6 6713.4 6732.6 6747.5 6400.3 6465.2 6482.2 6511.5 6532.5 19:05:58 INFO - PID 3815 | 19:05:58 INFO - PID 3815 | [#5] yui.html Cycles:180 Average:28311.89 Median:18865.26 stddev:42280.63 (224.1%) stddev-sans-first:42349.35 19:05:58 INFO - PID 3815 | Values: 956.4 987.6 997.0 1003.8 1017.8 20284.5 20564.3 20574.4 20578.3 20890.5 18614.8 18810.2 18876.0 19284.5 19569.9 1036.3 1062.9 1065.2 1065.2 1065.8 1083.4 1089.2 1094.9 1095.5 1098.2 1097.4 1128.5 1129.0 1139.4 1147.6 1117.9 1124.4 1125.6 1125.7 1136.2 788.3 824.6 826.4 826.7 831.7 18469.1 18847.2 18950.1 18989.4 19065.8 21054.0 21327.9 22365.0 22402.8 22767.9 18417.4 18485.9 18599.5 18663.6 18669.9 4032.3 4043.0 4067.6 4068.4 4079.5 153996.0 154745.2 156156.2 157490.0 159018.0 17733.2 18002.9 18041.4 18092.5 18136.5 31761.0 32547.5 32563.8 32571.3 32598.0 9617.5 9689.3 9735.1 9797.3 9810.6 4490.2 4553.4 4555.1 4578.0 4611.0 5914.3 5920.1 5993.7 5995.2 6046.0 1100.9 1101.7 1117.2 1117.3 1117.5 729.7 730.5 746.9 748.1 764.2 12303.4 12316.8 12418.6 12638.4 13078.9 729.2 737.7 738.2 743.8 762.3 5012.6 5084.2 5095.9 5113.5 5128.6 152185.0 156487.0 156974.0 158138.0 159845.2 120600.0 124785.0 127161.4 129580.0 131872.0 110078.0 113775.4 116044.8 117588.0 122949.4 13030.2 13164.3 13193.2 13228.3 13331.6 54590.7 55039.7 55526.7 55856.4 56071.0 70576.3 70759.0 70939.6 71361.6 73304.6 19075.1 19483.4 19536.0 19705.2 19736.5 20889.7 20910.3 20986.6 21038.9 21156.0 18231.3 18407.7 18436.7 18538.9 18563.6 18403.8 18542.5 18580.3 18583.9 18854.5 18937.7 19011.9 19019.8 19063.3 19309.5 13030.7 13064.2 13187.1 13389.7 13466.3 13146.5 13180.5 13324.9 13328.2 13543.9 19:05:58 INFO - PID 3815 | -------- Summary: end -------- 19:05:58 INFO - PID 3815 | 19:05:58 INFO - TEST-INFO | 3815: exit 0 19:05:58 INFO - TEST-OK | dromaeo_css | took 1111027ms 19:05:58 INFO - TEST-START | kraken 19:05:58 INFO - Initialising browser for kraken test... 19:05:58 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:40619/getInfo.html -profile /tmp/tmpwpNIjT/profile 19:05:58 INFO - TEST-INFO | started process 4187 (/builds/slave/test/build/application/firefox/firefox http://localhost:40619/getInfo.html) 19:06:04 INFO - TEST-INFO | 4187: exit 0 19:06:04 INFO - Browser initialized. 19:06:04 INFO - Running cycle 1/1 for kraken test... 19:06:04 INFO - Using env: {'DISPLAY': ':0', 19:06:04 INFO - 'HOME': '/home/cltbld', 19:06:04 INFO - 'JSGC_DISABLE_POISONING': '1', 19:06:04 INFO - 'LANG': 'en_US.UTF-8', 19:06:04 INFO - 'LANGUAGE': 'en_US:en', 19:06:04 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 19:06:04 INFO - 'LOGNAME': 'cltbld', 19:06:04 INFO - 'MAIL': '/var/mail/cltbld', 19:06:04 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:06:04 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 19:06:04 INFO - 'MOZ_CRASHREPORTER': '1', 19:06:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:06:04 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 19:06:04 INFO - 'MOZ_NO_REMOTE': '1', 19:06:04 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:06:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:06:04 INFO - 'NO_EM_RESTART': '1', 19:06:04 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:06:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:06:04 INFO - 'PWD': '/builds/slave/test', 19:06:04 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:06:04 INFO - 'RUST_BACKTRACE': 'full', 19:06:04 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 19:06:04 INFO - 'SHELL': '/bin/bash', 19:06:04 INFO - 'SHLVL': '1', 19:06:04 INFO - 'TERM': 'linux', 19:06:04 INFO - 'TMOUT': '86400', 19:06:04 INFO - 'USER': 'cltbld', 19:06:04 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1505958174.735341-1296699532', 19:06:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:06:04 INFO - '_': '/tools/buildbot/bin/python'} 19:06:04 INFO - TEST-INFO | started process 4424 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpwpNIjT/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 19:06:04 INFO - PID 4424 | 19:06:04 INFO - PID 4424 | (/builds/slave/test/build/application/firefox/firefox:4474): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 19:06:04 INFO - PID 4424 | 19:06:15 INFO - PID 4424 | RSS: Main: 147120128 19:06:15 INFO - PID 4424 | 19:06:17 INFO - PID 4424 | Cycle 1(1): loaded http://localhost:40619/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:40619/tests/kraken/driver.html?testName=audio-beat-detection) 19:06:18 INFO - PID 4424 | RSS: Main: 153100288 19:06:18 INFO - PID 4424 | 19:06:21 INFO - PID 4424 | Cycle 1(1): loaded http://localhost:40619/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:40619/tests/kraken/driver.html?testName=audio-dft) 19:06:21 INFO - PID 4424 | RSS: Main: 153337856 19:06:21 INFO - PID 4424 | 19:06:24 INFO - PID 4424 | Cycle 1(1): loaded http://localhost:40619/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:40619/tests/kraken/driver.html?testName=audio-fft) 19:06:25 INFO - PID 4424 | RSS: Main: 150036480 19:06:25 INFO - PID 4424 | 19:06:27 INFO - PID 4424 | Cycle 1(1): loaded http://localhost:40619/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:40619/tests/kraken/driver.html?testName=audio-oscillator) 19:06:28 INFO - PID 4424 | RSS: Main: 150908928 19:06:28 INFO - PID 4424 | 19:06:30 INFO - PID 4424 | Cycle 1(1): loaded http://localhost:40619/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:40619/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:06:30 INFO - PID 4424 | RSS: Main: 150802432 19:06:30 INFO - PID 4424 | 19:06:33 INFO - PID 4424 | Cycle 1(1): loaded http://localhost:40619/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:40619/tests/kraken/driver.html?testName=imaging-darkroom) 19:06:34 INFO - PID 4424 | RSS: Main: 150712320 19:06:34 INFO - PID 4424 | 19:06:37 INFO - PID 4424 | Cycle 1(1): loaded http://localhost:40619/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:40619/tests/kraken/driver.html?testName=imaging-desaturate) 19:06:38 INFO - PID 4424 | RSS: Main: 151756800 19:06:38 INFO - PID 4424 | 19:06:41 INFO - PID 4424 | Cycle 1(1): loaded http://localhost:40619/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:40619/tests/kraken/driver.html?testName=json-parse-financial) 19:06:42 INFO - PID 4424 | RSS: Main: 153690112 19:06:42 INFO - PID 4424 | 19:06:43 INFO - PID 4424 | Cycle 1(1): loaded http://localhost:40619/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:40619/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:06:44 INFO - PID 4424 | RSS: Main: 153501696 19:06:44 INFO - PID 4424 | 19:06:45 INFO - PID 4424 | Cycle 1(1): loaded http://localhost:40619/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:40619/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:06:46 INFO - PID 4424 | RSS: Main: 153317376 19:06:46 INFO - PID 4424 | 19:06:48 INFO - PID 4424 | Cycle 1(1): loaded http://localhost:40619/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:40619/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:06:49 INFO - PID 4424 | RSS: Main: 150396928 19:06:49 INFO - PID 4424 | 19:06:51 INFO - PID 4424 | Cycle 1(1): loaded http://localhost:40619/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:40619/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:06:52 INFO - PID 4424 | RSS: Main: 150503424 19:06:52 INFO - PID 4424 | 19:06:54 INFO - PID 4424 | Cycle 1(1): loaded http://localhost:40619/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:40619/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:06:55 INFO - PID 4424 | RSS: Main: 150872064 19:06:55 INFO - PID 4424 | 19:06:57 INFO - PID 4424 | Cycle 1(1): loaded http://localhost:40619/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:40619/tests/kraken/driver.html?testName=ai-astar) 19:06:57 INFO - PID 4424 | RSS: Main: 152682496 19:06:57 INFO - PID 4424 | 19:06:57 INFO - PID 4424 | __start_tp_report 19:06:57 INFO - PID 4424 | _x_x_mozilla_page_load 19:06:57 INFO - PID 4424 | _x_x_mozilla_page_load_details 19:06:57 INFO - PID 4424 | |i|pagename|runs| 19:06:57 INFO - PID 4424 | |0;ai-astar;105;104;105;104;104;107;107;105;106;107 19:06:57 INFO - PID 4424 | |1;audio-beat-detection;112;114;114;114;116;116;118;117;119;113 19:06:57 INFO - PID 4424 | |2;audio-dft;162;162;164;164;163;169;160;162;163;164 19:06:57 INFO - PID 4424 | |3;audio-fft;74;74;73;74;74;73;74;72;73;75 19:06:57 INFO - PID 4424 | |4;audio-oscillator;78;77;78;78;78;78;79;77;78;76 19:06:57 INFO - PID 4424 | |5;imaging-gaussian-blur;114;115;113;115;113;113;114;113;118;114 19:06:57 INFO - PID 4424 | |6;imaging-darkroom;149;149;150;150;149;152;149;150;150;151 19:06:57 INFO - PID 4424 | |7;imaging-desaturate;99;95;94;95;94;94;94;95;94;96 19:06:57 INFO - PID 4424 | |8;json-parse-financial;43;44;43;48;43;45;43;43;43;44 19:06:57 INFO - PID 4424 | |9;json-stringify-tinderbox;37;38;38;38;39;38;37;38;38;38 19:06:57 INFO - PID 4424 | |10;stanford-crypto-aes;70;70;69;75;70;71;73;72;71;70 19:06:57 INFO - PID 4424 | |11;stanford-crypto-ccm;106;105;104;114;110;113;104;120;101;113 19:06:57 INFO - PID 4424 | |12;stanford-crypto-pbkdf2;154;148;153;150;152;154;147;159;151;151 19:06:57 INFO - PID 4424 | |13;stanford-crypto-sha256-iterative;62;62;60;60;59;60;61;59;58;59 19:06:57 INFO - PID 4424 | __end_tp_report 19:06:57 INFO - PID 4424 | __start_cc_report 19:06:57 INFO - PID 4424 | _x_x_mozilla_cycle_collect,696 19:06:57 INFO - PID 4424 | __end_cc_report 19:06:57 INFO - PID 4424 | __startTimestamp1505959617325__endTimestamp 19:06:57 INFO - PID 4424 | 19:06:57 INFO - PID 4424 | ------- Summary: start ------- 19:06:57 INFO - PID 4424 | Number of tests: 14 19:06:57 INFO - PID 4424 | 19:06:57 INFO - PID 4424 | [#0] ai-astar Cycles:10 Average:105.40 Median:105.50 stddev:1.26 (1.2%) stddev-sans-first:1.33 19:06:57 INFO - PID 4424 | Values: 105.0 104.0 105.0 104.0 104.0 107.0 107.0 105.0 106.0 107.0 19:06:57 INFO - PID 4424 | 19:06:57 INFO - PID 4424 | [#1] audio-beat-detection Cycles:10 Average:115.30 Median:116.00 stddev:2.26 (2.0%) stddev-sans-first:2.06 19:06:57 INFO - PID 4424 | Values: 112.0 114.0 114.0 114.0 116.0 116.0 118.0 117.0 119.0 113.0 19:06:57 INFO - PID 4424 | 19:06:57 INFO - PID 4424 | [#2] audio-dft Cycles:10 Average:163.30 Median:163.50 stddev:2.36 (1.4%) stddev-sans-first:2.46 19:06:57 INFO - PID 4424 | Values: 162.0 162.0 164.0 164.0 163.0 169.0 160.0 162.0 163.0 164.0 19:06:57 INFO - PID 4424 | 19:06:57 INFO - PID 4424 | [#3] audio-fft Cycles:10 Average:73.60 Median:74.00 stddev:0.84 (1.1%) stddev-sans-first:0.88 19:06:57 INFO - PID 4424 | Values: 74.0 74.0 73.0 74.0 74.0 73.0 74.0 72.0 73.0 75.0 19:06:57 INFO - PID 4424 | 19:06:57 INFO - PID 4424 | [#4] audio-oscillator Cycles:10 Average:77.70 Median:78.00 stddev:0.82 (1.1%) stddev-sans-first:0.87 19:06:57 INFO - PID 4424 | Values: 78.0 77.0 78.0 78.0 78.0 78.0 79.0 77.0 78.0 76.0 19:06:57 INFO - PID 4424 | 19:06:57 INFO - PID 4424 | [#5] imaging-gaussian-blur Cycles:10 Average:114.20 Median:114.00 stddev:1.55 (1.4%) stddev-sans-first:1.64 19:06:57 INFO - PID 4424 | Values: 114.0 115.0 113.0 115.0 113.0 113.0 114.0 113.0 118.0 114.0 19:06:57 INFO - PID 4424 | 19:06:57 INFO - PID 4424 | [#6] imaging-darkroom Cycles:10 Average:149.90 Median:150.00 stddev:0.99 (0.7%) stddev-sans-first:1.00 19:06:57 INFO - PID 4424 | Values: 149.0 149.0 150.0 150.0 149.0 152.0 149.0 150.0 150.0 151.0 19:06:57 INFO - PID 4424 | 19:06:57 INFO - PID 4424 | [#7] imaging-desaturate Cycles:10 Average:95.00 Median:95.00 stddev:1.56 (1.6%) stddev-sans-first:0.73 19:06:57 INFO - PID 4424 | Values: 99.0 95.0 94.0 95.0 94.0 94.0 94.0 95.0 94.0 96.0 19:06:57 INFO - PID 4424 | 19:06:57 INFO - PID 4424 | [#8] json-parse-financial Cycles:10 Average:43.90 Median:43.50 stddev:1.60 (3.7%) stddev-sans-first:1.66 19:06:57 INFO - PID 4424 | Values: 43.0 44.0 43.0 48.0 43.0 45.0 43.0 43.0 43.0 44.0 19:06:57 INFO - PID 4424 | 19:06:57 INFO - PID 4424 | [#9] json-stringify-tinderbox Cycles:10 Average:37.90 Median:38.00 stddev:0.57 (1.5%) stddev-sans-first:0.50 19:06:57 INFO - PID 4424 | Values: 37.0 38.0 38.0 38.0 39.0 38.0 37.0 38.0 38.0 38.0 19:06:57 INFO - PID 4424 | 19:06:57 INFO - PID 4424 | [#10] stanford-crypto-aes Cycles:10 Average:71.10 Median:71.00 stddev:1.79 (2.5%) stddev-sans-first:1.86 19:06:57 INFO - PID 4424 | Values: 70.0 70.0 69.0 75.0 70.0 71.0 73.0 72.0 71.0 70.0 19:06:57 INFO - PID 4424 | 19:06:57 INFO - PID 4424 | [#11] stanford-crypto-ccm Cycles:10 Average:109.00 Median:111.50 stddev:5.94 (5.3%) stddev-sans-first:6.20 19:06:57 INFO - PID 4424 | Values: 106.0 105.0 104.0 114.0 110.0 113.0 104.0 120.0 101.0 113.0 19:06:57 INFO - PID 4424 | 19:06:57 INFO - PID 4424 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:151.90 Median:152.50 stddev:3.41 (2.2%) stddev-sans-first:3.54 19:06:57 INFO - PID 4424 | Values: 154.0 148.0 153.0 150.0 152.0 154.0 147.0 159.0 151.0 151.0 19:06:57 INFO - PID 4424 | 19:06:57 INFO - PID 4424 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:60.00 Median:60.00 stddev:1.33 (2.2%) stddev-sans-first:1.20 19:06:57 INFO - PID 4424 | Values: 62.0 62.0 60.0 60.0 59.0 60.0 61.0 59.0 58.0 59.0 19:06:57 INFO - PID 4424 | -------- Summary: end -------- 19:06:57 INFO - PID 4424 | 19:06:57 INFO - PID 4424 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 19:06:57 INFO - TEST-INFO | 4424: exit 0 19:06:57 INFO - TEST-OK | kraken | took 59023ms 19:06:57 INFO - SUITE-END | took 1170s 19:06:57 INFO - Completed test suite (00:19:30) 19:06:57 INFO - javascript benchmark 19:06:58 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 9032.925159203174, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3866.2074274773668, 3879.224155168966, 3902.746321489105, 3909.8045097745126, 3920.666743326817, 6276.780312689057, 6309.810705678837, 6351.047342898568, 6362.300146983828, 6447.065175549543, 6414.871702565946, 6420.71106800194, 6421.64680942548, 6431.7427302907945, 6440.194975628035, 5695.401982791809, 5700.7719691212405, 5725.627834190765, 5736.196932429463, 5744.770209191627, 5796.188533605298, 5807.419258074201, 5819.330776960662, 5835.533157347402, 5845.035569131088, 5754.798582049629, 5768.682722450264, 5818.970905145489, 5858.297004359461, 5859.4140585941495, 768.8753923314085, 5677.17680936262, 5695.601307908448, 5706.857328566819, 5817.331006934193, 5675.659460432346, 5742.913856292117, 5757.424257574209, 5772.7113644317615, 5781.508571771405, 6262.248530176363, 6293.339199384044, 6333.714982825784, 6358.268799088118, 6373.585716928431, 6859.142607174103, 6878.759243426506, 6895.586264824126, 6896.482763792736, 6985.091938048072, 6922.58464492127, 6945.791626251221, 7005.824854378681, 7022.754203602901, 7036.542624729376, 6330.208723909512, 6408.391202835724, 6422.389872962061, 6426.550141490097, 6431.163948686688, 7026.191987921455, 7067.257937916547, 7086.078809754699, 7136.607486588184, 7163.140423149255, 6827.590344579242, 6869.896951545731, 6897.068895699117, 6913.308669133046, 6924.065251191126, 6907.516473846783, 6953.89569156463, 6971.024056632075, 7003.299670033058, 7034.402075823612, 6834.077399550997, 6864.828379290558, 6913.343232392915, 6925.515213934977, 6934.653267336613, 6867.141607299088, 6873.072135261776, 6881.931180688229, 6931.306869313129, 6973.93026069743, 4917.508249175054, 4945.307656928033, 5002.774875130628, 5015.949840501621, 5018.021485810232, 4432.955670443254, 4467.620252278528, 4482.148391805546, 4500.639948804153, 4506.324051392317, 6306.653134077578, 6338.207724034496, 6340.714667839959, 6366.013267943476, 6393.264774550893, 6847.075644787989, 6858.931410685829, 6936.063631409796, 6940.09778728762, 6945.305469453015, 6272.40412160844, 6283.717232724538, 6380.234371875312, 6383.808485745435, 6403.903941440882, 5517.641353312022, 5548.583856210719, 5577.0, 5595.580331475156, 5607.271054762937, 7101.414719440487, 7111.253318401494, 7210.96394518024, 7211.278872112747, 7231.204567497571, 6785.0, 6829.385355318045, 6879.759208427681, 6881.621510816753, 6907.930920690729, 7036.788896333118, 7042.718291268292, 7059.5764254144915, 7065.0815393998455, 7070.39901608369, 6731.932680673131, 6736.090627765189, 6737.79786606383, 6739.292374300824, 6888.483363747639, 6843.418309443753, 6911.827204319731, 6950.79147625572, 6996.160460744743, 6997.650117494207, 6669.699863506057, 6675.966620166868, 6750.594964302158, 6754.459643228628, 6764.763233286812, 6467.644279564669, 6596.604203747598, 6648.667566621552, 6668.06647069401, 6759.831004224934, 6577.934220657732, 6607.141071660845, 6608.636524991171, 6610.8016759497295, 6612.173478315211, 6642.368974947372, 6648.634325112165, 6674.065630811593, 6731.528792984444, 6738.326167383419, 6351.872962540816, 6430.3891130342545, 6437.645929473924, 6529.281779004401, 6530.0], "value": 6256.023047148613, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [960.9231261499081, 972.907573780491, 999.7950420163867, 1019.7093828258943, 1033.0083120204613, 41305.0, 42929.0, 42943.35584966228, 43043.0, 43086.13827723444, 39315.0, 39316.410253846145, 39346.40980385289, 39522.209555808935, 39554.60445395545, 7602.467827252094, 7621.123570789375, 7776.416768742339, 7878.251566101211, 7887.014123234596, 7690.884636730426, 7766.06807183136, 7768.417368697342, 7773.416993725465, 7776.766696999099, 8368.121347258542, 8570.742877713647, 8574.013988391322, 8576.05663377028, 8579.785505362353, 8452.0, 8508.787280318042, 8516.957415212915, 8528.658853645878, 8532.914670853273, 8341.290990265774, 8423.452475589129, 8486.957565212197, 8521.829563408759, 8525.658973641046, 39920.401193982114, 39934.201315973805, 40011.59988400108, 40174.19651606952, 40371.7981410094, 3773.0, 3815.2178803345246, 3848.230353929225, 3852.383618621035, 3860.768353898775, 32233.355332893447, 32300.83849580737, 32325.35349293001, 32361.0, 32410.351792964244, 5495.95576840403, 5552.444755524456, 5583.916241256385, 5585.162225666142, 5700.971495142539, 201341.0, 204174.0, 204305.0, 205419.0, 205644.0, 51542.484575153765, 51655.7417212919, 51754.22368664473, 51851.48148518467, 51984.740076300135, 54255.0, 54259.72870135703, 54559.0, 54628.45371546234, 54672.0, 16145.515634530982, 16462.670746585, 16505.752413713803, 16508.75236871448, 16516.256768445448, 13252.27112508802, 13254.734905302033, 13303.3348332583, 13306.268155251553, 13307.201567905957, 15098.773518397233, 15300.693986120215, 15303.38786448552, 15391.461298854487, 15441.691166176613, 5839.153322768175, 5883.17635531026, 6010.158577799111, 6026.578139530191, 6198.349173336825, 1063.2031866146187, 1073.7047311989265, 1079.2768844873954, 1080.864891888514, 1094.075506197262, 24711.206918060125, 24765.380865478506, 24782.256532304058, 24857.87571062133, 24875.62686559703, 1172.1853311948114, 1184.9348285844362, 1200.3098218524383, 1201.1831954271179, 1218.4456072487174, 13082.934585327013, 13174.275414852274, 13452.394642241123, 13461.932690336485, 13600.251986140856, 280090.0, 280476.0, 280752.0, 282313.0, 282444.0, 56136.0, 56356.71821640948, 60175.39824601785, 60222.0, 60328.698356507935, 1500.18240059168, 1501.1218437214352, 1508.2835653064706, 1519.187234829383, 1523.489630973614, 29071.27321816887, 29470.410591788474, 29762.553561696157, 29807.0, 30412.391752165277, 35319.64680353266, 38816.80591597024, 39062.0, 39422.0, 39475.0, 226549.0, 227014.0, 227462.0, 227805.0, 228174.0, 34147.65852341544, 34223.0, 34289.657103428646, 34405.655943440244, 34435.82782086074, 42667.78666106649, 43669.0, 44131.0, 44133.55866441423, 44336.0, 33355.0, 33528.664713352555, 33730.8313458441, 33731.662683373834, 34035.48946765751, 33371.666283336854, 33837.32325353529, 33842.830785845916, 33883.4917476243, 33893.152671183416, 38029.2394152121, 38456.23087538178, 38605.0, 38873.80563097166, 38886.0, 32435.35129297448, 32439.189020274684, 32566.511502327958, 32643.0, 32708.836455817567, 24152.75847241505, 24469.265922021656, 24713.87643061845, 25350.49299014046, 25592.744072559035], "value": 18319.74364902337, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [595.8421018430117, 613.3529126771257, 613.4724137241968, 614.5882258886545, 623.3859648246478, 23279.883600581994, 23929.324947253677, 23975.400614984635, 24004.39989000276, 24016.75983240167, 21846.781532184672, 22704.772952270472, 22756.544869102607, 22773.544529109407, 22794.88602556985, 6848.82877928052, 6850.897236541456, 6903.689333979958, 6942.7917162484955, 6987.930120698778, 6530.314316996718, 6803.421709154728, 6813.625250611214, 6843.62360070196, 6856.142982127234, 6809.489288303373, 6810.829729256783, 6824.419924306415, 6838.589684618939, 6838.760643377483, 6656.534042617019, 6828.897566536506, 6834.4190743786585, 6835.213950395693, 6909.0, 6379.266384365765, 6424.903626445607, 6440.4525615322755, 6441.420272175526, 6445.129907462479, 29528.0, 29903.551446728317, 29934.401311973856, 30163.547546786816, 30177.0, 2301.8158547316175, 2316.9652455213186, 2332.2536788227776, 2338.1582630253097, 2345.2846881701075, 19035.524111897175, 19469.610607787905, 19549.0, 19609.607807843906, 19660.606787864304, 2600.6749156355454, 2610.517054344933, 2652.668416447944, 2668.3996100877343, 2673.585594232897, 137609.3119534406, 144979.0, 150757.0, 156495.0, 156686.0, 15339.309731062129, 15443.61390965235, 15677.451289204822, 15843.88059104618, 16381.0, 24632.13787517464, 24849.254522364357, 25001.499970000863, 25016.37459063538, 25056.624150637756, 10288.639897603547, 10480.947595262127, 10519.84220236697, 10570.365778053341, 10600.0, 9448.95275523618, 9544.141027307576, 9573.28200384974, 9587.137157655845, 9589.328746987783, 9409.0, 9410.435373877452, 9411.764705882408, 9473.484242521301, 9604.66383676582, 2689.300781796747, 2715.8506282154276, 2716.307341627872, 2881.1932658855553, 2955.600993865801, 1018.4703953943907, 1064.6965614799744, 1067.3009178987775, 1069.2087854987408, 1079.6113399176447, 20207.393778186677, 20843.0, 20944.162233510793, 21295.68056479154, 21417.92910354476, 1093.2729734726317, 1103.542030057533, 1107.2968664897687, 1115.654147214366, 1118.4128332625273, 11608.129390295593, 11662.358570278717, 11801.350925698996, 11858.703532411586, 11892.405379730979, 546549.0, 567472.0, 574367.0, 577596.0, 601372.0, 131877.0, 132081.0, 133129.3343533276, 133179.0, 133703.0, 1239.6590937492263, 1250.2685928731794, 1270.955516556916, 1281.077624110639, 1289.8516670582815, 18436.907815460836, 18832.62334753325, 18860.339888103852, 18943.43169704911, 19099.04504774728, 60965.39034609597, 62513.37486625076, 62610.68694656497, 62746.37253627588, 63261.683691581246, 77566.0, 77891.6105419492, 78323.60838195773, 81088.18911811043, 82956.0, 15309.387624494895, 15401.922990385425, 15511.37954481808, 15531.223438827787, 15584.610384740019, 23136.6529502061, 23583.41041473977, 23584.646230306214, 23724.169654062025, 23784.286471405885, 19193.32823351176, 19247.326343577908, 19446.61106777828, 19679.21283148658, 19743.506412339808, 16792.24434900408, 16991.405300814906, 17016.0, 17350.132493375535, 17416.564585884946, 19560.60878782388, 19628.018599070274, 20195.0, 20260.493487662927, 20339.79660203438, 14976.625584360478, 15159.848401515843, 15198.696026079195, 15260.0, 15320.38718451294, 19027.90486047561, 19201.903990479957, 19208.807911920703, 19408.902955485133, 19423.708644370065], "value": 12651.054110134517, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1467.8238611366635, 1516.2873449478748, 1531.1731664900954, 1536.1244090868202, 1552.0454920224058, 16333.428330008452, 17066.91466542667, 17129.0, 17138.828611713878, 17144.314227430918, 15819.367225311002, 15956.122413267265, 16021.0, 16023.679526409465, 16047.0, 2494.625806129077, 2511.4098186926058, 2542.5677634802128, 2544.8854801533885, 2545.6563363945907, 2762.6684797824187, 2782.874770635316, 2795.9440811183763, 2814.2964258935267, 2815.1132393296048, 2748.7663548598293, 2803.355228297493, 2845.2175651695743, 2845.430913817245, 2848.829070255791, 2825.5055365310986, 2834.305595129186, 2838.2620518665194, 2838.787090968175, 2862.5992361069466, 2320.582295186866, 2413.6138217885054, 2781.7913656475644, 2791.8324900506036, 2793.888244470219, 16532.256048477848, 16698.916505417517, 16709.248083836257, 16720.0, 16721.247543860554, 8238.382121340923, 8398.496090234605, 8422.115677853799, 8436.114208008194, 8453.873191902127, 5440.036188237846, 5525.557955363562, 5556.638818476827, 5668.773249070074, 5681.488666020036, 2196.934091977243, 2198.8460807743463, 2220.7890250426185, 2224.63293556563, 2265.569541787055, 29308.267293317625, 29440.0, 29534.409311813644, 29999.700002999907, 30798.692013079806, 6783.728650853911, 6842.4868134890085, 6900.378965893094, 7008.123984501937, 7033.577985320897, 18756.343527976456, 19149.712754308697, 19166.71249931252, 19428.417147485598, 19456.221751129797, 8357.080721120672, 8596.828063438696, 8600.139986001475, 8601.440906341068, 8609.053004169537, 7572.7728168155045, 7758.030246219223, 7797.0, 7803.102643196105, 7890.447668663139, 5478.58910581708, 5685.687287199244, 5708.628939118944, 5719.142128680648, 5735.913961290584, 1598.296749430247, 1611.9113448760431, 1628.3242454381536, 1630.6168050508122, 1640.6636639488877, 985.5121714751244, 998.1665309466531, 998.4009594243396, 1004.2116938203475, 1022.3048327137469, 8482.491050536864, 9295.581698823435, 9315.115064068903, 9487.098725621056, 9645.276604254708, 776.856281587908, 793.8134538383475, 802.3821657323828, 802.574635443216, 819.5451524403894, 5272.525472707474, 5374.328208973879, 5384.380796208407, 5412.891742165213, 5866.384029676822, 23567.764322356557, 24620.6306905393, 25289.367765805633, 25344.619830702555, 25350.971922246175, 23294.53410931772, 24521.87739061293, 24528.754712453003, 24583.754162458503, 24677.38306542351, 12637.936810316074, 12691.555795547112, 12727.10910236293, 12808.80786788199, 12840.486380544673, 9236.861447078434, 9387.0, 9402.106799854002, 9416.387934784218, 9491.145796878314, 20489.897550512153, 20590.0, 21185.152593896073, 21485.248016319354, 21917.671234931808, 21313.360599182048, 21528.0, 21640.0, 21691.349259522238, 21870.23454179096, 11808.291502509877, 11854.94072529632, 11858.288502689866, 11998.100142489522, 12021.639350819489, 15220.771688424462, 15317.157556334509, 15396.538103856903, 15416.845831541541, 15449.304781284643, 11985.34080625574, 12375.566855160027, 12618.36908154607, 12777.552785652457, 12930.418131183931, 11808.468618911998, 12016.399180040788, 12282.201656892274, 12284.692882678004, 12300.692482688004, 11057.11543076568, 11252.099832013255, 11267.605633802777, 11290.887091128983, 11303.0, 8213.753587392388, 8257.504549726795, 8370.665173392996, 8404.243618074403, 8441.0, 8270.503769773833, 8275.917240827515, 8308.459950103224, 8317.625706843328, 8346.624401901807], "value": 7202.24817514519, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [549.1597855281418, 563.5716855190054, 577.0680351232759, 584.1086621224938, 586.3784388548137, 6331.873362532747, 6371.235451745792, 6383.265924418687, 6411.455026322768, 6439.645819479927, 6280.801719398209, 6303.338149494306, 6350.110984462179, 6359.71381287842, 6421.711023003964, 5104.157813960701, 5156.74216289184, 5172.456892026339, 5186.974065129669, 5190.377154741417, 4889.486603906592, 5018.623603229754, 5033.572146367563, 5044.369453818273, 5073.594112471012, 4998.200287953928, 5008.574271186953, 5051.621128415365, 5060.11447996601, 5061.54446099851, 4886.0, 4993.850184494471, 4996.700197988114, 5026.673266237683, 5071.644984851062, 4522.29904364824, 4538.909221815578, 4544.7045942013665, 4589.816407343702, 4596.770161491945, 6038.245219347582, 6107.206063211798, 6155.214543056033, 6244.687765611701, 6267.686615669244, 5922.200502932092, 5955.7541140078765, 5981.970090149522, 5989.670568118751, 6000.249968753906, 6715.294894036105, 6770.49221308399, 6800.557963732391, 6816.693248803816, 6843.178818541807, 5105.974470127663, 5109.080365534202, 5116.744162791882, 5151.742412879341, 5164.1995490699, 6722.9663851680425, 6901.827454313632, 6939.167299923991, 7014.123234595676, 7044.647767611599, 5209.244659524424, 5229.320188375486, 5240.528352448298, 5296.682199068068, 5296.947030529645, 5289.947100528945, 5293.497117773805, 5299.496547828027, 5319.627626066215, 5353.652012619167, 5834.978878696211, 5886.1465087562065, 5960.5827592068135, 5965.582409231399, 5979.774146300085, 6710.530262881649, 6745.291744366771, 6758.668259450482, 6820.965895170492, 6824.283450237753, 1838.6598479281108, 1840.2363019346822, 1844.3913508542148, 1857.5913299074182, 1868.5235265007336, 1234.3519652182497, 1290.3096843188848, 1308.8809068246703, 1311.658968668134, 1317.4137508808487, 6028.216331876828, 6077.270727512727, 6189.783357582553, 6191.9690401547705, 6194.721237544321, 6741.696623651849, 6745.426638735762, 6798.94616334469, 6833.384995350442, 6834.2140653825445, 6222.968885155636, 6273.153124328157, 6275.337035685547, 6281.560290779602, 6310.495160387158, 3574.7140228781636, 3600.801955892451, 3616.638336166415, 3629.8729544466346, 3643.9453408198897, 6919.823629982915, 6920.446364290845, 7032.964835175791, 7048.859022819515, 7114.573125612376, 6912.343327383891, 6920.4809639277255, 6969.721211151497, 6984.126984126984, 7026.683799229047, 7092.858142837114, 7126.966589844444, 7127.394171495377, 7135.6432178391915, 7180.317869802465, 6850.794476165723, 6882.8623427532175, 6896.517243792886, 6903.309669033057, 6932.168139823052, 6781.23687841155, 6812.8978065328065, 6825.52221344521, 6871.828204294932, 6933.965330173317, 6931.0, 6983.75556855528, 7018.368346848808, 7072.190910181126, 7101.928980710333, 5170.121079416584, 5225.477452254744, 5238.790448382022, 5259.973700131475, 5270.156774915995, 6724.596524208563, 6822.94035049145, 6835.248122706399, 6844.178698556205, 6864.725410983505, 6320.241571011508, 6448.451881590117, 6522.380373864476, 6544.149260596092, 6616.801495955129, 6519.576227545194, 6543.247526534509, 6570.211574611078, 6647.4682025438815, 6660.600363978177, 6261.968690156702, 6461.160049193575, 6555.6066636002, 6583.144191255106, 6586.506012049212, 6531.248906375828, 6665.600063996176, 6713.362230588086, 6732.562383445061, 6747.527673062838, 6400.319816105851, 6465.1595292611655, 6482.222133344029, 6511.47908167336, 6532.510061745293], "value": 5235.552686723926, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [956.4118067388549, 987.6049580167933, 997.0328781082352, 1003.7841863999239, 1017.8218811707949, 20284.492887677796, 20564.280250191245, 20574.38276851693, 20578.279760208414, 20890.477738056557, 18614.813851861476, 18810.24759009638, 18876.0, 19284.51788705283, 19569.902150489263, 1036.2746077745571, 1062.8777690565569, 1065.1532032034552, 1065.2277099103167, 1065.8081545321838, 1083.409541799717, 1089.1722291058813, 1094.8795632480421, 1095.5015467962098, 1098.1708809848553, 1097.401915955806, 1128.5203788389942, 1128.9830652540218, 1139.4188963628567, 1147.6499667601379, 1117.8826223246524, 1124.449019980212, 1125.6341688951043, 1125.6679279612533, 1136.2160109524402, 788.3102285500187, 824.5547404401616, 826.4256341842422, 826.7437094500724, 831.7380025292074, 18469.076546172637, 18847.151878165514, 18950.147243374082, 18989.430317090508, 19065.80934190654, 21054.0, 21327.93166759807, 22365.0, 22402.775972240233, 22767.88616056926, 18417.447476575726, 18485.907570462197, 18599.53501162468, 18663.62672746551, 18669.906650466797, 4032.2741906456827, 4043.0498832774274, 4067.633912947849, 4068.3694027425795, 4079.530853951774, 153996.0, 154745.22627386905, 156156.2192189032, 157490.0, 159018.0, 17733.202005909767, 18002.909985449987, 18041.368552100612, 18092.547686307684, 18136.546586335182, 31761.0, 32547.51178732321, 32563.83718081442, 32571.34857302841, 32598.0, 9617.51912404377, 9689.27330450219, 9735.123838854537, 9797.265205109645, 9810.558524866256, 4490.169318676056, 4553.385292985471, 4555.088982203506, 4578.03861189154, 4610.97694511532, 5914.290285165807, 5920.082387229986, 5993.670348130895, 5995.2206213192, 6045.969770151121, 1100.9009189173014, 1101.6639924822846, 1117.1677100559973, 1117.2737720481753, 1117.4803716271972, 729.6607077708891, 730.4631096144329, 746.8879668049836, 748.0835975929443, 764.228129589119, 12303.384830758605, 12316.81524777129, 12418.565350212699, 12638.368081596067, 13078.907374819983, 729.2306616519517, 737.71229220604, 738.1584993107862, 743.8028922335477, 762.2644148396823, 5012.598992080625, 5084.186530155232, 5095.8980820383385, 5113.53978141969, 5128.589712822965, 152185.0, 156487.0, 156974.0, 158138.0, 159845.2007739954, 120600.0, 124785.0, 127161.36419317844, 129579.99999999811, 131872.0, 110078.0, 113775.4311228455, 116044.8395516051, 117588.0, 122949.38525307317, 13030.153040052366, 13164.341782910624, 13193.20840749558, 13228.338583071001, 13331.600051998455, 54590.72704636451, 55039.724801375734, 55526.72236638791, 55856.441435585126, 56071.0, 70576.29423705697, 70759.0, 70939.64530177315, 71361.6431917837, 73304.63347683227, 19075.141618626916, 19483.415497535098, 19536.0, 19705.211791528178, 19736.506587334858, 20889.686654700494, 20910.268140615004, 20986.580268394853, 21038.89480552649, 21156.047977841343, 18231.270749169886, 18407.723884142015, 18436.723449148543, 18538.907305463927, 18563.628727425647, 18403.81596184021, 18542.536436589195, 18580.25678972826, 18583.907080464513, 18854.52863678419, 18937.715934261272, 19011.904940475208, 19019.809801901803, 19063.332783352515, 19309.51726206856, 13030.674233144247, 13064.21614703121, 13187.142835715647, 13389.732205356033, 13466.259355735529, 13146.474141034634, 13180.472781088649, 13324.933375333449, 13328.200307981551, 13543.86456135426], "value": 9935.036577263634, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1368.2, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 104.0, 105.0, 104.0, 104.0, 107.0, 107.0, 105.0, 106.0, 107.0], "value": 105.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 114.0, 114.0, 114.0, 116.0, 116.0, 118.0, 117.0, 119.0, 113.0], "value": 115.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 162.0, 164.0, 164.0, 163.0, 169.0, 160.0, 162.0, 163.0, 164.0], "value": 163.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 74.0, 73.0, 74.0, 74.0, 73.0, 74.0, 72.0, 73.0, 75.0], "value": 73.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 77.0, 78.0, 78.0, 78.0, 78.0, 79.0, 77.0, 78.0, 76.0], "value": 77.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 115.0, 113.0, 115.0, 113.0, 113.0, 114.0, 113.0, 118.0, 114.0], "value": 114.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 149.0, 150.0, 150.0, 149.0, 152.0, 149.0, 150.0, 150.0, 151.0], "value": 149.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 95.0, 94.0, 95.0, 94.0, 94.0, 94.0, 95.0, 94.0, 96.0], "value": 95.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [43.0, 44.0, 43.0, 48.0, 43.0, 45.0, 43.0, 43.0, 43.0, 44.0], "value": 43.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [37.0, 38.0, 38.0, 38.0, 39.0, 38.0, 37.0, 38.0, 38.0, 38.0], "value": 37.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 70.0, 69.0, 75.0, 70.0, 71.0, 73.0, 72.0, 71.0, 70.0], "value": 71.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 105.0, 104.0, 114.0, 110.0, 113.0, 104.0, 120.0, 101.0, 113.0], "value": 109.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 148.0, 153.0, 150.0, 152.0, 154.0, 147.0, 159.0, 151.0, 151.0], "value": 151.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 62.0, 60.0, 60.0, 59.0, 60.0, 61.0, 59.0, 58.0, 59.0], "value": 60.0, "unit": "score"}]}]} 19:06:58 INFO - Return code: 0 19:06:58 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 19:06:58 INFO - # TBPL SUCCESS # 19:06:58 INFO - Running post-action listener: _package_coverage_data 19:06:58 INFO - Running post-action listener: _resource_record_post_action 19:06:58 INFO - [mozharness: 2017-09-21 02:06:58.131521Z] Finished run-tests step (success) 19:06:58 INFO - Running post-run listener: _resource_record_post_run 19:06:58 INFO - Total resource usage - Wall time: 1188s; CPU: 13.0%; Read bytes: 9445376; Write bytes: 458031104; Read time: 3132; Write time: 400712 19:06:58 INFO - TinderboxPrint: CPU usage
13.1% 19:06:58 INFO - TinderboxPrint: I/O read bytes / time
9,445,376 / 3,132 19:06:58 INFO - TinderboxPrint: I/O write bytes / time
458,031,104 / 400,712 19:06:58 INFO - TinderboxPrint: CPU idle
8,236.3 (86.9%) 19:06:58 INFO - TinderboxPrint: CPU user
1,186.7 (12.5%) 19:06:58 INFO - TinderboxPrint: Swap in / out
0 / 0 19:06:58 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 12308480; Read time: 0; Write time: 1780 19:06:58 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 19:06:58 INFO - run-tests - Wall time: 1171s; CPU: 13.0%; Read bytes: 9445376; Write bytes: 445722624; Read time: 3132; Write time: 398932 19:06:58 INFO - Running post-run listener: _upload_blobber_files 19:06:58 INFO - Blob upload gear active. 19:06:58 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:06:58 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 19:06:58 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'] 19:06:58 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 19:06:59 INFO - (blobuploader) - INFO - Open directory for files ... 19:06:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 19:06:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:06:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:07:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 19:07:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:07:00 INFO - (blobuploader) - INFO - Done attempting. 19:07:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 19:07:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:07:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:07:01 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 19:07:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:07:01 INFO - (blobuploader) - INFO - Done attempting. 19:07:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:07:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:07:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:07:09 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:07:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:07:09 INFO - (blobuploader) - INFO - Done attempting. 19:07:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 19:07:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:07:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:07:10 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 19:07:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:07:10 INFO - (blobuploader) - INFO - Done attempting. 19:07:10 INFO - (blobuploader) - INFO - Iteration through files over. 19:07:10 INFO - Return code: 0 19:07:10 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:07:10 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:07:10 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/34401e9eebf56367e260163383c045aa1560b2acd42487d966d74c2823cebb7f0062918ac64a21f27eda3d920c05646d517203c211cc6fe806dc4470c42d694b", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2b3214e65cea34fdb41aacfd382a9df79072d28a9ffe17bd4aba70051d09d17fea4ecc66d79bf980c277eaf94e3dcf53198a9b4e7286593468f371c2d5382358", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f2f7b2af2de644885c7cc18c8c82296005619507f3f0443611c51237f7fae308d0f2adbb770e2eb9c455a687ac618fc9a6f572eb7cc8a0cbcb9bd78dacbddfb2", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/779d9a7f18ee924c663cb4ce42c8e0a556c10ec363909bca0163b95d754426843671b0354e2a032dfd2e7fad646582a1e3e1b4c1bcaa4c57263d89b27638e3e4"} 19:07:10 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:07:10 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:07:10 INFO - Contents: 19:07:10 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/34401e9eebf56367e260163383c045aa1560b2acd42487d966d74c2823cebb7f0062918ac64a21f27eda3d920c05646d517203c211cc6fe806dc4470c42d694b", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2b3214e65cea34fdb41aacfd382a9df79072d28a9ffe17bd4aba70051d09d17fea4ecc66d79bf980c277eaf94e3dcf53198a9b4e7286593468f371c2d5382358", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f2f7b2af2de644885c7cc18c8c82296005619507f3f0443611c51237f7fae308d0f2adbb770e2eb9c455a687ac618fc9a6f572eb7cc8a0cbcb9bd78dacbddfb2", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/779d9a7f18ee924c663cb4ce42c8e0a556c10ec363909bca0163b95d754426843671b0354e2a032dfd2e7fad646582a1e3e1b4c1bcaa4c57263d89b27638e3e4"} 19:07:10 INFO - Running post-run listener: copy_logs_to_upload_dir 19:07:10 INFO - Copying logs to upload dir... 19:07:10 INFO - mkdir: /builds/slave/test/build/upload/logs 19:07:10 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1244.591016 ========= master_lag: 0.11 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 44 secs) (at 2017-09-20 19:07:11.215075) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-20 19:07:11.220095) ========= 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=41bf3e9aed84707956354da8000001c5-1505958174.735341-1296699532 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/34401e9eebf56367e260163383c045aa1560b2acd42487d966d74c2823cebb7f0062918ac64a21f27eda3d920c05646d517203c211cc6fe806dc4470c42d694b", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2b3214e65cea34fdb41aacfd382a9df79072d28a9ffe17bd4aba70051d09d17fea4ecc66d79bf980c277eaf94e3dcf53198a9b4e7286593468f371c2d5382358", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f2f7b2af2de644885c7cc18c8c82296005619507f3f0443611c51237f7fae308d0f2adbb770e2eb9c455a687ac618fc9a6f572eb7cc8a0cbcb9bd78dacbddfb2", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/779d9a7f18ee924c663cb4ce42c8e0a556c10ec363909bca0163b95d754426843671b0354e2a032dfd2e7fad646582a1e3e1b4c1bcaa4c57263d89b27638e3e4"} build_url:https://queue.taskcluster.net/v1/task/BqbOEH4RRjOmGYuwp-1rsQ/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011354 build_url: 'https://queue.taskcluster.net/v1/task/BqbOEH4RRjOmGYuwp-1rsQ/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/34401e9eebf56367e260163383c045aa1560b2acd42487d966d74c2823cebb7f0062918ac64a21f27eda3d920c05646d517203c211cc6fe806dc4470c42d694b", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2b3214e65cea34fdb41aacfd382a9df79072d28a9ffe17bd4aba70051d09d17fea4ecc66d79bf980c277eaf94e3dcf53198a9b4e7286593468f371c2d5382358", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f2f7b2af2de644885c7cc18c8c82296005619507f3f0443611c51237f7fae308d0f2adbb770e2eb9c455a687ac618fc9a6f572eb7cc8a0cbcb9bd78dacbddfb2", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/779d9a7f18ee924c663cb4ce42c8e0a556c10ec363909bca0163b95d754426843671b0354e2a032dfd2e7fad646582a1e3e1b4c1bcaa4c57263d89b27638e3e4"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-20 19:07:11.251192) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-20 19:07:11.251520) ========= 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=41bf3e9aed84707956354da8000001c5-1505958174.735341-1296699532 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004393 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-20 19:07:11.336672) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-20 19:07:11.336988) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-20 19:07:11.337303) ========= ========= Total master_lag: 0.53 =========