builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-018 starttime: 1505501486.28 results: success (0) revision: 9071a20f3eaa804a439990b7596dbc16d52d1e20 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-15 11:51:26.277817) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-15 11:51:26.278263) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-15 11:51:26.278533) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-15 11:51:26.296153) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-15 11:51:26.296449) ========= bash -c 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=62c568580acbfdaac3eec7be000001dc-1505500579.446389-1924944135 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-15 11:51:26-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 46.3M=0s 2017-09-15 11:51:26 (46.3 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.177872 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-15 11:51:26.494240) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-15 11:51:26.494602) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=62c568580acbfdaac3eec7be000001dc-1505500579.446389-1924944135 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015951 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-15 11:51:26.544551) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-15 11:51:26.547924) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 9071a20f3eaa804a439990b7596dbc16d52d1e20 --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 9071a20f3eaa804a439990b7596dbc16d52d1e20 --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=62c568580acbfdaac3eec7be000001dc-1505500579.446389-1924944135 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-15 11:51:26,624 truncating revision to first 12 chars 2017-09-15 11:51:26,624 Setting DEBUG logging. 2017-09-15 11:51:26,624 attempt 1/10 2017-09-15 11:51:26,624 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/9071a20f3eaa?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-15 11:51:27,053 unpacking tar archive at: mozilla-central-9071a20f3eaa/testing/mozharness/ program finished with exit code 0 elapsedTime=0.802091 ========= master_lag: 0.06 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-15 11:51:27.405816) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-15 11:51:27.406101) ========= script_repo_revision: 9071a20f3eaa804a439990b7596dbc16d52d1e20 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-15 11:51:27.406525) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-15 11:51:27.409812) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-15 11:51:27.490571) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 40 secs) (at 2017-09-15 11:51:27.493953) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=62c568580acbfdaac3eec7be000001dc-1505500579.446389-1924944135 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 11:51:27 INFO - MultiFileLogger online at 20170915 11:51:27 in /builds/slave/test 11:51:27 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 11:51:27 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:51:27 INFO - {'append_to_log': False, 11:51:27 INFO - 'base_work_dir': '/builds/slave/test', 11:51:27 INFO - 'blob_upload_branch': 'Firefox', 11:51:27 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:51:27 INFO - 'branch': 'Firefox', 11:51:27 INFO - 'buildbot_json_path': 'buildprops.json', 11:51:27 INFO - 'code_coverage': False, 11:51:27 INFO - 'config_files': ('talos/linux_config.py',), 11:51:27 INFO - 'default_actions': ('clobber', 11:51:27 INFO - 'read-buildbot-config', 11:51:27 INFO - 'download-and-extract', 11:51:27 INFO - 'populate-webroot', 11:51:27 INFO - 'create-virtualenv', 11:51:27 INFO - 'install', 11:51:27 INFO - 'setup-mitmproxy', 11:51:27 INFO - 'run-tests'), 11:51:27 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:51:27 INFO - 'disable_ccov_upload': False, 11:51:27 INFO - 'disable_stylo': False, 11:51:27 INFO - 'download_minidump_stackwalk': True, 11:51:27 INFO - 'download_symbols': 'ondemand', 11:51:27 INFO - 'enable_stylo': False, 11:51:27 INFO - 'enable_webrender': False, 11:51:27 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 11:51:27 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:51:27 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:51:27 INFO - 'gecko_profile': False, 11:51:27 INFO - 'gecko_profile_interval': 0, 11:51:27 INFO - 'installer_path': 'installer.exe', 11:51:27 INFO - 'log_level': 'info', 11:51:27 INFO - 'log_name': 'talos', 11:51:27 INFO - 'log_to_console': True, 11:51:27 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 11:51:27 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 11:51:27 INFO - 'opt_config_files': (), 11:51:27 INFO - 'pip_index': False, 11:51:27 INFO - 'suite': 'dromaeojs-e10s', 11:51:27 INFO - 'system_bits': '32', 11:51:27 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:51:27 INFO - 'title': 'talos-linux64-ix-018', 11:51:27 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:51:27 INFO - 'use_talos_json': True, 11:51:27 INFO - 'verify': 'False', 11:51:27 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:51:27 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:51:27 INFO - 'work_dir': 'build'} 11:51:27 INFO - [mozharness: 2017-09-15 18:51:27.714964Z] Running clobber step. 11:51:27 INFO - Running pre-action listener: _resource_record_pre_action 11:51:27 INFO - Running main action method: clobber 11:51:27 INFO - rmtree: /builds/slave/test/build 11:51:27 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:51:28 INFO - Running post-action listener: _resource_record_post_action 11:51:28 INFO - [mozharness: 2017-09-15 18:51:28.715984Z] Finished clobber step (success) 11:51:28 INFO - [mozharness: 2017-09-15 18:51:28.716080Z] Running read-buildbot-config step. 11:51:28 INFO - Running pre-action listener: _resource_record_pre_action 11:51:28 INFO - Running main action method: read_buildbot_config 11:51:28 INFO - Using buildbot properties: 11:51:28 INFO - { 11:51:28 INFO - "project": "", 11:51:28 INFO - "product": "firefox", 11:51:28 INFO - "who": "ffxbld@noreply.mozilla.org", 11:51:28 INFO - "installer_path": "public/build/target.tar.bz2", 11:51:28 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 11:51:28 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 11:51:28 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs-e10s", 11:51:28 INFO - "stage_platform": "linux64-pgo", 11:51:28 INFO - "basedir": "/builds/slave/test", 11:51:28 INFO - "buildnumber": 163, 11:51:28 INFO - "platform": "ubuntu64_hw", 11:51:28 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 11:51:28 INFO - "slavebuilddir": "test", 11:51:28 INFO - "taskId": "fgxycU9VRv2WcwjKzOJUyg", 11:51:28 INFO - "branch": "mozilla-central", 11:51:28 INFO - "script_repo_revision": "production", 11:51:28 INFO - "revision": "9071a20f3eaa804a439990b7596dbc16d52d1e20", 11:51:28 INFO - "slavename": "talos-linux64-ix-018", 11:51:28 INFO - "repo_path": "mozilla-central" 11:51:28 INFO - } 11:51:28 INFO - Finding installer, test and symbols from parent task. 11:51:28 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fgxycU9VRv2WcwjKzOJUyg'}, attempt #1 11:51:29 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fgxycU9VRv2WcwjKzOJUyg'}, attempt #1 11:51:29 INFO - Task dependencies: X6FlnGhmTjqdwUMlw8Fc9A 11:51:29 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/X6FlnGhmTjqdwUMlw8Fc9A'}, attempt #1 11:51:30 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/X6FlnGhmTjqdwUMlw8Fc9A/artifacts/public/build/target.tar.bz2 11:51:30 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/X6FlnGhmTjqdwUMlw8Fc9A/artifacts/public/build/target.test_packages.json 11:51:30 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/X6FlnGhmTjqdwUMlw8Fc9A/artifacts/public/build/target.crashreporter-symbols.zip 11:51:30 INFO - Running post-action listener: _resource_record_post_action 11:51:30 INFO - [mozharness: 2017-09-15 18:51:30.908286Z] Finished read-buildbot-config step (success) 11:51:30 INFO - [mozharness: 2017-09-15 18:51:30.908500Z] Running download-and-extract step. 11:51:30 INFO - Running pre-action listener: _resource_record_pre_action 11:51:30 INFO - Running main action method: download_and_extract 11:51:30 INFO - mkdir: /builds/slave/test/build/tests 11:51:30 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')]} 11:51:30 INFO - trying https://queue.taskcluster.net/v1/task/X6FlnGhmTjqdwUMlw8Fc9A/artifacts/public/build/target.test_packages.json 11:51:30 INFO - Downloading https://queue.taskcluster.net/v1/task/X6FlnGhmTjqdwUMlw8Fc9A/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 11:51:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/X6FlnGhmTjqdwUMlw8Fc9A/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 11:51:31 INFO - Downloaded 989 bytes. 11:51:31 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 11:51:31 INFO - Using the following test package requirements: 11:51:31 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 11:51:31 INFO - u'common': [u'target.common.tests.zip'], 11:51:31 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 11:51:31 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 11:51:31 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 11:51:31 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 11:51:31 INFO - u'mozbase': [u'target.common.tests.zip'], 11:51:31 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 11:51:31 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 11:51:31 INFO - u'web-platform': [u'target.common.tests.zip', 11:51:31 INFO - u'target.web-platform.tests.tar.gz'], 11:51:31 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 11:51:31 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 11:51:31 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/X6FlnGhmTjqdwUMlw8Fc9A/artifacts/public/build/target.common.tests.zip 11:51:31 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/X6FlnGhmTjqdwUMlw8Fc9A/artifacts/public/build/target.common.tests.zip'}, attempt #1 11:51:31 INFO - Fetch https://queue.taskcluster.net/v1/task/X6FlnGhmTjqdwUMlw8Fc9A/artifacts/public/build/target.common.tests.zip into memory 11:51:32 INFO - Content-Length response header: 55331173 11:51:32 INFO - Bytes received: 55331173 11:51:43 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/X6FlnGhmTjqdwUMlw8Fc9A/artifacts/public/build/target.talos.tests.zip 11:51:43 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/X6FlnGhmTjqdwUMlw8Fc9A/artifacts/public/build/target.talos.tests.zip'}, attempt #1 11:51:43 INFO - Fetch https://queue.taskcluster.net/v1/task/X6FlnGhmTjqdwUMlw8Fc9A/artifacts/public/build/target.talos.tests.zip into memory 11:51:44 INFO - Content-Length response header: 14502661 11:51:44 INFO - Bytes received: 14502661 11:51: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')]} 11:51:44 INFO - trying https://queue.taskcluster.net/v1/task/X6FlnGhmTjqdwUMlw8Fc9A/artifacts/public/build/target.tar.bz2 11:51:44 INFO - Downloading https://queue.taskcluster.net/v1/task/X6FlnGhmTjqdwUMlw8Fc9A/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 11:51:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/X6FlnGhmTjqdwUMlw8Fc9A/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 11:51:47 INFO - Downloaded 62273483 bytes. 11:51:47 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/X6FlnGhmTjqdwUMlw8Fc9A/artifacts/public/build/target.tar.bz2 11:51:47 INFO - mkdir: /builds/slave/test/properties 11:51:48 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:51:48 INFO - Writing to file /builds/slave/test/properties/build_url 11:51:48 INFO - Contents: 11:51:48 INFO - build_url:https://queue.taskcluster.net/v1/task/X6FlnGhmTjqdwUMlw8Fc9A/artifacts/public/build/target.tar.bz2 11:51:48 INFO - Running post-action listener: _resource_record_post_action 11:51:48 INFO - Running post-action listener: find_tests_for_verification 11:51:48 INFO - Running post-action listener: set_extra_try_arguments 11:51:48 INFO - [mozharness: 2017-09-15 18:51:48.002985Z] Finished download-and-extract step (success) 11:51:48 INFO - [mozharness: 2017-09-15 18:51:48.003271Z] Running populate-webroot step. 11:51:48 INFO - Running pre-action listener: _resource_record_pre_action 11:51:48 INFO - Running main action method: populate_webroot 11:51:48 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:51:48 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:51:48 INFO - 'tests': ['tresize', 11:51:48 INFO - 'tcanvasmark']}, 11:51:48 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:51:48 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:51:48 INFO - 'tests': ['dromaeo_css', 11:51:48 INFO - 'kraken']}, 11:51:48 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 11:51:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:51:48 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 11:51:48 INFO - 'talos_options': ['--disable-stylo'], 11:51:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:51:48 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 11:51:48 INFO - 'tests': ['damp', 'tps']}, 11:51:48 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 11:51:48 INFO - 'talos_options': ['--disable-stylo'], 11:51:48 INFO - 'tests': ['damp', 'tps']}, 11:51:48 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:51:48 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:51:48 INFO - 'tests': ['dromaeo_dom']}, 11:51:48 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 11:51:48 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:51:48 INFO - 'tests': ['basic_compositor_video', 11:51:48 INFO - 'glvideo']}, 11:51:48 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 11:51:48 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 11:51:48 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 11:51:48 INFO - 'talos_options': ['--disable-stylo'], 11:51:48 INFO - 'tests': ['ts_paint_webext', 11:51:48 INFO - 'tp5o_webext']}, 11:51:48 INFO - 'other-e10s': {'tests': ['a11yr', 11:51:48 INFO - 'ts_paint', 11:51:48 INFO - 'tpaint', 11:51:48 INFO - 'sessionrestore', 11:51:48 INFO - 'sessionrestore_many_windows', 11:51:48 INFO - 'sessionrestore_no_auto_restore', 11:51:48 INFO - 'tabpaint']}, 11:51:48 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:51:48 INFO - 'tests': ['a11yr', 11:51:48 INFO - 'ts_paint', 11:51:48 INFO - 'tpaint', 11:51:48 INFO - 'sessionrestore', 11:51:48 INFO - 'sessionrestore_many_windows', 11:51:48 INFO - 'sessionrestore_no_auto_restore', 11:51:48 INFO - 'tabpaint']}, 11:51:48 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 11:51:48 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 11:51:48 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:51:48 INFO - 'tests': ['bloom_basic_singleton']}, 11:51:48 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:51:48 INFO - 'tests': ['bloom_basic']}, 11:51:48 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:51:48 INFO - 'tsvgr_opacity', 11:51:48 INFO - 'tart', 11:51:48 INFO - 'tscrollx', 11:51:48 INFO - 'tsvg_static']}, 11:51:48 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:51:48 INFO - 'tests': ['tsvgx', 11:51:48 INFO - 'tsvgr_opacity', 11:51:48 INFO - 'tart', 11:51:48 INFO - 'tscrollx', 11:51:48 INFO - 'tsvg_static']}, 11:51:48 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 11:51:48 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 11:51:48 INFO - 'talos_options': ['--disable-stylo'], 11:51:48 INFO - 'tests': ['tp5o']}, 11:51:48 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 11:51:48 INFO - 'talos_options': ['--mitmproxy', 11:51:48 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 11:51:48 INFO - '--firstNonBlankPaint'], 11:51:48 INFO - 'tests': ['tp6_google', 11:51:48 INFO - 'tp6_youtube', 11:51:48 INFO - 'tp6_amazon', 11:51:48 INFO - 'tp6_facebook']}, 11:51:48 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 11:51:48 INFO - 'talos_options': ['--disable-stylo', 11:51:48 INFO - '--mitmproxy', 11:51:48 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 11:51:48 INFO - '--firstNonBlankPaint'], 11:51:48 INFO - 'tests': ['tp6_google', 11:51:48 INFO - 'tp6_youtube', 11:51:48 INFO - 'tp6_amazon', 11:51:48 INFO - 'tp6_facebook']}, 11:51:48 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 11:51:48 INFO - 'talos_options': ['--stylo-threads=1', 11:51:48 INFO - '--mitmproxy', 11:51:48 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 11:51:48 INFO - '--firstNonBlankPaint'], 11:51:48 INFO - 'tests': ['tp6_google', 11:51:48 INFO - 'tp6_youtube', 11:51:48 INFO - 'tp6_amazon', 11:51:48 INFO - 'tp6_facebook']}, 11:51:48 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 11:51:48 INFO - 'talos_options': ['--xperf_path', 11:51:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:51:48 INFO - 'tests': ['tp5n']}, 11:51:48 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 11:51:48 INFO - 'talos_options': ['--disable-stylo', 11:51:48 INFO - '--xperf_path', 11:51:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:51:48 INFO - 'tests': ['tp5n']}}} 11:51:48 INFO - Running post-action listener: _resource_record_post_action 11:51:48 INFO - [mozharness: 2017-09-15 18:51:48.299529Z] Finished populate-webroot step (success) 11:51:48 INFO - [mozharness: 2017-09-15 18:51:48.299626Z] Running create-virtualenv step. 11:51:48 INFO - Running pre-action listener: _resource_record_pre_action 11:51:48 INFO - Running main action method: create_virtualenv 11:51:48 INFO - Creating virtualenv /builds/slave/test/build/venv 11:51:48 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 11:51:48 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 11:51:48 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 11:51:48 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:51:48 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:51:48 INFO - Installing distribute.............................................................................................................................................................................................done. 11:51:49 INFO - Installing pip...............done. 11:51:49 INFO - Return code: 0 11:51:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 11:51:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 11:51:49 INFO - Reading from file tmpfile_stdout 11:51:49 INFO - Output received: 11:51:49 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 11:51:49 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:51: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')]} 11:51:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:51: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')]} 11:51:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:51: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xff31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11bca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x135fa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1363090>, '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 0x1363d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x135df90>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1505500579.446389-1924944135', '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 11:51: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', 'pip>=1.5'] in /builds/slave/test/build 11:51: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 pip>=1.5 11:51:49 INFO - Using env: {'DISPLAY': ':0', 11:51:49 INFO - 'HOME': '/home/cltbld', 11:51:49 INFO - 'LANG': 'en_US.UTF-8', 11:51:49 INFO - 'LANGUAGE': 'en_US:en', 11:51:49 INFO - 'LOGNAME': 'cltbld', 11:51:49 INFO - 'MAIL': '/var/mail/cltbld', 11:51:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:51:49 INFO - 'MOZ_NO_REMOTE': '1', 11:51:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:51:49 INFO - 'NO_EM_RESTART': '1', 11:51:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:51:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:51:49 INFO - 'PWD': '/builds/slave/test', 11:51:49 INFO - 'SHELL': '/bin/bash', 11:51:49 INFO - 'SHLVL': '1', 11:51:49 INFO - 'TERM': 'linux', 11:51:49 INFO - 'TMOUT': '86400', 11:51:49 INFO - 'USER': 'cltbld', 11:51:49 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1505500579.446389-1924944135', 11:51:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:51:49 INFO - '_': '/tools/buildbot/bin/python'} 11:51:50 INFO - Ignoring indexes: http://pypi.python.org/simple/ 11:51:50 INFO - Downloading/unpacking pip>=1.5 11:51:50 INFO - Running setup.py egg_info for package pip 11:51:50 INFO - warning: no files found matching 'pip/cacert.pem' 11:51:50 INFO - warning: no files found matching '*.html' under directory 'docs' 11:51:50 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 11:51:50 INFO - no previously-included directories found matching 'docs/_build/_sources' 11:51:50 INFO - Installing collected packages: pip 11:51:50 INFO - Found existing installation: pip 1.1 11:51:50 INFO - Uninstalling pip: 11:51:50 INFO - Successfully uninstalled pip 11:51:50 INFO - Running setup.py install for pip 11:51:50 INFO - warning: no files found matching 'pip/cacert.pem' 11:51:50 INFO - warning: no files found matching '*.html' under directory 'docs' 11:51:50 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 11:51:50 INFO - no previously-included directories found matching 'docs/_build/_sources' 11:51:50 INFO - Installing pip script to /builds/slave/test/build/venv/bin 11:51:50 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 11:51:50 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 11:51:50 INFO - Successfully installed pip 11:51:50 INFO - Cleaning up... 11:51:50 INFO - Return code: 0 11:51:50 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 11:51:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:51:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:51:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:51:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:51:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0xff31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11bca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x135fa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1363090>, '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 0x1363d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x135df90>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1505500579.446389-1924944135', '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 11:51:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 11:51:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 11:51:50 INFO - Using env: {'DISPLAY': ':0', 11:51:50 INFO - 'HOME': '/home/cltbld', 11:51:50 INFO - 'LANG': 'en_US.UTF-8', 11:51:50 INFO - 'LANGUAGE': 'en_US:en', 11:51:50 INFO - 'LOGNAME': 'cltbld', 11:51:50 INFO - 'MAIL': '/var/mail/cltbld', 11:51:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:51:50 INFO - 'MOZ_NO_REMOTE': '1', 11:51:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:51:50 INFO - 'NO_EM_RESTART': '1', 11:51:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:51:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:51:50 INFO - 'PWD': '/builds/slave/test', 11:51:50 INFO - 'SHELL': '/bin/bash', 11:51:50 INFO - 'SHLVL': '1', 11:51:50 INFO - 'TERM': 'linux', 11:51:50 INFO - 'TMOUT': '86400', 11:51:50 INFO - 'USER': 'cltbld', 11:51:50 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1505500579.446389-1924944135', 11:51:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:51:50 INFO - '_': '/tools/buildbot/bin/python'} 11:51:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:51:50 INFO - Downloading/unpacking psutil>=3.1.1 11:51:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:51:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:51:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:51:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:51:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:51:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:51:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:51:51 INFO - Installing collected packages: psutil 11:51:51 INFO - Running setup.py install for psutil 11:51:51 INFO - building 'psutil._psutil_linux' extension 11:51: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 11:51:52 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 11:51:52 INFO - building 'psutil._psutil_posix' extension 11:51:52 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 11:51:52 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 11:51:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:51:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:51:52 INFO - Successfully installed psutil 11:51:52 INFO - Cleaning up... 11:51:52 INFO - Return code: 0 11:51:52 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 11:51:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:51:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:51:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:51:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:51:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xff31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11bca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x135fa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1363090>, '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 0x1363d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x135df90>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1505500579.446389-1924944135', '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 11:51:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 11:51:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 11:51:52 INFO - Using env: {'DISPLAY': ':0', 11:51:52 INFO - 'HOME': '/home/cltbld', 11:51:52 INFO - 'LANG': 'en_US.UTF-8', 11:51:52 INFO - 'LANGUAGE': 'en_US:en', 11:51:52 INFO - 'LOGNAME': 'cltbld', 11:51:52 INFO - 'MAIL': '/var/mail/cltbld', 11:51:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:51:52 INFO - 'MOZ_NO_REMOTE': '1', 11:51:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:51:52 INFO - 'NO_EM_RESTART': '1', 11:51:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:51:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:51:52 INFO - 'PWD': '/builds/slave/test', 11:51:52 INFO - 'SHELL': '/bin/bash', 11:51:52 INFO - 'SHLVL': '1', 11:51:52 INFO - 'TERM': 'linux', 11:51:52 INFO - 'TMOUT': '86400', 11:51:52 INFO - 'USER': 'cltbld', 11:51:52 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1505500579.446389-1924944135', 11:51:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:51:52 INFO - '_': '/tools/buildbot/bin/python'} 11:51:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:51:52 INFO - Downloading/unpacking mozsystemmonitor==0.3 11:51:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:51:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:51:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:51:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:51:53 INFO - Downloading mozsystemmonitor-0.3.tar.gz 11:51:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:51:53 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 11:51:53 INFO - Installing collected packages: mozsystemmonitor 11:51:53 INFO - Running setup.py install for mozsystemmonitor 11:51:54 INFO - Successfully installed mozsystemmonitor 11:51:54 INFO - Cleaning up... 11:51:55 INFO - Return code: 0 11:51:55 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 11:51:55 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')]} 11:51:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:51:55 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')]} 11:51:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:51:55 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 0xff31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11bca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x135fa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1363090>, '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 0x1363d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x135df90>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1505500579.446389-1924944135', '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 11:51:55 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 11:51:55 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 11:51:55 INFO - Using env: {'DISPLAY': ':0', 11:51:55 INFO - 'HOME': '/home/cltbld', 11:51:55 INFO - 'LANG': 'en_US.UTF-8', 11:51:55 INFO - 'LANGUAGE': 'en_US:en', 11:51:55 INFO - 'LOGNAME': 'cltbld', 11:51:55 INFO - 'MAIL': '/var/mail/cltbld', 11:51:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:51:55 INFO - 'MOZ_NO_REMOTE': '1', 11:51:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:51:55 INFO - 'NO_EM_RESTART': '1', 11:51:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:51:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:51:55 INFO - 'PWD': '/builds/slave/test', 11:51:55 INFO - 'SHELL': '/bin/bash', 11:51:55 INFO - 'SHLVL': '1', 11:51:55 INFO - 'TERM': 'linux', 11:51:55 INFO - 'TMOUT': '86400', 11:51:55 INFO - 'USER': 'cltbld', 11:51:55 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1505500579.446389-1924944135', 11:51:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:51:55 INFO - '_': '/tools/buildbot/bin/python'} 11:51:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:51:55 INFO - Downloading/unpacking jsonschema==2.5.1 11:51:55 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 11:51:55 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 11:51:55 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 11:51:55 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 11:51:56 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:51:56 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 11:51: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 11:51: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 11:51: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 11:51: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 11:51:56 INFO - Downloading functools32-3.2.3-2.tar.gz 11:51:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:51:56 INFO - warning: no files found matching '*.txt' 11:51:56 INFO - no previously-included directories found matching 'build' 11:51:56 INFO - no previously-included directories found matching 'dist' 11:51:56 INFO - no previously-included directories found matching '.git*' 11:51:56 INFO - Installing collected packages: jsonschema, functools32 11:51:56 INFO - Running setup.py install for functools32 11:51:57 INFO - warning: no files found matching '*.txt' 11:51:57 INFO - no previously-included directories found matching 'build' 11:51:57 INFO - no previously-included directories found matching 'dist' 11:51:57 INFO - no previously-included directories found matching '.git*' 11:51:57 INFO - Successfully installed jsonschema functools32 11:51:57 INFO - Cleaning up... 11:51:57 INFO - Return code: 0 11:51:57 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 11:51: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')]} 11:51:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:51: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')]} 11:51:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:51: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 0xff31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11bca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x135fa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1363090>, '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 0x1363d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x135df90>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1505500579.446389-1924944135', '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 11:51: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 11:51: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 11:51:57 INFO - Using env: {'DISPLAY': ':0', 11:51:57 INFO - 'HOME': '/home/cltbld', 11:51:57 INFO - 'LANG': 'en_US.UTF-8', 11:51:57 INFO - 'LANGUAGE': 'en_US:en', 11:51:57 INFO - 'LOGNAME': 'cltbld', 11:51:57 INFO - 'MAIL': '/var/mail/cltbld', 11:51:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:51:57 INFO - 'MOZ_NO_REMOTE': '1', 11:51:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:51:57 INFO - 'NO_EM_RESTART': '1', 11:51:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:51:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:51:57 INFO - 'PWD': '/builds/slave/test', 11:51:57 INFO - 'SHELL': '/bin/bash', 11:51:57 INFO - 'SHLVL': '1', 11:51:57 INFO - 'TERM': 'linux', 11:51:57 INFO - 'TMOUT': '86400', 11:51:57 INFO - 'USER': 'cltbld', 11:51:57 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1505500579.446389-1924944135', 11:51:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:51:57 INFO - '_': '/tools/buildbot/bin/python'} 11:51:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:51:58 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 11:51:58 INFO - Cleaning up... 11:51:58 INFO - Return code: 0 11:51:58 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:51:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:51:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:51:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:51:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:51: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 0xff31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11bca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x135fa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1363090>, '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 0x1363d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x135df90>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1505500579.446389-1924944135', '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 11:51: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 11:51: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 11:51:58 INFO - Using env: {'DISPLAY': ':0', 11:51:58 INFO - 'HOME': '/home/cltbld', 11:51:58 INFO - 'LANG': 'en_US.UTF-8', 11:51:58 INFO - 'LANGUAGE': 'en_US:en', 11:51:58 INFO - 'LOGNAME': 'cltbld', 11:51:58 INFO - 'MAIL': '/var/mail/cltbld', 11:51:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:51:58 INFO - 'MOZ_NO_REMOTE': '1', 11:51:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:51:58 INFO - 'NO_EM_RESTART': '1', 11:51:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:51:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:51:58 INFO - 'PWD': '/builds/slave/test', 11:51:58 INFO - 'SHELL': '/bin/bash', 11:51:58 INFO - 'SHLVL': '1', 11:51:58 INFO - 'TERM': 'linux', 11:51:58 INFO - 'TMOUT': '86400', 11:51:58 INFO - 'USER': 'cltbld', 11:51:58 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1505500579.446389-1924944135', 11:51:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:51:58 INFO - '_': '/tools/buildbot/bin/python'} 11:51:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:51:58 INFO - Downloading/unpacking blobuploader==1.2.4 11:51: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 11:51: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 11:51: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 11:51: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 11:51:59 INFO - Downloading blobuploader-1.2.4.tar.gz 11:51:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:51:59 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:51: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 11:51: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 11:51: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 11:51: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 11:51:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:51:59 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:51: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 11:51: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 11:51: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 11:51: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 11:52:00 INFO - Downloading docopt-0.6.1.tar.gz 11:52:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:52:00 INFO - Installing collected packages: blobuploader, requests, docopt 11:52:00 INFO - Running setup.py install for blobuploader 11:52:00 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:52:00 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:52:00 INFO - Running setup.py install for requests 11:52:00 INFO - Running setup.py install for docopt 11:52:00 INFO - Successfully installed blobuploader requests docopt 11:52:00 INFO - Cleaning up... 11:52:00 INFO - Return code: 0 11:52:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:52: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')]} 11:52:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:52: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')]} 11:52:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:52: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 0xff31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11bca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x135fa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1363090>, '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 0x1363d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x135df90>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1505500579.446389-1924944135', '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 11:52: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 11:52: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 11:52:00 INFO - Using env: {'DISPLAY': ':0', 11:52:00 INFO - 'HOME': '/home/cltbld', 11:52:00 INFO - 'LANG': 'en_US.UTF-8', 11:52:00 INFO - 'LANGUAGE': 'en_US:en', 11:52:00 INFO - 'LOGNAME': 'cltbld', 11:52:00 INFO - 'MAIL': '/var/mail/cltbld', 11:52:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:52:00 INFO - 'MOZ_NO_REMOTE': '1', 11:52:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:52:00 INFO - 'NO_EM_RESTART': '1', 11:52:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:52:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:52:00 INFO - 'PWD': '/builds/slave/test', 11:52:00 INFO - 'SHELL': '/bin/bash', 11:52:00 INFO - 'SHLVL': '1', 11:52:00 INFO - 'TERM': 'linux', 11:52:00 INFO - 'TMOUT': '86400', 11:52:00 INFO - 'USER': 'cltbld', 11:52:00 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1505500579.446389-1924944135', 11:52:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:52:00 INFO - '_': '/tools/buildbot/bin/python'} 11:52:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:52:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:52:00 INFO - Running setup.py (path:/tmp/pip-QldbSk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:52:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:52:00 INFO - Running setup.py (path:/tmp/pip-65RBjm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:52:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:52:01 INFO - Running setup.py (path:/tmp/pip-Z8tlmA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:52:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:52:01 INFO - Running setup.py (path:/tmp/pip-QE_MG8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:52:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:52:01 INFO - Running setup.py (path:/tmp/pip-nSBb28-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:52:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:52:01 INFO - Running setup.py (path:/tmp/pip-9STMie-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:52:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:52:01 INFO - Running setup.py (path:/tmp/pip-cQn1Kz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:52:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:52:01 INFO - Running setup.py (path:/tmp/pip-1g2zhx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:52:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:52:01 INFO - Running setup.py (path:/tmp/pip-_NyFny-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:52:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:52:01 INFO - Running setup.py (path:/tmp/pip-uZN9di-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:52:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:52:01 INFO - Running setup.py (path:/tmp/pip-TDCt65-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:52:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:52:01 INFO - Running setup.py (path:/tmp/pip-n1QiTo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:52:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:52:01 INFO - Running setup.py (path:/tmp/pip-gd3stF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:52:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:52:01 INFO - Running setup.py (path:/tmp/pip-793bU2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:52:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:52:01 INFO - Running setup.py (path:/tmp/pip-RWMYGb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:52:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:52:01 INFO - Running setup.py (path:/tmp/pip-P0LJnP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:52:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:52:02 INFO - Running setup.py (path:/tmp/pip-g_lh0x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:52:02 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:52:02 INFO - Running setup.py install for manifestparser 11:52:02 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:52:02 INFO - Running setup.py install for mozcrash 11:52:02 INFO - Running setup.py install for mozdebug 11:52:02 INFO - Running setup.py install for mozdevice 11:52:02 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:52:02 INFO - Running setup.py install for mozfile 11:52:02 INFO - Running setup.py install for mozhttpd 11:52:02 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:52:02 INFO - Running setup.py install for mozinfo 11:52:02 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:52:02 INFO - Running setup.py install for mozInstall 11:52:02 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:52:02 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:52:02 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:52:02 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:52:02 INFO - Running setup.py install for mozleak 11:52:02 INFO - Running setup.py install for mozlog 11:52:03 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:52:03 INFO - Running setup.py install for moznetwork 11:52:03 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:52:03 INFO - Running setup.py install for mozprocess 11:52:03 INFO - Running setup.py install for mozprofile 11:52:03 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:52:03 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:52:03 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:52:03 INFO - Running setup.py install for mozrunner 11:52:03 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:52:03 INFO - Running setup.py install for mozscreenshot 11:52:03 INFO - Running setup.py install for moztest 11:52:03 INFO - Running setup.py install for mozversion 11:52:03 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:52:03 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:52:03 INFO - Cleaning up... 11:52:03 INFO - Return code: 0 11:52:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:52: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')]} 11:52:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:52: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')]} 11:52:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:52: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 0xff31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11bca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x135fa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1363090>, '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 0x1363d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x135df90>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1505500579.446389-1924944135', '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 11:52: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 11:52: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 11:52:03 INFO - Using env: {'DISPLAY': ':0', 11:52:03 INFO - 'HOME': '/home/cltbld', 11:52:03 INFO - 'LANG': 'en_US.UTF-8', 11:52:03 INFO - 'LANGUAGE': 'en_US:en', 11:52:03 INFO - 'LOGNAME': 'cltbld', 11:52:03 INFO - 'MAIL': '/var/mail/cltbld', 11:52:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:52:03 INFO - 'MOZ_NO_REMOTE': '1', 11:52:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:52:03 INFO - 'NO_EM_RESTART': '1', 11:52:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:52:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:52:03 INFO - 'PWD': '/builds/slave/test', 11:52:03 INFO - 'SHELL': '/bin/bash', 11:52:03 INFO - 'SHLVL': '1', 11:52:03 INFO - 'TERM': 'linux', 11:52:03 INFO - 'TMOUT': '86400', 11:52:03 INFO - 'USER': 'cltbld', 11:52:03 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1505500579.446389-1924944135', 11:52:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:52:03 INFO - '_': '/tools/buildbot/bin/python'} 11:52:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:52:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:52:04 INFO - Running setup.py (path:/tmp/pip-XWBs3b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:52: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)) 11:52:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:52:04 INFO - Running setup.py (path:/tmp/pip-9MoMeA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:52: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)) 11:52:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:52:04 INFO - Running setup.py (path:/tmp/pip-VU4lWu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:52: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)) 11:52:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:52:04 INFO - Running setup.py (path:/tmp/pip-XeOcuu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:52: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)) 11:52:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:52:04 INFO - Running setup.py (path:/tmp/pip-ZqfAbx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:52: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)) 11:52:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:52:04 INFO - Running setup.py (path:/tmp/pip-RjUoZm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:52: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)) 11:52:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:52:04 INFO - Running setup.py (path:/tmp/pip-USRXrb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:52: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)) 11:52:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:52:04 INFO - Running setup.py (path:/tmp/pip-cXtPsd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:52: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)) 11:52:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:52:04 INFO - Running setup.py (path:/tmp/pip-w18Hvn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:52: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)) 11:52:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:52:04 INFO - Running setup.py (path:/tmp/pip-AWKZar-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:52: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)) 11:52:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:52:04 INFO - Running setup.py (path:/tmp/pip-3kaoKn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:52: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)) 11:52:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:52:04 INFO - Running setup.py (path:/tmp/pip-vGb8JU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:52: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)) 11:52:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:52:04 INFO - Running setup.py (path:/tmp/pip-_y1lkw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:52:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 11:52:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:52:05 INFO - Running setup.py (path:/tmp/pip-NYLjTt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:52:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 11:52:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:52:05 INFO - Running setup.py (path:/tmp/pip-aQv1vr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:52: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)) 11:52:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:52:05 INFO - Running setup.py (path:/tmp/pip-XF84Jy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:52: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)) 11:52:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:52:05 INFO - Running setup.py (path:/tmp/pip-qs4kBR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:52: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)) 11:52: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)) 11:52: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)) 11:52: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)) 11:52: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)) 11:52: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)) 11:52: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)) 11:52:05 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:52: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 11:52: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 11:52: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 11:52: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 11:52:06 INFO - Downloading blessings-1.6.tar.gz 11:52:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:52:06 INFO - Installing collected packages: blessings 11:52:06 INFO - Running setup.py install for blessings 11:52:06 INFO - Successfully installed blessings 11:52:06 INFO - Cleaning up... 11:52:06 INFO - Return code: 0 11:52:06 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:52:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:52:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:52:06 INFO - Reading from file tmpfile_stdout 11:52:06 INFO - Current package versions: 11:52:06 INFO - argparse == 1.2.1 11:52:06 INFO - blessings == 1.6 11:52:06 INFO - blobuploader == 1.2.4 11:52:06 INFO - docopt == 0.6.1 11:52:06 INFO - functools32 == 3.2.3-2 11:52:06 INFO - jsonschema == 2.5.1 11:52:06 INFO - manifestparser == 1.1 11:52:06 INFO - mozInstall == 1.13 11:52:06 INFO - mozcrash == 1.0 11:52:06 INFO - mozdebug == 0.1 11:52:06 INFO - mozdevice == 0.51 11:52:06 INFO - mozfile == 1.2 11:52:06 INFO - mozhttpd == 0.7 11:52:06 INFO - mozinfo == 0.10 11:52:06 INFO - mozleak == 0.1 11:52:06 INFO - mozlog == 3.5 11:52:06 INFO - moznetwork == 0.27 11:52:06 INFO - mozprocess == 0.25 11:52:06 INFO - mozprofile == 0.28 11:52:06 INFO - mozrunner == 6.13 11:52:06 INFO - mozscreenshot == 0.1 11:52:06 INFO - mozsystemmonitor == 0.3 11:52:06 INFO - moztest == 0.8 11:52:06 INFO - mozversion == 1.4 11:52:06 INFO - psutil == 3.1.1 11:52:06 INFO - requests == 1.2.3 11:52:06 INFO - wsgiref == 0.1.2 11:52:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:52: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')]} 11:52:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:52: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')]} 11:52:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:52: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 0xff31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11bca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x135fa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1363090>, '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 0x1363d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x135df90>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1505500579.446389-1924944135', '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 11:52: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 11:52: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 11:52:06 INFO - Using env: {'DISPLAY': ':0', 11:52:06 INFO - 'HOME': '/home/cltbld', 11:52:06 INFO - 'LANG': 'en_US.UTF-8', 11:52:06 INFO - 'LANGUAGE': 'en_US:en', 11:52:06 INFO - 'LOGNAME': 'cltbld', 11:52:06 INFO - 'MAIL': '/var/mail/cltbld', 11:52:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:52:06 INFO - 'MOZ_NO_REMOTE': '1', 11:52:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:52:06 INFO - 'NO_EM_RESTART': '1', 11:52:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:52:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:52:06 INFO - 'PWD': '/builds/slave/test', 11:52:06 INFO - 'SHELL': '/bin/bash', 11:52:06 INFO - 'SHLVL': '1', 11:52:06 INFO - 'TERM': 'linux', 11:52:06 INFO - 'TMOUT': '86400', 11:52:06 INFO - 'USER': 'cltbld', 11:52:06 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1505500579.446389-1924944135', 11:52:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:52:06 INFO - '_': '/tools/buildbot/bin/python'} 11:52:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:52:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 11:52:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 11:52:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 11:52:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 11:52:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 11:52:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 11:52:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 11:52:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 11:52:07 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 11:52:07 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 11:52:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:52:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:52:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:52:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:52:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 11:52: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)) 11:52: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)) 11:52:08 INFO - Installing collected packages: simplejson 11:52:08 INFO - Running setup.py install for simplejson 11:52:08 INFO - building 'simplejson._speedups' extension 11:52: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 11:52: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 11:52:09 INFO - Successfully installed simplejson 11:52:09 INFO - Cleaning up... 11:52:09 INFO - Return code: 0 11:52:09 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:52: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')]} 11:52:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:52: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')]} 11:52:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:52: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 0xff31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11bca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x135fa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1363090>, '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 0x1363d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x135df90>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1505500579.446389-1924944135', '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 11:52: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 11:52: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 11:52:09 INFO - Using env: {'DISPLAY': ':0', 11:52:09 INFO - 'HOME': '/home/cltbld', 11:52:09 INFO - 'LANG': 'en_US.UTF-8', 11:52:09 INFO - 'LANGUAGE': 'en_US:en', 11:52:09 INFO - 'LOGNAME': 'cltbld', 11:52:09 INFO - 'MAIL': '/var/mail/cltbld', 11:52:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:52:09 INFO - 'MOZ_NO_REMOTE': '1', 11:52:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:52:09 INFO - 'NO_EM_RESTART': '1', 11:52:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:52:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:52:09 INFO - 'PWD': '/builds/slave/test', 11:52:09 INFO - 'SHELL': '/bin/bash', 11:52:09 INFO - 'SHLVL': '1', 11:52:09 INFO - 'TERM': 'linux', 11:52:09 INFO - 'TMOUT': '86400', 11:52:09 INFO - 'USER': 'cltbld', 11:52:09 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1505500579.446389-1924944135', 11:52:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:52:09 INFO - '_': '/tools/buildbot/bin/python'} 11:52:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:52:09 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 11:52:09 INFO - Cleaning up... 11:52:09 INFO - Return code: 0 11:52:09 INFO - Running post-action listener: _resource_record_post_action 11:52:09 INFO - Running post-action listener: _start_resource_monitoring 11:52:09 INFO - Starting resource monitoring. 11:52:09 INFO - [mozharness: 2017-09-15 18:52:09.540106Z] Finished create-virtualenv step (success) 11:52:09 INFO - [mozharness: 2017-09-15 18:52:09.540566Z] Running install step. 11:52:09 INFO - Running pre-action listener: _resource_record_pre_action 11:52:09 INFO - Running main action method: install 11:52:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:52:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:52:09 INFO - Reading from file tmpfile_stdout 11:52:09 INFO - Detecting whether we're running mozinstall >=1.0... 11:52:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:52:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:52:09 INFO - Reading from file tmpfile_stdout 11:52:09 INFO - Output received: 11:52:09 INFO - Usage: mozinstall [options] installer 11:52:09 INFO - Options: 11:52:09 INFO - -h, --help show this help message and exit 11:52:09 INFO - -d DEST, --destination=DEST 11:52:09 INFO - Directory to install application into. [default: 11:52:09 INFO - "/builds/slave/test"] 11:52:09 INFO - --app=APP Application being installed. [default: firefox] 11:52:09 INFO - mkdir: /builds/slave/test/build/application 11:52: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'] 11:52:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 11:52:27 INFO - Reading from file tmpfile_stdout 11:52:27 INFO - Output received: 11:52:27 INFO - /builds/slave/test/build/application/firefox/firefox 11:52:27 INFO - Running post-action listener: _resource_record_post_action 11:52:27 INFO - [mozharness: 2017-09-15 18:52:27.503412Z] Finished install step (success) 11:52:27 INFO - [mozharness: 2017-09-15 18:52:27.503662Z] Running setup-mitmproxy step. 11:52:27 INFO - Running pre-action listener: _resource_record_pre_action 11:52:27 INFO - Running main action method: setup_mitmproxy 11:52:27 INFO - Skipping: mitmproxy is not required 11:52:27 INFO - Running post-action listener: _resource_record_post_action 11:52:27 INFO - [mozharness: 2017-09-15 18:52:27.504784Z] Finished setup-mitmproxy step (success) 11:52:27 INFO - [mozharness: 2017-09-15 18:52:27.505001Z] Running run-tests step. 11:52:27 INFO - Running pre-action listener: _resource_record_pre_action 11:52:27 INFO - Running pre-action listener: _set_gcov_prefix 11:52:27 INFO - Running main action method: run_tests 11:52:27 WARNING - Try message not found. 11:52:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:52:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:52:27 INFO - Python 2.7.3 11:52:27 INFO - Return code: 0 11:52:27 INFO - grabbing minidump binary from tooltool 11:52: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')]} 11:52: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 0x1363090>, '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 0x1363d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x135df90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:52: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 11:52: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 11:52: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 11:52:27 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:52:27 INFO - Return code: 0 11:52:27 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 11:52:27 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:52:27 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:52:27 INFO - ENV: RUST_BACKTRACE is now full 11:52:27 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 11:52:27 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:52: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/X6FlnGhmTjqdwUMlw8Fc9A/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-018', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 11:52: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/X6FlnGhmTjqdwUMlw8Fc9A/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-018 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 11:52:27 INFO - Using env: {'DISPLAY': ':0', 11:52:27 INFO - 'HOME': '/home/cltbld', 11:52:27 INFO - 'LANG': 'en_US.UTF-8', 11:52:27 INFO - 'LANGUAGE': 'en_US:en', 11:52:27 INFO - 'LOGNAME': 'cltbld', 11:52:27 INFO - 'MAIL': '/var/mail/cltbld', 11:52:27 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:52:27 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 11:52:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:52:27 INFO - 'MOZ_NO_REMOTE': '1', 11:52:27 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:52:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:52:27 INFO - 'NO_EM_RESTART': '1', 11:52:27 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:52:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:52:27 INFO - 'PWD': '/builds/slave/test', 11:52:27 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:52:27 INFO - 'RUST_BACKTRACE': 'full', 11:52:27 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 11:52:27 INFO - 'SHELL': '/bin/bash', 11:52:27 INFO - 'SHLVL': '1', 11:52:27 INFO - 'TERM': 'linux', 11:52:27 INFO - 'TMOUT': '86400', 11:52:27 INFO - 'USER': 'cltbld', 11:52:27 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1505500579.446389-1924944135', 11:52:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:52:27 INFO - '_': '/tools/buildbot/bin/python'} 11:52: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/X6FlnGhmTjqdwUMlw8Fc9A/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-018', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 11:52:27 INFO - mozversion application_buildid: 20170915173306 11:52:27 INFO - mozversion application_changeset: 9071a20f3eaa804a439990b7596dbc16d52d1e20 11:52:27 INFO - mozversion application_display_name: Nightly 11:52:27 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:52:27 INFO - mozversion application_name: Firefox 11:52:27 INFO - mozversion application_remotingname: firefox 11:52:27 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 11:52:27 INFO - mozversion application_vendor: Mozilla 11:52:27 INFO - mozversion application_version: 57.0a1 11:52:27 INFO - mozversion platform_buildid: 20170915173306 11:52:27 INFO - mozversion platform_changeset: 9071a20f3eaa804a439990b7596dbc16d52d1e20 11:52:27 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 11:52:27 INFO - mozversion platform_version: 57.0a1 11:52:27 INFO - using testdate: 1505501547 11:52:27 INFO - actual date: 1505501547 11:52:27 INFO - starting webserver on 'localhost:42105' 11:52:27 INFO - SUITE-START | Running 2 tests 11:52:27 INFO - TEST-START | dromaeo_css 11:52:27 INFO - Initialising browser for dromaeo_css test... 11:52:27 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:42105/getInfo.html -profile /tmp/tmpFIfilr/profile 11:52:27 INFO - TEST-INFO | started process 3564 (/builds/slave/test/build/application/firefox/firefox http://localhost:42105/getInfo.html) 11:52:34 INFO - TEST-INFO | 3564: exit 0 11:52:34 INFO - Browser initialized. 11:52:34 INFO - Running cycle 1/1 for dromaeo_css test... 11:52:34 INFO - Using env: {'DISPLAY': ':0', 11:52:34 INFO - 'HOME': '/home/cltbld', 11:52:34 INFO - 'JSGC_DISABLE_POISONING': '1', 11:52:34 INFO - 'LANG': 'en_US.UTF-8', 11:52:34 INFO - 'LANGUAGE': 'en_US:en', 11:52:34 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 11:52:34 INFO - 'LOGNAME': 'cltbld', 11:52:34 INFO - 'MAIL': '/var/mail/cltbld', 11:52:34 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:52:34 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 11:52:34 INFO - 'MOZ_CRASHREPORTER': '1', 11:52:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:52:34 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 11:52:34 INFO - 'MOZ_NO_REMOTE': '1', 11:52:34 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:52:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:52:34 INFO - 'NO_EM_RESTART': '1', 11:52:34 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:52:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:52:34 INFO - 'PWD': '/builds/slave/test', 11:52:34 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:52:34 INFO - 'RUST_BACKTRACE': 'full', 11:52:34 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 11:52:34 INFO - 'SHELL': '/bin/bash', 11:52:34 INFO - 'SHLVL': '1', 11:52:34 INFO - 'TERM': 'linux', 11:52:34 INFO - 'TMOUT': '86400', 11:52:34 INFO - 'USER': 'cltbld', 11:52:34 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1505500579.446389-1924944135', 11:52:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:52:34 INFO - '_': '/tools/buildbot/bin/python'} 11:52:34 INFO - TEST-INFO | started process 3797 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpFIfilr/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 11:52:35 INFO - PID 3797 | 11:52:35 INFO - PID 3797 | (/builds/slave/test/build/application/firefox/firefox:3848): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 11:52:35 INFO - PID 3797 | 11:52:45 INFO - PID 3797 | RSS: Main: 166744064 11:52:45 INFO - PID 3797 | 11:55:42 INFO - PID 3797 | Cycle 1(1): loaded http://localhost:42105/tests/dromaeo/cssquery-dojo.html (next: http://localhost:42105/tests/dromaeo/cssquery-ext.html) 11:55:44 INFO - PID 3797 | RSS: Main: 170344448 11:55:44 INFO - PID 3797 | 11:58:45 INFO - PID 3797 | Cycle 1(1): loaded http://localhost:42105/tests/dromaeo/cssquery-ext.html (next: http://localhost:42105/tests/dromaeo/cssquery-jquery.html) 11:58:46 INFO - PID 3797 | RSS: Main: 166047744 11:58:46 INFO - PID 3797 | 12:01:47 INFO - PID 3797 | Cycle 1(1): loaded http://localhost:42105/tests/dromaeo/cssquery-jquery.html (next: http://localhost:42105/tests/dromaeo/cssquery-mootools.html) 12:01:48 INFO - PID 3797 | RSS: Main: 166240256 12:01:48 INFO - PID 3797 | 12:04:49 INFO - PID 3797 | Cycle 1(1): loaded http://localhost:42105/tests/dromaeo/cssquery-mootools.html (next: http://localhost:42105/tests/dromaeo/cssquery-prototype.html) 12:04:50 INFO - PID 3797 | RSS: Main: 167141376 12:04:50 INFO - PID 3797 | 12:07:53 INFO - PID 3797 | Cycle 1(1): loaded http://localhost:42105/tests/dromaeo/cssquery-prototype.html (next: http://localhost:42105/tests/dromaeo/cssquery-yui.html) 12:07:54 INFO - PID 3797 | RSS: Main: 168411136 12:07:54 INFO - PID 3797 | 12:10:55 INFO - PID 3797 | Cycle 1(1): loaded http://localhost:42105/tests/dromaeo/cssquery-yui.html (next: http://localhost:42105/tests/dromaeo/cssquery-dojo.html) 12:10:56 INFO - PID 3797 | RSS: Main: 171237376 12:10:56 INFO - PID 3797 | 12:10:56 INFO - PID 3797 | __start_tp_report 12:10:56 INFO - PID 3797 | _x_x_mozilla_page_load 12:10:56 INFO - PID 3797 | _x_x_mozilla_page_load_details 12:10:56 INFO - PID 3797 | |i|pagename|runs| 12:10:56 INFO - PID 3797 | |0;dojo.html;3797.335466293398;3818.121831978646;3850.5764365919727;3853.479780229668;3857;6086.908696369553;6133.11069894865;6182.9381706182985;6211.192544969148;6232.59488133271;6122.540809439299;6175.382461753822;6236.750529978806;6316.621002739839;6329.525285603575;5430.782768689248;5512.035393806088;5519.586031047667;5536.446355364472;5545.390007099216;448.35427887492517;5218.19118036703;5485.890282194354;5542.140968149923;5566.276384070065;5438.7280635968245;5463.48096930791;5530.99999999998;5538.307711536058;5550.47270509301;5479.808206712786;5495.148252020943;5557.722113894289;5558.666480011212;5561.555075593943;5475.561955043587;5484.725763711798;5561;5562.332520097574;5570.526505247059;6041.697915104227;6101;6148.907766383508;6167.414095660905;6172.629642221481;6656.93343066568;6734.292899245557;6754.9662251688915;6762.898556521607;6763.323667633246;6685.298043705438;6686.899696504556;6794.796156115324;6801.421879140279;6807.183138023469;6194.752209911598;6219.098230756515;6224.906626400562;6232.127502149703;6232.3144454110015;6807.965960170168;6918.169819621577;6923.792286231421;6930.653467326613;6947.548409353376;6585;6610.636414997125;6671.5997040177745;6691.431228345645;6717.059611654372;6705.228898676617;6710.66446677674;6718.865622687519;6792.320767923267;6807.217170025411;6606.075149479077;6615.536912416084;6630.237522684856;6630.53586248958;6635.236947750974;6631.568948018364;6635.502337324623;6639.734410623618;6641.236257830315;6662.467002639777;4892.0705066037335;4913.729744863996;4917.754112294371;4952.059108769322;5000;4463.866084017484;4497.370368148462;4539.024109816405;4557.043020965568;4568.565986231302;6204.968975155186;6214.8135555933395;6271.310155882849;6300.086487459384;6306.274778400542;6544.607323560602;6625.337466253336;6638.20341559016;6732.831679208059;6754.223264324665;6252.906206406908;6275.184226050585;6278.403551662676;6294.402031806971;6295.307516173218;5430.755615997289;5499.175123731472;5505.697186654692;5509.3664228613425;5511.917321240185;6771.796846094625;6927.411170050602;6955.234924158339;7037.788866334018;7047.753328633576;6595.604263744095;6680.565763225375;6686.732530698718;6708.429783468362;6709;6873.759418420332;6881.346272104142;6905.482088843256;6910.170779506491;6911.17065952089;6512.934870651422;6537.0521274413995;6566.376194261537;6587.505937054836;6623.205215374186;6587.835304117435;6605.405513503808;6625.933740662532;6705.061291419108;6705.0612914193025;6425.775097871552;6486.870262594627;6509.7396104155305;6531.11829902957;6535.673216339069;6257.2491301044165;6438.484921206198;6512.120863683532;6540.051692504465;6545.639989800607;6344.206974128234;6396.32838551964;6476.902846457213;6480.643564603992;6484.513661475314;6387.393197646216;6437.935620643733;6479.611223326616;6494.480441564758;6514.804555863332;6284.277308109443;6328.145700330397;6365.299817020032;6375.808725738235;6394.616323020447 12:10:56 INFO - PID 3797 | |1;ext.html;928.4150984879525;931.3945935142157;960.2701946520647;969.2197780786466;976.326334828968;44646.55353446465;44729.77635111828;45097.32354014692;45274.773626131864;45336.54663453368;41518.37722434166;41910.371344429856;41979.95050123749;42079;42287.365689514685;7118.359347658723;7318.268173182692;7357.0803649543805;7362.226966168556;7366.852662946738;7280.963595182016;7325.157606875197;7374.115106187265;7383.040204773399;7401.666924988388;7966.800829979239;8241.052278987903;8249.298809601161;8249.711260105898;8250.298724608416;8164.183581641818;8176.836463270702;8220.219079187467;8227.259546640802;8228.465149765276;7646.9617651911385;7814.882776758353;8011.879821802678;8023.237792409712;8040.638171282307;41014.589854101374;41057.794711026254;41200.38199426981;41405;41459.37810932838;3759.6804271636943;3794.90512737181;3801.391777315644;3816.809159542012;3821.464994900716;34434.6556534434;34579.13552161191;34609.653903460894;34653.57424980252;34668.30663386718;5442.537384322337;5456.727163641802;5515.696636684981;5522.972385138089;5548.528375088123;223806;224469.99999999837;225040;225173;225755;48314;49160.26259606109;49518.75240623846;54567.45432545624;55376;57612;57789.422105779246;57850;58062.709686452145;58208.41791582115;16745.83254167467;16954.830451695325;16969.15154242283;16976.915115424345;17019.149042547822;13738.725225495435;13999;14000.719985600232;14006.43974241019;14018.158910465405;15652.92173539125;15721.921390392974;15725.135117568643;15743.212839357986;15790.36838526446;6065.543454900772;6069.878602427927;6116.348585881876;6144.815655530341;6153;1066.4241309692834;1071.4267866691282;1079.9190060745475;1083.1713738989679;1098.6758906122714;24607.63088553673;24917.127900523396;25071.247862564152;25223.87388063048;25282.747172528037;1177.9234349767237;1202.6151631477844;1210.3706072842074;1213.3084142038954;1215.4751221457843;13232.40454179545;13306.600801975956;13309.93345033288;13509.189448633113;13611.591652250449;271130;272317;272722;273493;274379;54888;55012.174817377774;55190.448095519336;55328.72335638296;55391.169132463045;1473.6021274256104;1498.3332417074516;1506.992465037668;1527.045596502186;1532.0501289200768;24242.87878560596;24402.877985609957;24411.26766197017;24592.631110533;24773.25680229596;41202.587974121074;41255.7937210312;41477.1704565913;41585.792071039454;41621.167576648906;224828;225044;227043;227280;227621;35727;37066.44400334051;37202.44196337003;37426;37470.812645936596;44697.10605787931;45857;46356;46623.76688116538;46722.29916551187;33636.66363336335;34210.31579368449;34276;34417.82791086029;34708.65291347054;34248.97253082412;34404;34435.483467748505;34551;34589.65410345864;40102;40139;40234;40445.39331909965;40530.39204411877;32852.342953141284;32976;33030.669693302756;33278.168045798084;33283.167920802174;24795;24883;25350.112746054536;25366.873165634053;25818.22545323643 12:10:56 INFO - PID 3797 | |2;jquery.html;570.6376450953643;574.5662025170996;587.4389957590502;594.5808205215322;596.3172167867791;23024.88487557562;23722;23753;23761.643575346385;23770;22579.209727659534;23092;23124;24915.875420622917;25119.246422607306;6412.647304398256;6418.422341989209;6448;6448.677566121699;6458.644774537399;6447.387498187688;6500.6099634021875;6527.0209468579615;6539.149910511627;6749.561278516905;6697.062411262427;6704.061431399608;6713.060171575983;6718.59688418694;6728.764493242737;6704.2625311215725;6709.563878347892;6722.29415911332;6729.192496900403;6735.730570777163;5774.364819869811;5980.162777211194;5998.790042348497;6029.668368239745;6033.276006879202;29165.854170729224;29639;29649.555256671167;29688.40623187524;29758.85120574383;2253.2001139595372;2281.041962375806;2281.1673739085113;2282.897269622871;2287.2109122352763;19538.902305488522;19913;20302.492437689027;20420.591588168303;20559;2486.0552989863736;2497.3506888209195;2519.143491212978;2543.58030924899;2549.5028469448466;137190;142131.28934355263;151599;157477;158687;14958.009981968735;15428;15461.768073478683;15630.296636651377;15953.8462307587;24295.39261518441;24339.634905476432;24478.632820507708;24514.264572062868;24578.262652120466;10232.821526677339;10339.172866170687;10524.736881577868;10581;10613.84079238812;9480.33637645372;9484.19384352324;9548.522573871278;9555;9610;9364.484953327632;9421.90578094224;9441.952790236004;9571.234301255883;9571.90428095724;2794.3852352482827;2807.5367564352064;2808.9157325280275;2853.885844566236;2881.6109825173753;1027.4503140819625;1037.906588407047;1042.8122937871256;1045.994770026145;1063.1229235880276;20301.751719724773;20431.591368172554;20477.897610511853;20752.48118797042;20910.686339704916;1094.4910616563336;1106.25880659958;1116.1015382616908;1120.3613940054254;1126.8704099028716;11323.433828308716;11343.376114313793;11369.147313951487;11383.658490245305;11385.544578216945;604678;611202;613885;628417;634997;126452.36773816073;130888;132983;133441.3327933354;134762.32618837038;1202.1765090912577;1234.962951111468;1250.5560526013317;1255.1527718789962;1266.4554241676167;18107.909460453142;18212.18045187996;18354.72467912956;18404.631907362047;18497.260109595467;60434;60937;62465;62672.68663656653;63009.369906302185;77963;78356.60821696083;79101.60449197948;79111.60444197743;79469;14551.126932384092;14869.851301486846;14876.18181000012;14888.106713597219;14997.625059373166;23071.423214419774;23211;23239.651405229273;23262.76737232606;23586.410339741644;18759.812401875806;19073.237070517025;19302.22791088341;19307.8069219306;19321.710174347678;16922.661546769243;17098.230579624196;17241.482755517354;17332.480025599252;17867;18439.53901152482;19418.90290548538;19583.216671332986;19649.60700786005;19743.308984185485;14809.851901480848;14847.777283340974;14853.702925941638;14870.553883383516;14912.179830109448;18721.438356849318;18798;18963.525911852314;19037.333693320666;19217.711734324275 12:10:56 INFO - PID 3797 | |3;mootools.html;1377.6624726941898;1470.595586213791;1480.1785009319824;1493.8954273200873;1502.9473968411107;16211.513654590352;16779.832201677997;16822.411215607455;16840.57898552534;16840.83159168408;16414.62853944488;16465.25906334215;16502.42241521547;16503.752443713354;16505.33978640856;2801.131649188748;2806.556593915144;2844.559093340525;2845.8861645534153;2847.1173936079776;2736.58951157327;2815.2539577012108;2824.096289187461;2838.574213867926;2842.6730925943575;2642.207337798662;2719.687235967869;2832.136198459469;2842.3462603601183;2843.218115018366;2764.2812868654296;2785.679646840619;2811.831290122599;2815.127310533741;2815.5776633505034;2647.099986004748;2658.734126587345;2664.387190946074;2675.4247836715003;2681.1956413076;15935.760963585439;16761.497155085366;16799.328026879033;16822.66354672912;16823.831761682348;8068.677252909876;8280.089190189074;8306.252437280673;8364.874526882102;8369.916300837036;5112.514311140436;5332;5380.650257733274;5491.890162196733;5499.340079190522;2066.359428577131;2082.5106100066473;2089.3523007867457;2104.0298277753823;2141.8286537076997;30835.383292334027;30943.845280773676;30964.535531967038;31065.378692426024;31066.689333106606;6891.483138764613;6945.062416573799;7025.578465292099;7129.144502659714;7154.678039488235;19049.61900761977;19068.14193361302;19103.044847757556;19120;19123.71314430285;8187.4678145920325;8331.708390206313;8343.916560834436;8344.958275208584;8363.372747043995;7295.452841036943;7463.8507229855095;7473.962630186923;7557.886631700529;7740.690372385154;5532.5297349724915;5596.552275817925;5768.451997060272;5860.912086318709;5894.115882617642;1474.4470823441209;1506.0737646347418;1593.2272847668871;1642.0295645225776;1645.3994292083544;929.846575315079;957.913787759105;967.2552134856121;974.1232890398556;979.8726165598426;9446.338756286994;9691.51542422876;9711.854322185172;9763.853542196874;9847.655332063487;809.4819315637878;823.197382552019;1035.9896401035894;1056.6565866093551;1058.169337070396;5775.971120144372;5935.198748168942;5990.400959903975;6052.092186172022;6058.545609079337;23927.401814954766;24564.754352456603;24921.127760528296;25121.371965700637;25245.495090098095;24244.39389015289;24334.26997190087;24703.50592988166;24743.38141546476;24793.380165495648;12169.452374643162;12657.493700252071;12671.049671274692;12672.176308539916;12680.112392132647;9198.172164505228;9209.953950230207;9296.860547091665;9311;9318.627254909728;20795.376138715863;20943.685844712036;21078.47303817417;21180.894095529424;21560.568788624452;21728.456788580414;21972;21974.780252197274;21981.560368792856;22012.779872201714;11874.703132421759;12011.219270747373;12016.158868879094;12018.158728888893;12051.638450846489;15020.248987550798;15441.0735355879;15465.613359666098;15530.844691553391;15540.61148471252;12013.339266340436;12241.20432171906;12367.505299787908;12368.690782730504;12568.245905245714;12043.939780301042;12122.818157727464;12207.816882746589;12290.324032178316;12482.75034499323;10781.353118812896;10867.836982445428;10884.564617415532;11015.83476247841;11024;7963.601819909098;8037.196280371916;8067.112617612175;8074.232947869943;8084.231997960184;8274.544900030556;8293.377996650155;8294.543800091053;8316.251537361666;8429.957850210954 12:10:56 INFO - PID 3797 | |4;prototype.html;506.74156180348035;509.0302972836745;520.997395013025;525.9421463639002;537.433008176374;5980.641161530311;6166.132748272053;6190.907136392958;6228.346023667517;6241.65670888101;5925.4963328117055;6074.210352654149;6096.055111457731;6111.480524155452;6133.846653833656;4701.4593321767925;4812.422509298889;4848.806047758086;4876.878078048042;4881.194602890519;4826.565609095181;4830.063498248934;4863.8540843774745;4882.584980276663;4900.485449027854;4785.66500344976;4810.711357318554;4826.686265392738;4830.758462076899;4843.539863712942;4839.54024367688;4839.782209800567;4850.854474365775;4856.805727770885;4903.338049363361;4410.514843367227;4411.338299255138;4413.867583972486;4424.336349547561;4432.315720026401;5931.881362372772;6020.2775666919815;6069.514438844882;6090.604110732788;6137.754489820401;5588.245586845765;5628.605997580171;5641.8589535261535;5688.402717714622;5695.800646977336;6493.70778314977;6514.804555863284;6533.281339052701;6557.458997135669;6560.40956313934;4816.7832447539595;4864.173090574611;4878.414515282512;4894.265860120975;4909.435414927293;6667.533272670915;6783.898241526381;6832.59004459729;6851.794446166673;6852.5203235773515;4988.152014157602;5020.824271150492;5041.319421878073;5047.4952504749235;5054.09026375256;5085.355615080138;5096.566791822663;5097.2716014860025;5103.77033033507;5111.309973153651;5613.270274864241;5629.493345598916;5633.408492108351;5678.659280443187;5693.316801983706;6307.463865571386;6322.755421825278;6412.134361861183;6465.579737317059;6530.314316996742;1728.1963886792703;1743.6599863026843;1748.5453782016766;1749.4052022312476;1756.9648607027789;1210.2254557083475;1223.7613665335352;1226.9877301227052;1228.9139760216879;1247.875212478745;6080.439670247314;6081.908771368433;6112.2970858351855;6117;6160.507159427235;6632.83417914556;6633.611899716098;6656.933430665728;6668.233153187349;6680.966595166993;5955.851103722355;6012.248468941382;6087.299960504599;6096.72564734588;6117.510599152057;3473.426884564069;3476;3498.3003399319728;3513.2270900401872;3528.970841765995;6827.590344579341;6842.828929276709;6858.417034552119;6862.289395688675;6988.860222795515;6554.623529058951;6653.667316634149;6724.66376681164;6750.696218670171;6806.659667016629;6744.831379215559;6759.493038022168;6783.592984420852;6789.898151527731;6810.8297292567095;6120.479759220516;6460.25707043696;6518.348165183634;6579.243387010555;6680.298568650416;6417.743290268337;6452.419282264619;6468.514861385321;6493.155889734304;6534.8366290843105;6803.897941530782;6821.147356580427;6857.314268573103;6911.447084233349;6919.238883722685;4956.274049272957;4968.975155124202;5005.77474013663;5020.774065167149;5023.874403139951;6501.609903405811;6631.6684165792485;6633.2040155181685;6744.527883048336;6769.729210831511;6268.843278917881;6376.266729326187;6387.648679322496;6412.39082287182;6430.3891130342545;6379.553431259767;6402;6463.806085817433;6480.546361754632;6509;6254.874902502015;6280.717367718372;6380.776672816429;6428.646424446701;6439.291677915519;6437.163168788027;6486.740530378921;6549.508786840911;6550.639714815731;6563.7702680405955;6303.716332764945;6350.571121497626;6367.840803979938;6378.266499352634;6402.487800975818 12:10:56 INFO - PID 3797 | |5;yui.html;948.729612060562;987.629638885418;990.4305024610849;994.4282037828247;1023.0025724918204;19390.806091939077;19392.22431102754;19455.805441945577;19489.707654385194;20126.798732012674;19315.323963661274;19331.806681933176;19364.322248721262;20315.593688126264;20422.489437764063;1039.8908114647966;1061.5488417422603;1061.766411389493;1062.1184416933927;1065.1532032034552;1018.0684673523717;1076.9983914959084;1080.027974822662;1080.2654195147297;1080.7406222506581;1143.4054291768275;1153.6827372472553;1154.7344110854508;1157.5138441854442;1157.6411312493112;1145.5417832866838;1145.9828102578467;1148.2306854407566;1149.6263714292847;1150.384544268812;782.6869252299069;800.8998875140608;810.9229623185788;811.1401913971209;811.2455416462687;18269.269229230817;18290.725639115422;18306.35927742536;18380.356687515872;18394.540136496562;23444.413889652897;23546.175883844153;23841.403964900845;24035.519289614283;24259.514809703705;17139.485815425433;17158;17215;17456.30174793007;17560.034198119098;3937.0944682722848;3955.0310174007263;3956.129651476699;3960.3861401482527;3961.7028722845903;153029;153578;154377.99999999886;156004.2199789005;156811;17223.483295500904;17649.7352539712;17664;17753;17760.111994400486;34398.140046498535;34567.4814877777;34631.82684086564;34656;34668.653313467046;8900.599473023729;9165.404248723811;9166.404183728037;9186.816263674822;9247.213986811197;4815.181419158752;4901.50984901507;4909.042736666316;4916.655834091651;4924.261360795837;6101.969490152521;6137.9079313810325;6220.911340515392;6277.121203031579;6287.622742635456;1181.3148373943093;1215.6110044785585;1218.0438355890583;1221.2062159596155;1232.7596118756755;716.2872941423317;723.1141851232303;724.576122968059;727.2509315405141;737.3621817128145;11718.88281117195;11881.940590296994;12187.695307617381;12227.14409991292;12608.81086783699;676.1987045351275;679.2358596578849;688.3426327856885;693.1578132568953;696.5368030259821;5705.600607957486;5777.62445441045;5791.971040144772;5792.739326730307;5899.4985426239255;143245;143985;146521.26739366236;147284;149497;108715.45642271738;126024;127291;132047.3397633025;132491.33754331167;106804;107412.46293768482;108046;108189;109552.4522377399;11897.583584574499;11942.223755455867;11995.760084798081;12020;12232.327222002876;52021;53141;53362.73318633382;53631.731841340545;54673;68326;68475.65762171158;68698.31301686919;69190.3080969204;70276.64861675659;20332.898335508227;20448.69326960067;20491.38525844227;20685.379438616867;20852.791472085086;22852.200172993864;22985.310440686808;23218.535629287657;23302;23514.412139696644;17903.194356253738;18025.639487210443;18273.81726182721;18312.450626481226;18559.536011599277;20500;20583.485412864797;20683.58632827305;20784.376468705963;21117.683234751185;17396.565085872448;17425.128743563026;17436.73844892302;19048.42854714361;19067.427977160707;15491.922540387226;15581.065136091871;15618.687626247638;15720.606984825014;15766.211689415253;13681.452741890213;13730.313484325943;13774.793378099135;13798.793018104938;13806.516771912935 12:10:56 INFO - PID 3797 | __end_tp_report 12:10:56 INFO - PID 3797 | __start_cc_report 12:10:56 INFO - PID 3797 | _x_x_mozilla_cycle_collect,349 12:10:56 INFO - PID 3797 | __end_cc_report 12:10:56 INFO - PID 3797 | __startTimestamp1505502656105__endTimestamp 12:10:56 INFO - PID 3797 | 12:10:56 INFO - PID 3797 | ------- Summary: start ------- 12:10:56 INFO - PID 3797 | Number of tests: 6 12:10:56 INFO - PID 3797 | 12:10:56 INFO - PID 3797 | [#0] dojo.html Cycles:165 Average:6129.22 Median:6394.62 stddev:845.64 (13.2%) stddev-sans-first:828.21 12:10:56 INFO - PID 3797 | Values: 3797.3 3818.1 3850.6 3853.5 3857.0 6086.9 6133.1 6182.9 6211.2 6232.6 6122.5 6175.4 6236.8 6316.6 6329.5 5430.8 5512.0 5519.6 5536.4 5545.4 448.4 5218.2 5485.9 5542.1 5566.3 5438.7 5463.5 5531.0 5538.3 5550.5 5479.8 5495.1 5557.7 5558.7 5561.6 5475.6 5484.7 5561.0 5562.3 5570.5 6041.7 6101.0 6148.9 6167.4 6172.6 6656.9 6734.3 6755.0 6762.9 6763.3 6685.3 6686.9 6794.8 6801.4 6807.2 6194.8 6219.1 6224.9 6232.1 6232.3 6808.0 6918.2 6923.8 6930.7 6947.5 6585.0 6610.6 6671.6 6691.4 6717.1 6705.2 6710.7 6718.9 6792.3 6807.2 6606.1 6615.5 6630.2 6630.5 6635.2 6631.6 6635.5 6639.7 6641.2 6662.5 4892.1 4913.7 4917.8 4952.1 5000.0 4463.9 4497.4 4539.0 4557.0 4568.6 6205.0 6214.8 6271.3 6300.1 6306.3 6544.6 6625.3 6638.2 6732.8 6754.2 6252.9 6275.2 6278.4 6294.4 6295.3 5430.8 5499.2 5505.7 5509.4 5511.9 6771.8 6927.4 6955.2 7037.8 7047.8 6595.6 6680.6 6686.7 6708.4 6709.0 6873.8 6881.3 6905.5 6910.2 6911.2 6512.9 6537.1 6566.4 6587.5 6623.2 6587.8 6605.4 6625.9 6705.1 6705.1 6425.8 6486.9 6509.7 6531.1 6535.7 6257.2 6438.5 6512.1 6540.1 6545.6 6344.2 6396.3 6476.9 6480.6 6484.5 6387.4 6437.9 6479.6 6494.5 6514.8 6284.3 6328.1 6365.3 6375.8 6394.6 12:10:56 INFO - PID 3797 | 12:10:56 INFO - PID 3797 | [#1] ext.html Cycles:180 Average:41990.43 Median:3777.29 stddev:62963.20 (1666.9%) stddev-sans-first:63064.34 12:10:56 INFO - PID 3797 | Values: 928.4 931.4 960.3 969.2 976.3 44646.6 44729.8 45097.3 45274.8 45336.5 41518.4 41910.4 41980.0 42079.0 42287.4 7118.4 7318.3 7357.1 7362.2 7366.9 7281.0 7325.2 7374.1 7383.0 7401.7 7966.8 8241.1 8249.3 8249.7 8250.3 8164.2 8176.8 8220.2 8227.3 8228.5 7647.0 7814.9 8011.9 8023.2 8040.6 41014.6 41057.8 41200.4 41405.0 41459.4 3759.7 3794.9 3801.4 3816.8 3821.5 34434.7 34579.1 34609.7 34653.6 34668.3 5442.5 5456.7 5515.7 5523.0 5548.5 223806.0 224470.0 225040.0 225173.0 225755.0 48314.0 49160.3 49518.8 54567.5 55376.0 57612.0 57789.4 57850.0 58062.7 58208.4 16745.8 16954.8 16969.2 16976.9 17019.1 13738.7 13999.0 14000.7 14006.4 14018.2 15652.9 15721.9 15725.1 15743.2 15790.4 6065.5 6069.9 6116.3 6144.8 6153.0 1066.4 1071.4 1079.9 1083.2 1098.7 24607.6 24917.1 25071.2 25223.9 25282.7 1177.9 1202.6 1210.4 1213.3 1215.5 13232.4 13306.6 13309.9 13509.2 13611.6 271130.0 272317.0 272722.0 273493.0 274379.0 54888.0 55012.2 55190.4 55328.7 55391.2 1473.6 1498.3 1507.0 1527.0 1532.1 24242.9 24402.9 24411.3 24592.6 24773.3 41202.6 41255.8 41477.2 41585.8 41621.2 224828.0 225044.0 227043.0 227280.0 227621.0 35727.0 37066.4 37202.4 37426.0 37470.8 44697.1 45857.0 46356.0 46623.8 46722.3 33636.7 34210.3 34276.0 34417.8 34708.7 34249.0 34404.0 34435.5 34551.0 34589.7 40102.0 40139.0 40234.0 40445.4 40530.4 32852.3 32976.0 33030.7 33278.2 33283.2 24795.0 24883.0 25350.1 25366.9 25818.2 12:10:56 INFO - PID 3797 | 12:10:56 INFO - PID 3797 | [#2] jquery.html Cycles:180 Average:39692.56 Median:12656.05 stddev:103426.06 (817.2%) stddev-sans-first:103674.48 12:10:56 INFO - PID 3797 | Values: 570.6 574.6 587.4 594.6 596.3 23024.9 23722.0 23753.0 23761.6 23770.0 22579.2 23092.0 23124.0 24915.9 25119.2 6412.6 6418.4 6448.0 6448.7 6458.6 6447.4 6500.6 6527.0 6539.1 6749.6 6697.1 6704.1 6713.1 6718.6 6728.8 6704.3 6709.6 6722.3 6729.2 6735.7 5774.4 5980.2 5998.8 6029.7 6033.3 29165.9 29639.0 29649.6 29688.4 29758.9 2253.2 2281.0 2281.2 2282.9 2287.2 19538.9 19913.0 20302.5 20420.6 20559.0 2486.1 2497.4 2519.1 2543.6 2549.5 137190.0 142131.3 151599.0 157477.0 158687.0 14958.0 15428.0 15461.8 15630.3 15953.8 24295.4 24339.6 24478.6 24514.3 24578.3 10232.8 10339.2 10524.7 10581.0 10613.8 9480.3 9484.2 9548.5 9555.0 9610.0 9364.5 9421.9 9442.0 9571.2 9571.9 2794.4 2807.5 2808.9 2853.9 2881.6 1027.5 1037.9 1042.8 1046.0 1063.1 20301.8 20431.6 20477.9 20752.5 20910.7 1094.5 1106.3 1116.1 1120.4 1126.9 11323.4 11343.4 11369.1 11383.7 11385.5 604678.0 611202.0 613885.0 628417.0 634997.0 126452.4 130888.0 132983.0 133441.3 134762.3 1202.2 1235.0 1250.6 1255.2 1266.5 18107.9 18212.2 18354.7 18404.6 18497.3 60434.0 60937.0 62465.0 62672.7 63009.4 77963.0 78356.6 79101.6 79111.6 79469.0 14551.1 14869.9 14876.2 14888.1 14997.6 23071.4 23211.0 23239.7 23262.8 23586.4 18759.8 19073.2 19302.2 19307.8 19321.7 16922.7 17098.2 17241.5 17332.5 17867.0 18439.5 19418.9 19583.2 19649.6 19743.3 14809.9 14847.8 14853.7 14870.6 14912.2 18721.4 18798.0 18963.5 19037.3 19217.7 12:10:56 INFO - PID 3797 | 12:10:56 INFO - PID 3797 | [#3] mootools.html Cycles:180 Average:10336.19 Median:2661.56 stddev:7680.28 (288.6%) stddev-sans-first:7672.33 12:10:56 INFO - PID 3797 | Values: 1377.7 1470.6 1480.2 1493.9 1502.9 16211.5 16779.8 16822.4 16840.6 16840.8 16414.6 16465.3 16502.4 16503.8 16505.3 2801.1 2806.6 2844.6 2845.9 2847.1 2736.6 2815.3 2824.1 2838.6 2842.7 2642.2 2719.7 2832.1 2842.3 2843.2 2764.3 2785.7 2811.8 2815.1 2815.6 2647.1 2658.7 2664.4 2675.4 2681.2 15935.8 16761.5 16799.3 16822.7 16823.8 8068.7 8280.1 8306.3 8364.9 8369.9 5112.5 5332.0 5380.7 5491.9 5499.3 2066.4 2082.5 2089.4 2104.0 2141.8 30835.4 30943.8 30964.5 31065.4 31066.7 6891.5 6945.1 7025.6 7129.1 7154.7 19049.6 19068.1 19103.0 19120.0 19123.7 8187.5 8331.7 8343.9 8345.0 8363.4 7295.5 7463.9 7474.0 7557.9 7740.7 5532.5 5596.6 5768.5 5860.9 5894.1 1474.4 1506.1 1593.2 1642.0 1645.4 929.8 957.9 967.3 974.1 979.9 9446.3 9691.5 9711.9 9763.9 9847.7 809.5 823.2 1036.0 1056.7 1058.2 5776.0 5935.2 5990.4 6052.1 6058.5 23927.4 24564.8 24921.1 25121.4 25245.5 24244.4 24334.3 24703.5 24743.4 24793.4 12169.5 12657.5 12671.0 12672.2 12680.1 9198.2 9210.0 9296.9 9311.0 9318.6 20795.4 20943.7 21078.5 21180.9 21560.6 21728.5 21972.0 21974.8 21981.6 22012.8 11874.7 12011.2 12016.2 12018.2 12051.6 15020.2 15441.1 15465.6 15530.8 15540.6 12013.3 12241.2 12367.5 12368.7 12568.2 12043.9 12122.8 12207.8 12290.3 12482.8 10781.4 10867.8 10884.6 11015.8 11024.0 7963.6 8037.2 8067.1 8074.2 8084.2 8274.5 8293.4 8294.5 8316.3 8430.0 12:10:56 INFO - PID 3797 | 12:10:56 INFO - PID 3797 | [#4] prototype.html Cycles:180 Average:5493.77 Median:6114.65 stddev:1551.12 (25.4%) stddev-sans-first:1509.63 12:10:56 INFO - PID 3797 | Values: 506.7 509.0 521.0 525.9 537.4 5980.6 6166.1 6190.9 6228.3 6241.7 5925.5 6074.2 6096.1 6111.5 6133.8 4701.5 4812.4 4848.8 4876.9 4881.2 4826.6 4830.1 4863.9 4882.6 4900.5 4785.7 4810.7 4826.7 4830.8 4843.5 4839.5 4839.8 4850.9 4856.8 4903.3 4410.5 4411.3 4413.9 4424.3 4432.3 5931.9 6020.3 6069.5 6090.6 6137.8 5588.2 5628.6 5641.9 5688.4 5695.8 6493.7 6514.8 6533.3 6557.5 6560.4 4816.8 4864.2 4878.4 4894.3 4909.4 6667.5 6783.9 6832.6 6851.8 6852.5 4988.2 5020.8 5041.3 5047.5 5054.1 5085.4 5096.6 5097.3 5103.8 5111.3 5613.3 5629.5 5633.4 5678.7 5693.3 6307.5 6322.8 6412.1 6465.6 6530.3 1728.2 1743.7 1748.5 1749.4 1757.0 1210.2 1223.8 1227.0 1228.9 1247.9 6080.4 6081.9 6112.3 6117.0 6160.5 6632.8 6633.6 6656.9 6668.2 6681.0 5955.9 6012.2 6087.3 6096.7 6117.5 3473.4 3476.0 3498.3 3513.2 3529.0 6827.6 6842.8 6858.4 6862.3 6988.9 6554.6 6653.7 6724.7 6750.7 6806.7 6744.8 6759.5 6783.6 6789.9 6810.8 6120.5 6460.3 6518.3 6579.2 6680.3 6417.7 6452.4 6468.5 6493.2 6534.8 6803.9 6821.1 6857.3 6911.4 6919.2 4956.3 4969.0 5005.8 5020.8 5023.9 6501.6 6631.7 6633.2 6744.5 6769.7 6268.8 6376.3 6387.6 6412.4 6430.4 6379.6 6402.0 6463.8 6480.5 6509.0 6254.9 6280.7 6380.8 6428.6 6439.3 6437.2 6486.7 6549.5 6550.6 6563.8 6303.7 6350.6 6367.8 6378.3 6402.5 12:10:56 INFO - PID 3797 | 12:10:56 INFO - PID 3797 | [#5] yui.html Cycles:180 Average:27795.46 Median:19191.38 stddev:40598.29 (211.5%) stddev-sans-first:40662.13 12:10:56 INFO - PID 3797 | Values: 948.7 987.6 990.4 994.4 1023.0 19390.8 19392.2 19455.8 19489.7 20126.8 19315.3 19331.8 19364.3 20315.6 20422.5 1039.9 1061.5 1061.8 1062.1 1065.2 1018.1 1077.0 1080.0 1080.3 1080.7 1143.4 1153.7 1154.7 1157.5 1157.6 1145.5 1146.0 1148.2 1149.6 1150.4 782.7 800.9 810.9 811.1 811.2 18269.3 18290.7 18306.4 18380.4 18394.5 23444.4 23546.2 23841.4 24035.5 24259.5 17139.5 17158.0 17215.0 17456.3 17560.0 3937.1 3955.0 3956.1 3960.4 3961.7 153029.0 153578.0 154378.0 156004.2 156811.0 17223.5 17649.7 17664.0 17753.0 17760.1 34398.1 34567.5 34631.8 34656.0 34668.7 8900.6 9165.4 9166.4 9186.8 9247.2 4815.2 4901.5 4909.0 4916.7 4924.3 6102.0 6137.9 6220.9 6277.1 6287.6 1181.3 1215.6 1218.0 1221.2 1232.8 716.3 723.1 724.6 727.3 737.4 11718.9 11881.9 12187.7 12227.1 12608.8 676.2 679.2 688.3 693.2 696.5 5705.6 5777.6 5792.0 5792.7 5899.5 143245.0 143985.0 146521.3 147284.0 149497.0 108715.5 126024.0 127291.0 132047.3 132491.3 106804.0 107412.5 108046.0 108189.0 109552.5 11897.6 11942.2 11995.8 12020.0 12232.3 52021.0 53141.0 53362.7 53631.7 54673.0 68326.0 68475.7 68698.3 69190.3 70276.6 20332.9 20448.7 20491.4 20685.4 20852.8 22852.2 22985.3 23218.5 23302.0 23514.4 17903.2 18025.6 18273.8 18312.5 18559.5 20500.0 20583.5 20683.6 20784.4 21117.7 17396.6 17425.1 17436.7 19048.4 19067.4 15491.9 15581.1 15618.7 15720.6 15766.2 13681.5 13730.3 13774.8 13798.8 13806.5 12:10:56 INFO - PID 3797 | -------- Summary: end -------- 12:10:56 INFO - PID 3797 | 12:10:56 INFO - TEST-INFO | 3797: exit 0 12:10:56 INFO - TEST-OK | dromaeo_css | took 1108990ms 12:10:56 INFO - TEST-START | kraken 12:10:56 INFO - Initialising browser for kraken test... 12:10:56 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:42105/getInfo.html -profile /tmp/tmpypyZXX/profile 12:10:56 INFO - TEST-INFO | started process 4167 (/builds/slave/test/build/application/firefox/firefox http://localhost:42105/getInfo.html) 12:11:01 INFO - TEST-INFO | 4167: exit 0 12:11:01 INFO - Browser initialized. 12:11:01 INFO - Running cycle 1/1 for kraken test... 12:11:01 INFO - Using env: {'DISPLAY': ':0', 12:11:01 INFO - 'HOME': '/home/cltbld', 12:11:01 INFO - 'JSGC_DISABLE_POISONING': '1', 12:11:01 INFO - 'LANG': 'en_US.UTF-8', 12:11:01 INFO - 'LANGUAGE': 'en_US:en', 12:11:01 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 12:11:01 INFO - 'LOGNAME': 'cltbld', 12:11:01 INFO - 'MAIL': '/var/mail/cltbld', 12:11:01 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:11:01 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 12:11:01 INFO - 'MOZ_CRASHREPORTER': '1', 12:11:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:11:01 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 12:11:01 INFO - 'MOZ_NO_REMOTE': '1', 12:11:01 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:11:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:11:01 INFO - 'NO_EM_RESTART': '1', 12:11:01 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:11:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:11:01 INFO - 'PWD': '/builds/slave/test', 12:11:01 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:11:01 INFO - 'RUST_BACKTRACE': 'full', 12:11:01 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 12:11:01 INFO - 'SHELL': '/bin/bash', 12:11:01 INFO - 'SHLVL': '1', 12:11:01 INFO - 'TERM': 'linux', 12:11:01 INFO - 'TMOUT': '86400', 12:11:01 INFO - 'USER': 'cltbld', 12:11:01 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1505500579.446389-1924944135', 12:11:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:11:01 INFO - '_': '/tools/buildbot/bin/python'} 12:11:01 INFO - TEST-INFO | started process 4399 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpypyZXX/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 12:11:02 INFO - PID 4399 | 12:11:02 INFO - PID 4399 | (/builds/slave/test/build/application/firefox/firefox:4449): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 12:11:02 INFO - PID 4399 | 12:11:13 INFO - PID 4399 | RSS: Main: 166531072 12:11:13 INFO - PID 4399 | 12:11:15 INFO - PID 4399 | Cycle 1(1): loaded http://localhost:42105/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:42105/tests/kraken/driver.html?testName=audio-beat-detection) 12:11:16 INFO - PID 4399 | RSS: Main: 171732992 12:11:16 INFO - PID 4399 | 12:11:19 INFO - PID 4399 | Cycle 1(1): loaded http://localhost:42105/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:42105/tests/kraken/driver.html?testName=audio-dft) 12:11:19 INFO - PID 4399 | RSS: Main: 172036096 12:11:19 INFO - PID 4399 | 12:11:22 INFO - PID 4399 | Cycle 1(1): loaded http://localhost:42105/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:42105/tests/kraken/driver.html?testName=audio-fft) 12:11:23 INFO - PID 4399 | RSS: Main: 167018496 12:11:23 INFO - PID 4399 | 12:11:25 INFO - PID 4399 | Cycle 1(1): loaded http://localhost:42105/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:42105/tests/kraken/driver.html?testName=audio-oscillator) 12:11:26 INFO - PID 4399 | RSS: Main: 168321024 12:11:26 INFO - PID 4399 | 12:11:28 INFO - PID 4399 | Cycle 1(1): loaded http://localhost:42105/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:42105/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:11:28 INFO - PID 4399 | RSS: Main: 168300544 12:11:28 INFO - PID 4399 | 12:11:31 INFO - PID 4399 | Cycle 1(1): loaded http://localhost:42105/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:42105/tests/kraken/driver.html?testName=imaging-darkroom) 12:11:32 INFO - PID 4399 | RSS: Main: 171843584 12:11:32 INFO - PID 4399 | 12:11:35 INFO - PID 4399 | Cycle 1(1): loaded http://localhost:42105/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:42105/tests/kraken/driver.html?testName=imaging-desaturate) 12:11:36 INFO - PID 4399 | RSS: Main: 171417600 12:11:36 INFO - PID 4399 | 12:11:39 INFO - PID 4399 | Cycle 1(1): loaded http://localhost:42105/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:42105/tests/kraken/driver.html?testName=json-parse-financial) 12:11:40 INFO - PID 4399 | RSS: Main: 171307008 12:11:40 INFO - PID 4399 | 12:11:41 INFO - PID 4399 | Cycle 1(1): loaded http://localhost:42105/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:42105/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:11:42 INFO - PID 4399 | RSS: Main: 171249664 12:11:42 INFO - PID 4399 | 12:11:43 INFO - PID 4399 | Cycle 1(1): loaded http://localhost:42105/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:42105/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:11:44 INFO - PID 4399 | RSS: Main: 171302912 12:11:44 INFO - PID 4399 | 12:11:46 INFO - PID 4399 | Cycle 1(1): loaded http://localhost:42105/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:42105/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:11:47 INFO - PID 4399 | RSS: Main: 168603648 12:11:47 INFO - PID 4399 | 12:11:49 INFO - PID 4399 | Cycle 1(1): loaded http://localhost:42105/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:42105/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:11:50 INFO - PID 4399 | RSS: Main: 168505344 12:11:50 INFO - PID 4399 | 12:11:53 INFO - PID 4399 | Cycle 1(1): loaded http://localhost:42105/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:42105/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:11:53 INFO - PID 4399 | RSS: Main: 168935424 12:11:53 INFO - PID 4399 | 12:11:55 INFO - PID 4399 | Cycle 1(1): loaded http://localhost:42105/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:42105/tests/kraken/driver.html?testName=ai-astar) 12:11:55 INFO - PID 4399 | RSS: Main: 170721280 12:11:55 INFO - PID 4399 | 12:11:55 INFO - PID 4399 | __start_tp_report 12:11:55 INFO - PID 4399 | _x_x_mozilla_page_load 12:11:55 INFO - PID 4399 | _x_x_mozilla_page_load_details 12:11:55 INFO - PID 4399 | |i|pagename|runs| 12:11:55 INFO - PID 4399 | |0;ai-astar;105;104;105;104;106;106;106;107;106;104 12:11:55 INFO - PID 4399 | |1;audio-beat-detection;112;116;115;114;112;115;112;113;113;114 12:11:55 INFO - PID 4399 | |2;audio-dft;162;162;163;161;162;162;162;162;162;166 12:11:55 INFO - PID 4399 | |3;audio-fft;73;72;72;73;74;73;73;75;76;76 12:11:55 INFO - PID 4399 | |4;audio-oscillator;78;77;78;79;78;77;79;77;78;77 12:11:55 INFO - PID 4399 | |5;imaging-gaussian-blur;117;113;110;113;113;113;113;113;113;110 12:11:55 INFO - PID 4399 | |6;imaging-darkroom;149;148;150;150;150;148;149;148;151;150 12:11:55 INFO - PID 4399 | |7;imaging-desaturate;98;98;98;94;99;98;98;99;98;98 12:11:55 INFO - PID 4399 | |8;json-parse-financial;45;46;45;45;46;45;45;47;56;45 12:11:55 INFO - PID 4399 | |9;json-stringify-tinderbox;39;39;39;39;39;39;39;39;39;38 12:11:55 INFO - PID 4399 | |10;stanford-crypto-aes;72;73;77;71;75;72;72;71;71;72 12:11:55 INFO - PID 4399 | |11;stanford-crypto-ccm;113;108;104;104;106;104;117;110;112;104 12:11:55 INFO - PID 4399 | |12;stanford-crypto-pbkdf2;153;159;155;156;158;155;153;156;157;155 12:11:55 INFO - PID 4399 | |13;stanford-crypto-sha256-iterative;68;62;59;61;60;58;60;64;61;60 12:11:55 INFO - PID 4399 | __end_tp_report 12:11:55 INFO - PID 4399 | __start_cc_report 12:11:55 INFO - PID 4399 | _x_x_mozilla_cycle_collect,850 12:11:55 INFO - PID 4399 | __end_cc_report 12:11:55 INFO - PID 4399 | __startTimestamp1505502715637__endTimestamp 12:11:55 INFO - PID 4399 | 12:11:55 INFO - PID 4399 | ------- Summary: start ------- 12:11:55 INFO - PID 4399 | Number of tests: 14 12:11:55 INFO - PID 4399 | 12:11:55 INFO - PID 4399 | [#0] ai-astar Cycles:10 Average:105.30 Median:106.00 stddev:1.06 (1.0%) stddev-sans-first:1.12 12:11:55 INFO - PID 4399 | Values: 105.0 104.0 105.0 104.0 106.0 106.0 106.0 107.0 106.0 104.0 12:11:55 INFO - PID 4399 | 12:11:55 INFO - PID 4399 | [#1] audio-beat-detection Cycles:10 Average:113.60 Median:114.00 stddev:1.43 (1.3%) stddev-sans-first:1.39 12:11:55 INFO - PID 4399 | Values: 112.0 116.0 115.0 114.0 112.0 115.0 112.0 113.0 113.0 114.0 12:11:55 INFO - PID 4399 | 12:11:55 INFO - PID 4399 | [#2] audio-dft Cycles:10 Average:162.40 Median:162.00 stddev:1.35 (0.8%) stddev-sans-first:1.42 12:11:55 INFO - PID 4399 | Values: 162.0 162.0 163.0 161.0 162.0 162.0 162.0 162.0 162.0 166.0 12:11:55 INFO - PID 4399 | 12:11:55 INFO - PID 4399 | [#3] audio-fft Cycles:10 Average:73.70 Median:73.50 stddev:1.49 (2.0%) stddev-sans-first:1.56 12:11:55 INFO - PID 4399 | Values: 73.0 72.0 72.0 73.0 74.0 73.0 73.0 75.0 76.0 76.0 12:11:55 INFO - PID 4399 | 12:11:55 INFO - PID 4399 | [#4] audio-oscillator Cycles:10 Average:77.80 Median:78.00 stddev:0.79 (1.0%) stddev-sans-first:0.83 12:11:55 INFO - PID 4399 | Values: 78.0 77.0 78.0 79.0 78.0 77.0 79.0 77.0 78.0 77.0 12:11:55 INFO - PID 4399 | 12:11:55 INFO - PID 4399 | [#5] imaging-gaussian-blur Cycles:10 Average:112.80 Median:113.00 stddev:1.93 (1.7%) stddev-sans-first:1.32 12:11:55 INFO - PID 4399 | Values: 117.0 113.0 110.0 113.0 113.0 113.0 113.0 113.0 113.0 110.0 12:11:55 INFO - PID 4399 | 12:11:55 INFO - PID 4399 | [#6] imaging-darkroom Cycles:10 Average:149.30 Median:150.00 stddev:1.06 (0.7%) stddev-sans-first:1.12 12:11:55 INFO - PID 4399 | Values: 149.0 148.0 150.0 150.0 150.0 148.0 149.0 148.0 151.0 150.0 12:11:55 INFO - PID 4399 | 12:11:55 INFO - PID 4399 | [#7] imaging-desaturate Cycles:10 Average:97.80 Median:98.00 stddev:1.40 (1.4%) stddev-sans-first:1.48 12:11:55 INFO - PID 4399 | Values: 98.0 98.0 98.0 94.0 99.0 98.0 98.0 99.0 98.0 98.0 12:11:55 INFO - PID 4399 | 12:11:55 INFO - PID 4399 | [#8] json-parse-financial Cycles:10 Average:46.50 Median:45.50 stddev:3.41 (7.5%) stddev-sans-first:3.57 12:11:55 INFO - PID 4399 | Values: 45.0 46.0 45.0 45.0 46.0 45.0 45.0 47.0 56.0 45.0 12:11:55 INFO - PID 4399 | 12:11:55 INFO - PID 4399 | [#9] json-stringify-tinderbox Cycles:10 Average:38.90 Median:39.00 stddev:0.32 (0.8%) stddev-sans-first:0.33 12:11:55 INFO - PID 4399 | Values: 39.0 39.0 39.0 39.0 39.0 39.0 39.0 39.0 39.0 38.0 12:11:55 INFO - PID 4399 | 12:11:55 INFO - PID 4399 | [#10] stanford-crypto-aes Cycles:10 Average:72.60 Median:72.00 stddev:1.96 (2.7%) stddev-sans-first:2.06 12:11:55 INFO - PID 4399 | Values: 72.0 73.0 77.0 71.0 75.0 72.0 72.0 71.0 71.0 72.0 12:11:55 INFO - PID 4399 | 12:11:55 INFO - PID 4399 | [#11] stanford-crypto-ccm Cycles:10 Average:108.20 Median:109.00 stddev:4.64 (4.3%) stddev-sans-first:4.58 12:11:55 INFO - PID 4399 | Values: 113.0 108.0 104.0 104.0 106.0 104.0 117.0 110.0 112.0 104.0 12:11:55 INFO - PID 4399 | 12:11:55 INFO - PID 4399 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:155.70 Median:156.00 stddev:1.95 (1.2%) stddev-sans-first:1.80 12:11:55 INFO - PID 4399 | Values: 153.0 159.0 155.0 156.0 158.0 155.0 153.0 156.0 157.0 155.0 12:11:55 INFO - PID 4399 | 12:11:55 INFO - PID 4399 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.30 Median:61.00 stddev:2.87 (4.7%) stddev-sans-first:1.74 12:11:55 INFO - PID 4399 | Values: 68.0 62.0 59.0 61.0 60.0 58.0 60.0 64.0 61.0 60.0 12:11:55 INFO - PID 4399 | -------- Summary: end -------- 12:11:55 INFO - PID 4399 | 12:11:56 INFO - PID 4399 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 12:11:56 INFO - TEST-INFO | 4399: exit 0 12:11:56 INFO - TEST-OK | kraken | took 59636ms 12:11:56 INFO - SUITE-END | took 1168s 12:11:56 INFO - Completed test suite (00:19:28) 12:11:56 INFO - javascript benchmark 12:11:56 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8933.628182177767, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3797.335466293398, 3818.121831978646, 3850.5764365919727, 3853.479780229668, 3857.0, 6086.908696369553, 6133.11069894865, 6182.9381706182985, 6211.192544969148, 6232.59488133271, 6122.540809439299, 6175.382461753822, 6236.750529978806, 6316.621002739839, 6329.525285603575, 5430.782768689248, 5512.035393806088, 5519.586031047667, 5536.446355364472, 5545.390007099216, 448.35427887492517, 5218.19118036703, 5485.890282194354, 5542.140968149923, 5566.276384070065, 5438.7280635968245, 5463.48096930791, 5530.99999999998, 5538.307711536058, 5550.47270509301, 5479.808206712786, 5495.148252020943, 5557.722113894289, 5558.666480011212, 5561.555075593943, 5475.561955043587, 5484.725763711798, 5561.0, 5562.332520097574, 5570.526505247059, 6041.697915104227, 6101.0, 6148.907766383508, 6167.414095660905, 6172.629642221481, 6656.93343066568, 6734.292899245557, 6754.9662251688915, 6762.898556521607, 6763.323667633246, 6685.298043705438, 6686.899696504556, 6794.796156115324, 6801.421879140279, 6807.183138023469, 6194.752209911598, 6219.098230756515, 6224.906626400562, 6232.127502149703, 6232.3144454110015, 6807.965960170168, 6918.169819621577, 6923.792286231421, 6930.653467326613, 6947.548409353376, 6585.0, 6610.636414997125, 6671.5997040177745, 6691.431228345645, 6717.059611654372, 6705.228898676617, 6710.66446677674, 6718.865622687519, 6792.320767923267, 6807.217170025411, 6606.075149479077, 6615.536912416084, 6630.237522684856, 6630.53586248958, 6635.236947750974, 6631.568948018364, 6635.502337324623, 6639.734410623618, 6641.236257830315, 6662.467002639777, 4892.0705066037335, 4913.729744863996, 4917.754112294371, 4952.059108769322, 5000.0, 4463.866084017484, 4497.370368148462, 4539.024109816405, 4557.043020965568, 4568.565986231302, 6204.968975155186, 6214.8135555933395, 6271.310155882849, 6300.086487459384, 6306.274778400542, 6544.607323560602, 6625.337466253336, 6638.20341559016, 6732.831679208059, 6754.223264324665, 6252.906206406908, 6275.184226050585, 6278.403551662676, 6294.402031806971, 6295.307516173218, 5430.755615997289, 5499.175123731472, 5505.697186654692, 5509.3664228613425, 5511.917321240185, 6771.796846094625, 6927.411170050602, 6955.234924158339, 7037.788866334018, 7047.753328633576, 6595.604263744095, 6680.565763225375, 6686.732530698718, 6708.429783468362, 6709.0, 6873.759418420332, 6881.346272104142, 6905.482088843256, 6910.170779506491, 6911.17065952089, 6512.934870651422, 6537.0521274413995, 6566.376194261537, 6587.505937054836, 6623.205215374186, 6587.835304117435, 6605.405513503808, 6625.933740662532, 6705.061291419108, 6705.0612914193025, 6425.775097871552, 6486.870262594627, 6509.7396104155305, 6531.11829902957, 6535.673216339069, 6257.2491301044165, 6438.484921206198, 6512.120863683532, 6540.051692504465, 6545.639989800607, 6344.206974128234, 6396.32838551964, 6476.902846457213, 6480.643564603992, 6484.513661475314, 6387.393197646216, 6437.935620643733, 6479.611223326616, 6494.480441564758, 6514.804555863332, 6284.277308109443, 6328.145700330397, 6365.299817020032, 6375.808725738235, 6394.616323020447], "value": 6074.030556659368, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [928.4150984879525, 931.3945935142157, 960.2701946520647, 969.2197780786466, 976.326334828968, 44646.55353446465, 44729.77635111828, 45097.32354014692, 45274.773626131864, 45336.54663453368, 41518.37722434166, 41910.371344429856, 41979.95050123749, 42079.0, 42287.365689514685, 7118.359347658723, 7318.268173182692, 7357.0803649543805, 7362.226966168556, 7366.852662946738, 7280.963595182016, 7325.157606875197, 7374.115106187265, 7383.040204773399, 7401.666924988388, 7966.800829979239, 8241.052278987903, 8249.298809601161, 8249.711260105898, 8250.298724608416, 8164.183581641818, 8176.836463270702, 8220.219079187467, 8227.259546640802, 8228.465149765276, 7646.9617651911385, 7814.882776758353, 8011.879821802678, 8023.237792409712, 8040.638171282307, 41014.589854101374, 41057.794711026254, 41200.38199426981, 41405.0, 41459.37810932838, 3759.6804271636943, 3794.90512737181, 3801.391777315644, 3816.809159542012, 3821.464994900716, 34434.6556534434, 34579.13552161191, 34609.653903460894, 34653.57424980252, 34668.30663386718, 5442.537384322337, 5456.727163641802, 5515.696636684981, 5522.972385138089, 5548.528375088123, 223806.0, 224469.99999999837, 225040.0, 225173.0, 225755.0, 48314.0, 49160.26259606109, 49518.75240623846, 54567.45432545624, 55376.0, 57612.0, 57789.422105779246, 57850.0, 58062.709686452145, 58208.41791582115, 16745.83254167467, 16954.830451695325, 16969.15154242283, 16976.915115424345, 17019.149042547822, 13738.725225495435, 13999.0, 14000.719985600232, 14006.43974241019, 14018.158910465405, 15652.92173539125, 15721.921390392974, 15725.135117568643, 15743.212839357986, 15790.36838526446, 6065.543454900772, 6069.878602427927, 6116.348585881876, 6144.815655530341, 6153.0, 1066.4241309692834, 1071.4267866691282, 1079.9190060745475, 1083.1713738989679, 1098.6758906122714, 24607.63088553673, 24917.127900523396, 25071.247862564152, 25223.87388063048, 25282.747172528037, 1177.9234349767237, 1202.6151631477844, 1210.3706072842074, 1213.3084142038954, 1215.4751221457843, 13232.40454179545, 13306.600801975956, 13309.93345033288, 13509.189448633113, 13611.591652250449, 271130.0, 272317.0, 272722.0, 273493.0, 274379.0, 54888.0, 55012.174817377774, 55190.448095519336, 55328.72335638296, 55391.169132463045, 1473.6021274256104, 1498.3332417074516, 1506.992465037668, 1527.045596502186, 1532.0501289200768, 24242.87878560596, 24402.877985609957, 24411.26766197017, 24592.631110533, 24773.25680229596, 41202.587974121074, 41255.7937210312, 41477.1704565913, 41585.792071039454, 41621.167576648906, 224828.0, 225044.0, 227043.0, 227280.0, 227621.0, 35727.0, 37066.44400334051, 37202.44196337003, 37426.0, 37470.812645936596, 44697.10605787931, 45857.0, 46356.0, 46623.76688116538, 46722.29916551187, 33636.66363336335, 34210.31579368449, 34276.0, 34417.82791086029, 34708.65291347054, 34248.97253082412, 34404.0, 34435.483467748505, 34551.0, 34589.65410345864, 40102.0, 40139.0, 40234.0, 40445.39331909965, 40530.39204411877, 32852.342953141284, 32976.0, 33030.669693302756, 33278.168045798084, 33283.167920802174, 24795.0, 24883.0, 25350.112746054536, 25366.873165634053, 25818.22545323643], "value": 18423.812595757023, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [570.6376450953643, 574.5662025170996, 587.4389957590502, 594.5808205215322, 596.3172167867791, 23024.88487557562, 23722.0, 23753.0, 23761.643575346385, 23770.0, 22579.209727659534, 23092.0, 23124.0, 24915.875420622917, 25119.246422607306, 6412.647304398256, 6418.422341989209, 6448.0, 6448.677566121699, 6458.644774537399, 6447.387498187688, 6500.6099634021875, 6527.0209468579615, 6539.149910511627, 6749.561278516905, 6697.062411262427, 6704.061431399608, 6713.060171575983, 6718.59688418694, 6728.764493242737, 6704.2625311215725, 6709.563878347892, 6722.29415911332, 6729.192496900403, 6735.730570777163, 5774.364819869811, 5980.162777211194, 5998.790042348497, 6029.668368239745, 6033.276006879202, 29165.854170729224, 29639.0, 29649.555256671167, 29688.40623187524, 29758.85120574383, 2253.2001139595372, 2281.041962375806, 2281.1673739085113, 2282.897269622871, 2287.2109122352763, 19538.902305488522, 19913.0, 20302.492437689027, 20420.591588168303, 20559.0, 2486.0552989863736, 2497.3506888209195, 2519.143491212978, 2543.58030924899, 2549.5028469448466, 137190.0, 142131.28934355263, 151599.0, 157477.0, 158687.0, 14958.009981968735, 15428.0, 15461.768073478683, 15630.296636651377, 15953.8462307587, 24295.39261518441, 24339.634905476432, 24478.632820507708, 24514.264572062868, 24578.262652120466, 10232.821526677339, 10339.172866170687, 10524.736881577868, 10581.0, 10613.84079238812, 9480.33637645372, 9484.19384352324, 9548.522573871278, 9555.0, 9610.0, 9364.484953327632, 9421.90578094224, 9441.952790236004, 9571.234301255883, 9571.90428095724, 2794.3852352482827, 2807.5367564352064, 2808.9157325280275, 2853.885844566236, 2881.6109825173753, 1027.4503140819625, 1037.906588407047, 1042.8122937871256, 1045.994770026145, 1063.1229235880276, 20301.751719724773, 20431.591368172554, 20477.897610511853, 20752.48118797042, 20910.686339704916, 1094.4910616563336, 1106.25880659958, 1116.1015382616908, 1120.3613940054254, 1126.8704099028716, 11323.433828308716, 11343.376114313793, 11369.147313951487, 11383.658490245305, 11385.544578216945, 604678.0, 611202.0, 613885.0, 628417.0, 634997.0, 126452.36773816073, 130888.0, 132983.0, 133441.3327933354, 134762.32618837038, 1202.1765090912577, 1234.962951111468, 1250.5560526013317, 1255.1527718789962, 1266.4554241676167, 18107.909460453142, 18212.18045187996, 18354.72467912956, 18404.631907362047, 18497.260109595467, 60434.0, 60937.0, 62465.0, 62672.68663656653, 63009.369906302185, 77963.0, 78356.60821696083, 79101.60449197948, 79111.60444197743, 79469.0, 14551.126932384092, 14869.851301486846, 14876.18181000012, 14888.106713597219, 14997.625059373166, 23071.423214419774, 23211.0, 23239.651405229273, 23262.76737232606, 23586.410339741644, 18759.812401875806, 19073.237070517025, 19302.22791088341, 19307.8069219306, 19321.710174347678, 16922.661546769243, 17098.230579624196, 17241.482755517354, 17332.480025599252, 17867.0, 18439.53901152482, 19418.90290548538, 19583.216671332986, 19649.60700786005, 19743.308984185485, 14809.851901480848, 14847.777283340974, 14853.702925941638, 14870.553883383516, 14912.179830109448, 18721.438356849318, 18798.0, 18963.525911852314, 19037.333693320666, 19217.711734324275], "value": 12485.711869300158, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1377.6624726941898, 1470.595586213791, 1480.1785009319824, 1493.8954273200873, 1502.9473968411107, 16211.513654590352, 16779.832201677997, 16822.411215607455, 16840.57898552534, 16840.83159168408, 16414.62853944488, 16465.25906334215, 16502.42241521547, 16503.752443713354, 16505.33978640856, 2801.131649188748, 2806.556593915144, 2844.559093340525, 2845.8861645534153, 2847.1173936079776, 2736.58951157327, 2815.2539577012108, 2824.096289187461, 2838.574213867926, 2842.6730925943575, 2642.207337798662, 2719.687235967869, 2832.136198459469, 2842.3462603601183, 2843.218115018366, 2764.2812868654296, 2785.679646840619, 2811.831290122599, 2815.127310533741, 2815.5776633505034, 2647.099986004748, 2658.734126587345, 2664.387190946074, 2675.4247836715003, 2681.1956413076, 15935.760963585439, 16761.497155085366, 16799.328026879033, 16822.66354672912, 16823.831761682348, 8068.677252909876, 8280.089190189074, 8306.252437280673, 8364.874526882102, 8369.916300837036, 5112.514311140436, 5332.0, 5380.650257733274, 5491.890162196733, 5499.340079190522, 2066.359428577131, 2082.5106100066473, 2089.3523007867457, 2104.0298277753823, 2141.8286537076997, 30835.383292334027, 30943.845280773676, 30964.535531967038, 31065.378692426024, 31066.689333106606, 6891.483138764613, 6945.062416573799, 7025.578465292099, 7129.144502659714, 7154.678039488235, 19049.61900761977, 19068.14193361302, 19103.044847757556, 19120.0, 19123.71314430285, 8187.4678145920325, 8331.708390206313, 8343.916560834436, 8344.958275208584, 8363.372747043995, 7295.452841036943, 7463.8507229855095, 7473.962630186923, 7557.886631700529, 7740.690372385154, 5532.5297349724915, 5596.552275817925, 5768.451997060272, 5860.912086318709, 5894.115882617642, 1474.4470823441209, 1506.0737646347418, 1593.2272847668871, 1642.0295645225776, 1645.3994292083544, 929.846575315079, 957.913787759105, 967.2552134856121, 974.1232890398556, 979.8726165598426, 9446.338756286994, 9691.51542422876, 9711.854322185172, 9763.853542196874, 9847.655332063487, 809.4819315637878, 823.197382552019, 1035.9896401035894, 1056.6565866093551, 1058.169337070396, 5775.971120144372, 5935.198748168942, 5990.400959903975, 6052.092186172022, 6058.545609079337, 23927.401814954766, 24564.754352456603, 24921.127760528296, 25121.371965700637, 25245.495090098095, 24244.39389015289, 24334.26997190087, 24703.50592988166, 24743.38141546476, 24793.380165495648, 12169.452374643162, 12657.493700252071, 12671.049671274692, 12672.176308539916, 12680.112392132647, 9198.172164505228, 9209.953950230207, 9296.860547091665, 9311.0, 9318.627254909728, 20795.376138715863, 20943.685844712036, 21078.47303817417, 21180.894095529424, 21560.568788624452, 21728.456788580414, 21972.0, 21974.780252197274, 21981.560368792856, 22012.779872201714, 11874.703132421759, 12011.219270747373, 12016.158868879094, 12018.158728888893, 12051.638450846489, 15020.248987550798, 15441.0735355879, 15465.613359666098, 15530.844691553391, 15540.61148471252, 12013.339266340436, 12241.20432171906, 12367.505299787908, 12368.690782730504, 12568.245905245714, 12043.939780301042, 12122.818157727464, 12207.816882746589, 12290.324032178316, 12482.75034499323, 10781.353118812896, 10867.836982445428, 10884.564617415532, 11015.83476247841, 11024.0, 7963.601819909098, 8037.196280371916, 8067.112617612175, 8074.232947869943, 8084.231997960184, 8274.544900030556, 8293.377996650155, 8294.543800091053, 8316.251537361666, 8429.957850210954], "value": 7231.206628239104, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [506.74156180348035, 509.0302972836745, 520.997395013025, 525.9421463639002, 537.433008176374, 5980.641161530311, 6166.132748272053, 6190.907136392958, 6228.346023667517, 6241.65670888101, 5925.4963328117055, 6074.210352654149, 6096.055111457731, 6111.480524155452, 6133.846653833656, 4701.4593321767925, 4812.422509298889, 4848.806047758086, 4876.878078048042, 4881.194602890519, 4826.565609095181, 4830.063498248934, 4863.8540843774745, 4882.584980276663, 4900.485449027854, 4785.66500344976, 4810.711357318554, 4826.686265392738, 4830.758462076899, 4843.539863712942, 4839.54024367688, 4839.782209800567, 4850.854474365775, 4856.805727770885, 4903.338049363361, 4410.514843367227, 4411.338299255138, 4413.867583972486, 4424.336349547561, 4432.315720026401, 5931.881362372772, 6020.2775666919815, 6069.514438844882, 6090.604110732788, 6137.754489820401, 5588.245586845765, 5628.605997580171, 5641.8589535261535, 5688.402717714622, 5695.800646977336, 6493.70778314977, 6514.804555863284, 6533.281339052701, 6557.458997135669, 6560.40956313934, 4816.7832447539595, 4864.173090574611, 4878.414515282512, 4894.265860120975, 4909.435414927293, 6667.533272670915, 6783.898241526381, 6832.59004459729, 6851.794446166673, 6852.5203235773515, 4988.152014157602, 5020.824271150492, 5041.319421878073, 5047.4952504749235, 5054.09026375256, 5085.355615080138, 5096.566791822663, 5097.2716014860025, 5103.77033033507, 5111.309973153651, 5613.270274864241, 5629.493345598916, 5633.408492108351, 5678.659280443187, 5693.316801983706, 6307.463865571386, 6322.755421825278, 6412.134361861183, 6465.579737317059, 6530.314316996742, 1728.1963886792703, 1743.6599863026843, 1748.5453782016766, 1749.4052022312476, 1756.9648607027789, 1210.2254557083475, 1223.7613665335352, 1226.9877301227052, 1228.9139760216879, 1247.875212478745, 6080.439670247314, 6081.908771368433, 6112.2970858351855, 6117.0, 6160.507159427235, 6632.83417914556, 6633.611899716098, 6656.933430665728, 6668.233153187349, 6680.966595166993, 5955.851103722355, 6012.248468941382, 6087.299960504599, 6096.72564734588, 6117.510599152057, 3473.426884564069, 3476.0, 3498.3003399319728, 3513.2270900401872, 3528.970841765995, 6827.590344579341, 6842.828929276709, 6858.417034552119, 6862.289395688675, 6988.860222795515, 6554.623529058951, 6653.667316634149, 6724.66376681164, 6750.696218670171, 6806.659667016629, 6744.831379215559, 6759.493038022168, 6783.592984420852, 6789.898151527731, 6810.8297292567095, 6120.479759220516, 6460.25707043696, 6518.348165183634, 6579.243387010555, 6680.298568650416, 6417.743290268337, 6452.419282264619, 6468.514861385321, 6493.155889734304, 6534.8366290843105, 6803.897941530782, 6821.147356580427, 6857.314268573103, 6911.447084233349, 6919.238883722685, 4956.274049272957, 4968.975155124202, 5005.77474013663, 5020.774065167149, 5023.874403139951, 6501.609903405811, 6631.6684165792485, 6633.2040155181685, 6744.527883048336, 6769.729210831511, 6268.843278917881, 6376.266729326187, 6387.648679322496, 6412.39082287182, 6430.3891130342545, 6379.553431259767, 6402.0, 6463.806085817433, 6480.546361754632, 6509.0, 6254.874902502015, 6280.717367718372, 6380.776672816429, 6428.646424446701, 6439.291677915519, 6437.163168788027, 6486.740530378921, 6549.508786840911, 6550.639714815731, 6563.7702680405955, 6303.716332764945, 6350.571121497626, 6367.840803979938, 6378.266499352634, 6402.487800975818], "value": 5039.686691619378, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [948.729612060562, 987.629638885418, 990.4305024610849, 994.4282037828247, 1023.0025724918204, 19390.806091939077, 19392.22431102754, 19455.805441945577, 19489.707654385194, 20126.798732012674, 19315.323963661274, 19331.806681933176, 19364.322248721262, 20315.593688126264, 20422.489437764063, 1039.8908114647966, 1061.5488417422603, 1061.766411389493, 1062.1184416933927, 1065.1532032034552, 1018.0684673523717, 1076.9983914959084, 1080.027974822662, 1080.2654195147297, 1080.7406222506581, 1143.4054291768275, 1153.6827372472553, 1154.7344110854508, 1157.5138441854442, 1157.6411312493112, 1145.5417832866838, 1145.9828102578467, 1148.2306854407566, 1149.6263714292847, 1150.384544268812, 782.6869252299069, 800.8998875140608, 810.9229623185788, 811.1401913971209, 811.2455416462687, 18269.269229230817, 18290.725639115422, 18306.35927742536, 18380.356687515872, 18394.540136496562, 23444.413889652897, 23546.175883844153, 23841.403964900845, 24035.519289614283, 24259.514809703705, 17139.485815425433, 17158.0, 17215.0, 17456.30174793007, 17560.034198119098, 3937.0944682722848, 3955.0310174007263, 3956.129651476699, 3960.3861401482527, 3961.7028722845903, 153029.0, 153578.0, 154377.99999999886, 156004.2199789005, 156811.0, 17223.483295500904, 17649.7352539712, 17664.0, 17753.0, 17760.111994400486, 34398.140046498535, 34567.4814877777, 34631.82684086564, 34656.0, 34668.653313467046, 8900.599473023729, 9165.404248723811, 9166.404183728037, 9186.816263674822, 9247.213986811197, 4815.181419158752, 4901.50984901507, 4909.042736666316, 4916.655834091651, 4924.261360795837, 6101.969490152521, 6137.9079313810325, 6220.911340515392, 6277.121203031579, 6287.622742635456, 1181.3148373943093, 1215.6110044785585, 1218.0438355890583, 1221.2062159596155, 1232.7596118756755, 716.2872941423317, 723.1141851232303, 724.576122968059, 727.2509315405141, 737.3621817128145, 11718.88281117195, 11881.940590296994, 12187.695307617381, 12227.14409991292, 12608.81086783699, 676.1987045351275, 679.2358596578849, 688.3426327856885, 693.1578132568953, 696.5368030259821, 5705.600607957486, 5777.62445441045, 5791.971040144772, 5792.739326730307, 5899.4985426239255, 143245.0, 143985.0, 146521.26739366236, 147284.0, 149497.0, 108715.45642271738, 126024.0, 127291.0, 132047.3397633025, 132491.33754331167, 106804.0, 107412.46293768482, 108046.0, 108189.0, 109552.4522377399, 11897.583584574499, 11942.223755455867, 11995.760084798081, 12020.0, 12232.327222002876, 52021.0, 53141.0, 53362.73318633382, 53631.731841340545, 54673.0, 68326.0, 68475.65762171158, 68698.31301686919, 69190.3080969204, 70276.64861675659, 20332.898335508227, 20448.69326960067, 20491.38525844227, 20685.379438616867, 20852.791472085086, 22852.200172993864, 22985.310440686808, 23218.535629287657, 23302.0, 23514.412139696644, 17903.194356253738, 18025.639487210443, 18273.81726182721, 18312.450626481226, 18559.536011599277, 20500.0, 20583.485412864797, 20683.58632827305, 20784.376468705963, 21117.683234751185, 17396.565085872448, 17425.128743563026, 17436.73844892302, 19048.42854714361, 19067.427977160707, 15491.922540387226, 15581.065136091871, 15618.687626247638, 15720.606984825014, 15766.211689415253, 13681.452741890213, 13730.313484325943, 13774.793378099135, 13798.793018104938, 13806.516771912935], "value": 9982.865850650067, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1375.8999999999999, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 104.0, 105.0, 104.0, 106.0, 106.0, 106.0, 107.0, 106.0, 104.0], "value": 105.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 116.0, 115.0, 114.0, 112.0, 115.0, 112.0, 113.0, 113.0, 114.0], "value": 113.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 162.0, 163.0, 161.0, 162.0, 162.0, 162.0, 162.0, 162.0, 166.0], "value": 162.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 72.0, 72.0, 73.0, 74.0, 73.0, 73.0, 75.0, 76.0, 76.0], "value": 73.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 77.0, 78.0, 79.0, 78.0, 77.0, 79.0, 77.0, 78.0, 77.0], "value": 77.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 113.0, 110.0, 113.0, 113.0, 113.0, 113.0, 113.0, 113.0, 110.0], "value": 112.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 148.0, 150.0, 150.0, 150.0, 148.0, 149.0, 148.0, 151.0, 150.0], "value": 149.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 98.0, 98.0, 94.0, 99.0, 98.0, 98.0, 99.0, 98.0, 98.0], "value": 97.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [45.0, 46.0, 45.0, 45.0, 46.0, 45.0, 45.0, 47.0, 56.0, 45.0], "value": 46.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [39.0, 39.0, 39.0, 39.0, 39.0, 39.0, 39.0, 39.0, 39.0, 38.0], "value": 38.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 73.0, 77.0, 71.0, 75.0, 72.0, 72.0, 71.0, 71.0, 72.0], "value": 72.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 108.0, 104.0, 104.0, 106.0, 104.0, 117.0, 110.0, 112.0, 104.0], "value": 108.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 159.0, 155.0, 156.0, 158.0, 155.0, 153.0, 156.0, 157.0, 155.0], "value": 155.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 62.0, 59.0, 61.0, 60.0, 58.0, 60.0, 64.0, 61.0, 60.0], "value": 61.3, "unit": "score"}]}]} 12:11:56 INFO - Return code: 0 12:11:56 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:11:56 INFO - # TBPL SUCCESS # 12:11:56 INFO - Running post-action listener: _package_coverage_data 12:11:56 INFO - Running post-action listener: _resource_record_post_action 12:11:56 INFO - [mozharness: 2017-09-15 19:11:56.929165Z] Finished run-tests step (success) 12:11:56 INFO - Running post-run listener: _resource_record_post_run 12:11:57 INFO - Total resource usage - Wall time: 1187s; CPU: 13.0%; Read bytes: 4096; Write bytes: 340717568; Read time: 12; Write time: 262508 12:11:57 INFO - TinderboxPrint: CPU usage
13.0% 12:11:57 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 12:11:57 INFO - TinderboxPrint: I/O write bytes / time
340,717,568 / 262,508 12:11:57 INFO - TinderboxPrint: CPU idle
8,229.2 (86.9%) 12:11:57 INFO - TinderboxPrint: CPU user
1,187.8 (12.5%) 12:11:57 INFO - TinderboxPrint: Swap in / out
0 / 0 12:11:57 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 12558336; Read time: 0; Write time: 1532 12:11:57 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 12:11:57 INFO - run-tests - Wall time: 1169s; CPU: 13.0%; Read bytes: 4096; Write bytes: 327135232; Read time: 12; Write time: 260948 12:11:57 INFO - Running post-run listener: _upload_blobber_files 12:11:57 INFO - Blob upload gear active. 12:11:57 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:11:57 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 12:11:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 12:11:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 12:11:57 INFO - (blobuploader) - INFO - Open directory for files ... 12:11:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 12:11:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:11:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:11:58 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 12:11:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:11:58 INFO - (blobuploader) - INFO - Done attempting. 12:11:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 12:11:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:11:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:11:59 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 12:11:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:11:59 INFO - (blobuploader) - INFO - Done attempting. 12:11:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:11:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:11:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:12:06 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:12:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:12:06 INFO - (blobuploader) - INFO - Done attempting. 12:12:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 12:12:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:12:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:12:07 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 12:12:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:12:07 INFO - (blobuploader) - INFO - Done attempting. 12:12:07 INFO - (blobuploader) - INFO - Iteration through files over. 12:12:07 INFO - Return code: 0 12:12:07 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:12:07 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:12:07 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0b159f32951f30c9462abbb73ff953659f4c4022640e69c5bd2e7d008731e8ac3d4bec1c45001fe9858e69f64962fd81903678b2c6c21698f6c29b73cf2e4716", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9442b9e9e8c69f0bf1d4e036494d33d5a75484472f3a6542a2e6a125390fd5f4bc060f370b7f18be1394367777fa603cf5e402acb421fd2454f9ff5c4c3d0719", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/baf30f691f698fc96ceadcc1296d002f2fda50592df2c9ca032e4b5d85d2cbf13b9ea89ac2f9836f087a615273d4d04c36cceb9bdfc27137016c3af74a175db2", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f840712a6026c02906d05e728d44832c1bf3bc992acaf41ab25ee87c3f19f0ed27f683ab10d6d74821dc663ace87517cbe191d2121ebf0c57c77d22f38f8e937"} 12:12:07 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:12:07 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:12:07 INFO - Contents: 12:12:07 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0b159f32951f30c9462abbb73ff953659f4c4022640e69c5bd2e7d008731e8ac3d4bec1c45001fe9858e69f64962fd81903678b2c6c21698f6c29b73cf2e4716", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9442b9e9e8c69f0bf1d4e036494d33d5a75484472f3a6542a2e6a125390fd5f4bc060f370b7f18be1394367777fa603cf5e402acb421fd2454f9ff5c4c3d0719", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/baf30f691f698fc96ceadcc1296d002f2fda50592df2c9ca032e4b5d85d2cbf13b9ea89ac2f9836f087a615273d4d04c36cceb9bdfc27137016c3af74a175db2", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f840712a6026c02906d05e728d44832c1bf3bc992acaf41ab25ee87c3f19f0ed27f683ab10d6d74821dc663ace87517cbe191d2121ebf0c57c77d22f38f8e937"} 12:12:07 INFO - Running post-run listener: copy_logs_to_upload_dir 12:12:07 INFO - Copying logs to upload dir... 12:12:07 INFO - mkdir: /builds/slave/test/build/upload/logs 12:12:07 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1240.408440 ========= master_lag: 0.08 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 40 secs) (at 2017-09-15 12:12:07.982171) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-15 12:12:07.986190) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test/properties (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=62c568580acbfdaac3eec7be000001dc-1505500579.446389-1924944135 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/0b159f32951f30c9462abbb73ff953659f4c4022640e69c5bd2e7d008731e8ac3d4bec1c45001fe9858e69f64962fd81903678b2c6c21698f6c29b73cf2e4716", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9442b9e9e8c69f0bf1d4e036494d33d5a75484472f3a6542a2e6a125390fd5f4bc060f370b7f18be1394367777fa603cf5e402acb421fd2454f9ff5c4c3d0719", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/baf30f691f698fc96ceadcc1296d002f2fda50592df2c9ca032e4b5d85d2cbf13b9ea89ac2f9836f087a615273d4d04c36cceb9bdfc27137016c3af74a175db2", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f840712a6026c02906d05e728d44832c1bf3bc992acaf41ab25ee87c3f19f0ed27f683ab10d6d74821dc663ace87517cbe191d2121ebf0c57c77d22f38f8e937"} build_url:https://queue.taskcluster.net/v1/task/X6FlnGhmTjqdwUMlw8Fc9A/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011523 build_url: 'https://queue.taskcluster.net/v1/task/X6FlnGhmTjqdwUMlw8Fc9A/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0b159f32951f30c9462abbb73ff953659f4c4022640e69c5bd2e7d008731e8ac3d4bec1c45001fe9858e69f64962fd81903678b2c6c21698f6c29b73cf2e4716", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9442b9e9e8c69f0bf1d4e036494d33d5a75484472f3a6542a2e6a125390fd5f4bc060f370b7f18be1394367777fa603cf5e402acb421fd2454f9ff5c4c3d0719", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/baf30f691f698fc96ceadcc1296d002f2fda50592df2c9ca032e4b5d85d2cbf13b9ea89ac2f9836f087a615273d4d04c36cceb9bdfc27137016c3af74a175db2", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f840712a6026c02906d05e728d44832c1bf3bc992acaf41ab25ee87c3f19f0ed27f683ab10d6d74821dc663ace87517cbe191d2121ebf0c57c77d22f38f8e937"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-15 12:12:08.018838) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-15 12:12:08.019230) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=62c568580acbfdaac3eec7be000001dc-1505500579.446389-1924944135 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004489 ========= master_lag: 0.07 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-15 12:12:08.097970) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-15 12:12:08.098292) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-15 12:12:08.098614) ========= ========= Total master_lag: 0.28 =========